search

fuzeman / Spotify2.bundle

Spotify music streaming for Plex (all platforms)
77 stars 45 forks source link

[LOG INCLUDED] Cant login at all, (tells me wrong username and password) installed node... and spotify premium user... PLEASE HELP D= #34

Open Seffuel opened 10 years ago

Seffuel commented 10 years ago

2014-07-15 18:55:24,150 (21d8) : INFO (core:347) - Starting framework core - Version: 2.4.2, Build: a2c3abe (Sun Jul 6 13:49:21 UTC 2014) 2014-07-15 18:55:24,151 (21d8) : DEBUG (core:359) - Using the elevated policy 2014-07-15 18:55:24,151 (21d8) : DEBUG (core:448) - Starting runtime component. 2014-07-15 18:55:24,220 (21d8) : DEBUG (core:448) - Starting caching component. 2014-07-15 18:55:24,220 (21d8) : DEBUG (core:448) - Starting data component. 2014-07-15 18:55:24,221 (21d8) : DEBUG (core:448) - Starting networking component. 2014-07-15 18:55:24,223 (21d8) : DEBUG (networking:288) - Loaded HTTP cookies 2014-07-15 18:55:24,223 (21d8) : DEBUG (networking:458) - Setting the default network timeout to 20.0 2014-07-15 18:55:24,224 (21d8) : DEBUG (core:448) - Starting localization component. 2014-07-15 18:55:24,226 (21d8) : INFO (localization:408) - Setting the default locale to en-us 2014-07-15 18:55:24,227 (21d8) : DEBUG (localization:426) - Loaded en strings 2014-07-15 18:55:24,227 (21d8) : DEBUG (core:448) - Starting messaging component. 2014-07-15 18:55:24,233 (21d8) : DEBUG (core:448) - Starting debugging component. 2014-07-15 18:55:24,233 (21d8) : DEBUG (core:448) - Starting services component. 2014-07-15 18:55:24,233 (21d8) : DEBUG (core:448) - Starting myplex component. 2014-07-15 18:55:24,233 (21d8) : DEBUG (core:448) - Starting notifications component. 2014-07-15 18:55:24,236 (2940) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.plugins.spotify2' 2014-07-15 18:55:24,440 (21d8) : DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.plugins.spotify2 in namespace 'metadata' 2014-07-15 18:55:24,444 (21d8) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes' 2014-07-15 18:55:24,581 (21d8) : DEBUG (runtime:1111) - Created a thread named 'load_allservices' 2014-07-15 18:55:24,582 (199c) : DEBUG (services:265) - Plug-in is not daemonized - loading services from system 2014-07-15 18:55:24,582 (199c) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM/Y2VyZWFsMQoxCmxpc3QKMApyMAo/Y2VyZWFsMQoxCmRpY3QKMApyMAo' 2014-07-15 18:55:24,589 (21d8) : DEBUG (runtime:1111) - Created a thread named 'get_server_info' 2014-07-15 18:55:24,589 (21d8) : DEBUG (core:150) - Finished starting framework core 2014-07-15 18:55:24,589 (21d8) : DEBUG (core:558) - Loading plug-in code 2014-07-15 18:55:24,595 (2d9c) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400' 2014-07-15 18:55:24,609 (2d9c) : DEBUG (core:536) - Machine identifier is f569bb42b4c8d7887fbb6a420050b5ceeaf68d3c 2014-07-15 18:55:24,611 (2d9c) : DEBUG (core:537) - Server version is 0.9.9.12.504-3e7f93c 2014-07-15 18:55:24,661 (21d8) : DEBUG (core:564) - Finished loading plug-in code 2014-07-15 18:55:24,786 (21d8) : DEBUG (logkit:13) - ['com', 'cerealizer', 'com.plexapp.plugins', 'com.plexapp', 'com.plexapp.plugins.spotify2'] 2014-07-15 18:55:24,931 (199c) : DEBUG (services:362) - Loaded services 2014-07-15 18:55:24,937 (21d8) : DEBUG (preferences:256) - Loaded preferences from DefaultPrefs.json 2014-07-15 18:55:24,944 (21d8) : DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.plugins.spotify2 2014-07-15 18:55:24,947 (1f04) : DEBUG (services:438) - No shared code to load 2014-07-15 18:55:24,947 (21d8) : DEBUG (logkit:13) - {'pyemitter': 20, 'plugin': 20} 2014-07-15 18:55:24,948 (21d8) : DEBUG (logkit:13) - Piping events from "spotify2" to plex (level: DEBUG) 2014-07-15 18:55:24,948 (21d8) : DEBUG (logkit:13) - Piping events from "cherrypy" to plex (level: DEBUG) 2014-07-15 18:55:24,948 (21d8) : DEBUG (logkit:13) - Piping events from "plugin" to plex (level: INFO) 2014-07-15 18:55:24,950 (21d8) : DEBUG (logkit:13) - Piping events from "pyemitter" to plex (level: INFO) 2014-07-15 18:55:24,950 (21d8) : DEBUG (logkit:13) - Piping events from "requests" to plex (level: DEBUG) 2014-07-15 18:55:24,950 (21d8) : DEBUG (logkit:13) - Piping events from "spotify" to plex (level: DEBUG) 2014-07-15 18:55:25,187 (21d8) : DEBUG (logkit:13) - [spotify2.migrator] Running migration <Clean object at 0x02E50CD0> 2014-07-15 18:55:25,190 (21d8) : DEBUG (logkit:13) - spotify2.migrator delete_file: "C:\Users\Michael\AppData\Local\Plex Media Server\Plug-ins\Spotify2.bundle\Contents\Code\plugin.py" 2014-07-15 18:55:25,190 (21d8) : DEBUG (logkit:13) - spotify2.migrator delete_directory: "C:\Users\Michael\AppData\Local\Plex Media Server\Plug-ins\Spotify2.bundle\Contents\Libraries\Shared\spotify_web" 2014-07-15 18:55:31,992 (21d8) : DEBUG (logkit:13) - bundle_path: "C:\Users\Michael\AppData\Local\Plex Media Server\Plug-ins\Spotify2.bundle\Contents" 2014-07-15 18:55:31,993 (21d8) : INFO (logkit:16) - [plugin.profiles] Loaded profile with name "Generic" (supports_ranges: True) 2014-07-15 18:55:31,993 (21d8) : INFO (logkit:16) - [plugin.profiles] Loaded profile with name "INTEGRA DTR-40.2" (supports_ranges: True) 2014-07-15 18:55:31,994 (21d8) : INFO (logkit:16) - [cherrypy.error] [15/Jul/2014:18:55:31] ENGINE Bus STARTING 2014-07-15 18:55:31,996 (21d8) : INFO (logkit:16) - [cherrypy.error] [15/Jul/2014:18:55:31] ENGINE Started monitor thread '_TimeoutMonitor'. 2014-07-15 18:55:32,203 (21d8) : INFO (logkit:16) - [cherrypy.error] [15/Jul/2014:18:55:32] ENGINE Serving on http://0.0.0.0:12555 2014-07-15 18:55:32,203 (21d8) : INFO (logkit:16) - [cherrypy.error] [15/Jul/2014:18:55:32] ENGINE Bus STARTED 2014-07-15 18:55:32,239 (2198) : INFO (logkit:16) - [requests.packages.urllib3.connectionpool] Starting new HTTPS connection (1): play.spotify.com 2014-07-15 18:55:32,240 (21d8) : INFO (logkit:16) - Starting Spotify (version 0.5.5) 2014-07-15 18:55:32,242 (21d8) : DEBUG (runtime:640) - Adding a prefix handler for 'Spotify' ('/music/spotify') 2014-07-15 18:55:32,243 (21d8) : DEBUG (logkit:13) - Using locale: Dutch_Netherlands.1252 2014-07-15 18:55:32,243 (21d8) : INFO (core:609) - Started plug-in 2014-07-15 18:55:32,243 (21d8) : DEBUG (socketinterface:160) - Starting socket server 2014-07-15 18:55:32,270 (21d8) : DEBUG (runtime:1111) - Created a thread named 'start' 2014-07-15 18:55:32,272 (21d8) : INFO (socketinterface:184) - Socket server started on port 65049 2014-07-15 18:55:32,272 (21d8) : INFO (pipeinterface:25) - Entering run loop 2014-07-15 18:55:32,273 (21d8) : DEBUG (runtime:717) - Handling request GET /:/prefixes 2014-07-15 18:55:32,275 (21d8) : DEBUG (runtime:814) - Found route matching /:/prefixes 2014-07-15 18:55:32,276 (21d8) : DEBUG (runtime:918) - Response: [200] MediaContainer, 450 bytes 2014-07-15 18:55:33,921 (2198) : DEBUG (logkit:13) - [requests.packages.urllib3.connectionpool] "GET / HTTP/1.1" 200 10436 2014-07-15 18:55:33,923 (2198) : DEBUG (logkit:13) - [spotify.components.authentication] Landing - success, code: 200, content-type: text/html 2014-07-15 18:55:33,924 (2198) : DEBUG (logkit:13) - [spotify.components.authentication] Landing - CSRF token: d997e098a57e771f84b5e05e39d50427, tracking ID: 953bfa900e95960cf871f26f461c7e0c72992c13 2014-07-15 18:55:34,000 (21ac) : DEBUG (logkit:13) - [requests.packages.urllib3.connectionpool] "POST /xhr/json/auth.php HTTP/1.1" 200 None 2014-07-15 18:55:34,002 (21ac) : DEBUG (logkit:13) - [spotify.components.authentication] Authenticate - success, code: 200, content-type: application/json; charset=utf-8 2014-07-15 18:55:34,002 (21ac) : ERROR (logkit:22) - Invalid account credentials provided, check your username/password (invalid_credentials) 2014-07-15 18:55:45,987 (2710) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.plugins.spotify2/resources/icon-default.png?t=1405353988 2014-07-15 18:55:46,003 (2710) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.plugins.spotify2/resources/icon-default.png 2014-07-15 18:55:46,006 (2710) : DEBUG (runtime:918) - Response: [200] DataObject, 68199 bytes 2014-07-15 18:55:49,671 (2784) : DEBUG (runtime:717) - Handling request GET /music/spotify/:/prefs 2014-07-15 18:55:49,673 (2784) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:55:49,674 (2784) : DEBUG (runtime:814) - Found route matching /music/spotify/:/prefs 2014-07-15 18:55:49,683 (2784) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:55:49,683 (2784) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1188 bytes 2014-07-15 18:55:52,308 (2674) : DEBUG (runtime:717) - Handling request GET /music/spotify 2014-07-15 18:55:52,309 (2674) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:55:52,325 (2674) : DEBUG (runtime:814) - Found route matching /music/spotify 2014-07-15 18:55:52,325 (2674) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: {}, async: False 2014-07-15 18:55:52,328 (2674) : DEBUG (base:125) - Checking if com.plexapp.plugins.spotify2 is broken 2014-07-15 18:55:52,331 (2674) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMjgKY29tLnBsZXhhcHAucGx1Z2lucy5zcG90aWZ5MnMxMAppZGVudGlmaWVycjAK' 2014-07-15 18:55:52,344 (2674) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:55:52,345 (2674) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1474 bytes 2014-07-15 18:55:52,368 (260c) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.plugins.spotify2/resources/art-default.png?t=1405353988 2014-07-15 18:55:52,371 (260c) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.plugins.spotify2/resources/art-default.png 2014-07-15 18:55:52,372 (260c) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:55:52,374 (260c) : DEBUG (runtime:918) - Response: [200] DataObject, 611251 bytes 2014-07-15 18:55:52,395 (277c) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.plugins.spotify2/resources/icon-message-error.png?t=1405353988 2014-07-15 18:55:52,398 (277c) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.plugins.spotify2/resources/icon-message-error.png 2014-07-15 18:55:52,398 (277c) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:55:52,400 (277c) : DEBUG (runtime:918) - Response: [200] DataObject, 1251 bytes 2014-07-15 18:55:52,463 (2720) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.plugins.spotify2/resources/icon-explore.png?t=1405353988 2014-07-15 18:55:52,464 (2720) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.plugins.spotify2/resources/icon-explore.png 2014-07-15 18:55:52,467 (2720) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:55:52,467 (2720) : DEBUG (runtime:918) - Response: [200] DataObject, 3578 bytes 2014-07-15 18:55:52,505 (1ca4) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.plugins.spotify2/resources/icon-about.png?t=1405353988 2014-07-15 18:55:52,509 (20e8) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.plugins.spotify2/resources/icon-your_music.png?t=1405353988 2014-07-15 18:55:52,515 (1ca4) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.plugins.spotify2/resources/icon-about.png 2014-07-15 18:55:52,519 (20e8) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.plugins.spotify2/resources/icon-your_music.png 2014-07-15 18:55:52,522 (1ca4) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:55:52,523 (1ca4) : DEBUG (runtime:918) - Response: [200] DataObject, 2756 bytes 2014-07-15 18:55:52,526 (20e8) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:55:52,529 (20e8) : DEBUG (runtime:918) - Response: [200] DataObject, 5171 bytes 2014-07-15 18:56:51,980 (3304) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.plugins.spotify2/prefs 2014-07-15 18:56:51,982 (3304) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.plugins.spotify2/prefs 2014-07-15 18:56:51,989 (3304) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:56:51,989 (3304) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1188 bytes 2014-07-15 18:56:53,631 (3718) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.plugins.spotify2/prefs 2014-07-15 18:56:53,632 (3718) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:56:53,634 (3718) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.plugins.spotify2/prefs 2014-07-15 18:56:53,641 (3718) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:56:53,641 (3718) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1188 bytes 2014-07-15 18:57:13,203 (15f0) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.plugins.spotify2/prefs/set?username=seffiewuffy%40gmail.com 2014-07-15 18:57:13,203 (15f0) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:57:13,204 (15f0) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.plugins.spotify2/prefs/set 2014-07-15 18:57:13,210 (15f0) : DEBUG (preferences:198) - Saved the user preferences 2014-07-15 18:57:13,210 (15f0) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: None, async: False 2014-07-15 18:57:13,210 (15f0) : DEBUG (logkit:13) - ['cherrypy.error', 'plugin.profiles', 'spotify2.direct', 'ws4py', 'cherrypy.wsgiserver', 'plugin.stream', 'spotify.objects.base', 'spotify2.migrator', 'cerealizer', 'spotify.components.metadata', 'concurrent.futures', 'spotify', 'cherrypy', 'com.plexapp.plugins.spotify2', 'spotify.commands', 'cherrypy.wsgiserver.wsgiserver2', 'spotify.components', 'spotify.commands.work', 'spotify.components.connection', 'spotify.core.search_request', 'com.plexapp', 'spotify.client', 'com.plexapp.plugins', 'cherrypy.access', 'requests.packages.urllib3.poolmanager', 'pyemitter', 'spotify.core.search_response', 'spotify.objects.album', 'requests.packages.urllib3.connectionpool', 'spotify.mercury', 'spotify2', 'spotify.commands.flash', 'spotify.tunigo', 'spotify.components.authentication', 'spotify.objects.playlist', 'plugin.track', 'requests.packages.urllib3', 'concurrent', 'cherrypy.access.76837680', 'cherrypy.error.76837680', 'spotify.objects', 'spotify.objects.album_group', 'spotify.mercury.request', 'requests.packages.urllib3.response', 'spotify.objects.track', 'plugin', 'spotify.objects.parser', 'requests.packages', 'spotify.core.request', 'spotify.core', 'spotify.objects.restriction', 'spotify.hermes', 'spotify.hermes.cache', 'plugin.server', 'spotify.commands.manager', 'requests', 'spotify.tunigo.request', 'com', 'plugin.util', 'requests_futures'] 2014-07-15 18:57:13,213 (15f0) : DEBUG (logkit:13) - {'pyemitter': 20, 'plugin': 20} 2014-07-15 18:57:13,213 (15f0) : DEBUG (logkit:13) - Piping events from "spotify2" to plex (level: DEBUG) 2014-07-15 18:57:13,213 (15f0) : DEBUG (logkit:13) - Piping events from "cherrypy" to plex (level: DEBUG) 2014-07-15 18:57:13,213 (15f0) : DEBUG (logkit:13) - Piping events from "plugin" to plex (level: INFO) 2014-07-15 18:57:13,213 (15f0) : DEBUG (logkit:13) - Piping events from "pyemitter" to plex (level: INFO) 2014-07-15 18:57:13,213 (15f0) : DEBUG (logkit:13) - Piping events from "requests" to plex (level: DEBUG) 2014-07-15 18:57:13,214 (15f0) : DEBUG (logkit:13) - Piping events from "spotify" to plex (level: DEBUG) 2014-07-15 18:57:13,216 (15f0) : DEBUG (logkit:13) - bundle_path: "C:\Users\Michael\AppData\Local\Plex Media Server\Plug-ins\Spotify2.bundle\Contents" 2014-07-15 18:57:13,223 (15f0) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:57:13,226 (15f0) : DEBUG (runtime:918) - Response: [200] str, 0 bytes 2014-07-15 18:57:13,226 (8a4) : INFO (logkit:16) - [requests.packages.urllib3.connectionpool] Starting new HTTPS connection (1): play.spotify.com 2014-07-15 18:57:13,671 (8a4) : DEBUG (logkit:13) - [requests.packages.urllib3.connectionpool] "GET / HTTP/1.1" 200 10436 2014-07-15 18:57:13,674 (8a4) : DEBUG (logkit:13) - [spotify.components.authentication] Landing - success, code: 200, content-type: text/html 2014-07-15 18:57:13,676 (8a4) : DEBUG (logkit:13) - [spotify.components.authentication] Landing - CSRF token: d997e098a57e771f84b5e05e39d50427, tracking ID: 953bfa900e95960cf871f26f461c7e0c72992c13 2014-07-15 18:57:13,750 (1530) : DEBUG (logkit:13) - [requests.packages.urllib3.connectionpool] "POST /xhr/json/auth.php HTTP/1.1" 200 None 2014-07-15 18:57:13,750 (1530) : DEBUG (logkit:13) - [spotify.components.authentication] Authenticate - success, code: 200, content-type: application/json; charset=utf-8 2014-07-15 18:57:13,752 (1530) : ERROR (logkit:22) - Invalid account credentials provided, check your username/password (invalid_credentials) 2014-07-15 18:57:14,424 (17cc) : DEBUG (runtime:717) - Handling request GET /music/spotify 2014-07-15 18:57:14,427 (17cc) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:57:14,430 (17cc) : DEBUG (runtime:814) - Found route matching /music/spotify 2014-07-15 18:57:14,430 (17cc) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: {}, async: False 2014-07-15 18:57:14,431 (17cc) : DEBUG (base:125) - Checking if com.plexapp.plugins.spotify2 is broken 2014-07-15 18:57:14,434 (17cc) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMjgKY29tLnBsZXhhcHAucGx1Z2lucy5zcG90aWZ5MnMxMAppZGVudGlmaWVycjAK' 2014-07-15 18:57:14,456 (17cc) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:57:14,457 (17cc) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1474 bytes 2014-07-15 18:57:14,476 (f40) : DEBUG (runtime:717) - Handling request GET /music/spotify/messages 2014-07-15 18:57:14,476 (f40) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:57:14,477 (f40) : DEBUG (runtime:814) - Found route matching /music/spotify/messages 2014-07-15 18:57:14,479 (f40) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: {}, async: False 2014-07-15 18:57:14,480 (f40) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:57:14,480 (f40) : DEBUG (runtime:918) - Response: [200] MediaContainer, 578 bytes 2014-07-15 18:57:15,813 (356c) : DEBUG (runtime:717) - Handling request GET /music/spotify 2014-07-15 18:57:15,818 (356c) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:57:15,819 (356c) : DEBUG (runtime:814) - Found route matching /music/spotify 2014-07-15 18:57:15,821 (356c) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: {}, async: False 2014-07-15 18:57:15,822 (356c) : DEBUG (base:125) - Checking if com.plexapp.plugins.spotify2 is broken 2014-07-15 18:57:15,823 (356c) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMjgKY29tLnBsZXhhcHAucGx1Z2lucy5zcG90aWZ5MnMxMAppZGVudGlmaWVycjAK' 2014-07-15 18:57:15,846 (356c) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:57:15,846 (356c) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1474 bytes 2014-07-15 18:57:16,615 (22c4) : DEBUG (runtime:717) - Handling request GET /music/spotify 2014-07-15 18:57:16,618 (22c4) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:57:16,619 (22c4) : DEBUG (runtime:814) - Found route matching /music/spotify 2014-07-15 18:57:16,619 (22c4) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: {}, async: False 2014-07-15 18:57:16,621 (22c4) : DEBUG (base:125) - Checking if com.plexapp.plugins.spotify2 is broken 2014-07-15 18:57:16,624 (22c4) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMjgKY29tLnBsZXhhcHAucGx1Z2lucy5zcG90aWZ5MnMxMAppZGVudGlmaWVycjAK' 2014-07-15 18:57:16,641 (22c4) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:57:16,641 (22c4) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1474 bytes 2014-07-15 18:57:16,657 (2950) : DEBUG (runtime:717) - Handling request GET /music/spotify/your_music 2014-07-15 18:57:16,657 (2950) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:57:16,658 (2950) : DEBUG (runtime:814) - Found route matching /music/spotify/your_music 2014-07-15 18:57:16,660 (2950) : DEBUG (logkit:13) - plugin_callback - method: <decorator object at 0x04945950>, kwargs: {}, async: False 2014-07-15 18:57:16,660 (2950) : DEBUG (logkit:13) - authenticated 2014-07-15 18:57:26,661 (2950) : DEBUG (logkit:13) - authenticated - login error 2014-07-15 18:57:26,663 (2950) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:57:26,663 (2950) : DEBUG (runtime:918) - Response: [200] MessageContainer, 152 bytes 2014-07-15 18:59:29,464 (2e34) : DEBUG (runtime:717) - Handling request GET /music/spotify 2014-07-15 18:59:29,466 (2e34) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:59:29,467 (2e34) : DEBUG (runtime:814) - Found route matching /music/spotify 2014-07-15 18:59:29,467 (2e34) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: {}, async: False 2014-07-15 18:59:29,470 (2e34) : DEBUG (base:125) - Checking if com.plexapp.plugins.spotify2 is broken 2014-07-15 18:59:29,470 (2e34) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMjgKY29tLnBsZXhhcHAucGx1Z2lucy5zcG90aWZ5MnMxMAppZGVudGlmaWVycjAK' 2014-07-15 18:59:29,486 (2e34) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:59:29,486 (2e34) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1474 bytes 2014-07-15 18:59:31,049 (2fcc) : DEBUG (runtime:717) - Handling request GET /music/spotify 2014-07-15 18:59:31,052 (2fcc) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:59:31,053 (2fcc) : DEBUG (runtime:814) - Found route matching /music/spotify 2014-07-15 18:59:31,053 (2fcc) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: {}, async: False 2014-07-15 18:59:31,055 (2fcc) : DEBUG (base:125) - Checking if com.plexapp.plugins.spotify2 is broken 2014-07-15 18:59:31,056 (2fcc) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMjgKY29tLnBsZXhhcHAucGx1Z2lucy5zcG90aWZ5MnMxMAppZGVudGlmaWVycjAK' 2014-07-15 18:59:31,078 (2fcc) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:59:31,078 (2fcc) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1474 bytes 2014-07-15 18:59:31,131 (2cd4) : DEBUG (runtime:717) - Handling request GET /music/spotify/your_music 2014-07-15 18:59:31,131 (2cd4) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:59:31,132 (2cd4) : DEBUG (runtime:814) - Found route matching /music/spotify/your_music 2014-07-15 18:59:31,132 (2cd4) : DEBUG (logkit:13) - plugin_callback - method: <decorator object at 0x04945950>, kwargs: {}, async: False 2014-07-15 18:59:31,134 (2cd4) : DEBUG (logkit:13) - authenticated 2014-07-15 18:59:41,134 (2cd4) : DEBUG (logkit:13) - authenticated - login error 2014-07-15 18:59:41,137 (2cd4) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:59:41,137 (2cd4) : DEBUG (runtime:918) - Response: [200] MessageContainer, 152 bytes 2014-07-15 18:59:45,339 (25a4) : DEBUG (runtime:717) - Handling request GET /music/spotify 2014-07-15 18:59:45,342 (25a4) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:59:45,342 (25a4) : DEBUG (runtime:814) - Found route matching /music/spotify 2014-07-15 18:59:45,344 (25a4) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: {}, async: False 2014-07-15 18:59:45,345 (25a4) : DEBUG (base:125) - Checking if com.plexapp.plugins.spotify2 is broken 2014-07-15 18:59:45,348 (25a4) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMjgKY29tLnBsZXhhcHAucGx1Z2lucy5zcG90aWZ5MnMxMAppZGVudGlmaWVycjAK' 2014-07-15 18:59:45,365 (25a4) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:59:45,365 (25a4) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1474 bytes 2014-07-15 18:59:46,648 (2f08) : DEBUG (runtime:717) - Handling request GET /music/spotify 2014-07-15 18:59:46,650 (2f08) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:59:46,651 (2f08) : DEBUG (runtime:814) - Found route matching /music/spotify 2014-07-15 18:59:46,651 (2f08) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: {}, async: False 2014-07-15 18:59:46,655 (2f08) : DEBUG (base:125) - Checking if com.plexapp.plugins.spotify2 is broken 2014-07-15 18:59:46,657 (2f08) : DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X1N0b3JlU2VydmljZTpJc0NoYW5uZWxCcm9rZW4_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMjgKY29tLnBsZXhhcHAucGx1Z2lucy5zcG90aWZ5MnMxMAppZGVudGlmaWVycjAK' 2014-07-15 18:59:46,678 (2f08) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:59:46,678 (2f08) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1474 bytes 2014-07-15 18:59:46,696 (312c) : DEBUG (runtime:717) - Handling request GET /music/spotify/messages 2014-07-15 18:59:46,697 (312c) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:59:46,698 (312c) : DEBUG (runtime:814) - Found route matching /music/spotify/messages 2014-07-15 18:59:46,698 (312c) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: {}, async: False 2014-07-15 18:59:46,700 (312c) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:59:46,701 (312c) : DEBUG (runtime:918) - Response: [200] MediaContainer, 578 bytes 2014-07-15 18:59:52,319 (1654) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.plugins.spotify2/prefs 2014-07-15 18:59:52,319 (1654) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 18:59:52,322 (1654) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.plugins.spotify2/prefs 2014-07-15 18:59:52,328 (1654) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 18:59:52,328 (1654) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1198 bytes 2014-07-15 19:00:02,164 (2a88) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.plugins.spotify2/prefs/set?proxy_tracks=false 2014-07-15 19:00:02,165 (2a88) : DEBUG (runtime:49) - Received packed state data (80 bytes) 2014-07-15 19:00:02,167 (2a88) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.plugins.spotify2/prefs/set 2014-07-15 19:00:02,173 (2a88) : DEBUG (preferences:198) - Saved the user preferences 2014-07-15 19:00:02,173 (2a88) : DEBUG (logkit:13) - plugin_callback - method: , kwargs: None, async: False 2014-07-15 19:00:02,173 (2a88) : DEBUG (logkit:13) - ['cherrypy.error', 'plugin.profiles', 'spotify2.direct', 'ws4py', 'cherrypy.wsgiserver', 'plugin.stream', 'spotify.objects.base', 'spotify2.migrator', 'cerealizer', 'spotify.components.metadata', 'concurrent.futures', 'spotify', 'cherrypy', 'com.plexapp.plugins.spotify2', 'spotify.commands', 'cherrypy.wsgiserver.wsgiserver2', 'spotify.components', 'spotify.commands.work', 'spotify.components.connection', 'spotify.core.search_request', 'com.plexapp', 'spotify.client', 'com.plexapp.plugins', 'cherrypy.access', 'requests.packages.urllib3.poolmanager', 'pyemitter', 'spotify.core.search_response', 'spotify.objects.album', 'requests.packages.urllib3.connectionpool', 'spotify.mercury', 'spotify2', 'spotify.commands.flash', 'spotify.tunigo', 'spotify.components.authentication', 'spotify.objects.playlist', 'plugin.track', 'requests.packages.urllib3', 'concurrent', 'cherrypy.access.76837680', 'cherrypy.error.76837680', 'spotify.objects', 'spotify.objects.album_group', 'spotify.mercury.request', 'requests.packages.urllib3.response', 'spotify.objects.track', 'plugin', 'spotify.objects.parser', 'requests.packages', 'spotify.core.request', 'spotify.core', 'spotify.objects.restriction', 'spotify.hermes', 'spotify.hermes.cache', 'plugin.server', 'spotify.commands.manager', 'requests', 'spotify.tunigo.request', 'com', 'plugin.util', 'requests_futures'] 2014-07-15 19:00:02,174 (2a88) : DEBUG (logkit:13) - {'pyemitter': 20, 'plugin': 20} 2014-07-15 19:00:02,174 (2a88) : DEBUG (logkit:13) - Piping events from "spotify2" to plex (level: DEBUG) 2014-07-15 19:00:02,174 (2a88) : DEBUG (logkit:13) - Piping events from "cherrypy" to plex (level: DEBUG) 2014-07-15 19:00:02,176 (2a88) : DEBUG (logkit:13) - Piping events from "plugin" to plex (level: INFO) 2014-07-15 19:00:02,176 (2a88) : DEBUG (logkit:13) - Piping events from "pyemitter" to plex (level: INFO) 2014-07-15 19:00:02,176 (2a88) : DEBUG (logkit:13) - Piping events from "requests" to plex (level: DEBUG) 2014-07-15 19:00:02,176 (2a88) : DEBUG (logkit:13) - Piping events from "spotify" to plex (level: DEBUG) 2014-07-15 19:00:02,177 (2a88) : DEBUG (logkit:13) - bundle_path: "C:\Users\Michael\AppData\Local\Plex Media Server\Plug-ins\Spotify2.bundle\Contents" 2014-07-15 19:00:02,180 (2a88) : INFO (logkit:16) - [cherrypy.error] [15/Jul/2014:19:00:02] ENGINE Bus STOPPING 2014-07-15 19:00:02,398 (2a88) : INFO (logkit:16) - [cherrypy.error] [15/Jul/2014:19:00:02] ENGINE HTTP Server cherrypy._cpwsgi_server.CPWSGIServer(('0.0.0.0', 12555)) shut down 2014-07-15 19:00:02,400 (2a88) : INFO (logkit:16) - [cherrypy.error] [15/Jul/2014:19:00:02] ENGINE Stopped thread '_TimeoutMonitor'. 2014-07-15 19:00:02,403 (2a88) : INFO (logkit:16) - [cherrypy.error] [15/Jul/2014:19:00:02] ENGINE Bus STOPPED 2014-07-15 19:00:02,411 (2a88) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2014-07-15 19:00:02,414 (2a88) : DEBUG (runtime:918) - Response: [200] str, 0 bytes 2014-07-15 19:00:02,415 (1944) : INFO (logkit:16) - [requests.packages.urllib3.connectionpool] Starting new HTTPS connection (1): play.spotify.com 2014-07-15 19:00:03,170 (1944) : DEBUG (logkit:13) - [requests.packages.urllib3.connectionpool] "GET / HTTP/1.1" 200 10436 2014-07-15 19:00:03,171 (1944) : DEBUG (logkit:13) - [spotify.components.authentication] Landing - success, code: 200, content-type: text/html 2014-07-15 19:00:03,174 (1944) : DEBUG (logkit:13) - [spotify.components.authentication] Landing - CSRF token: d997e098a57e771f84b5e05e39d50427, tracking ID: 953bfa900e95960cf871f26f461c7e0c72992c13 2014-07-15 19:00:03,243 (2f60) : DEBUG (logkit:13) - [requests.packages.urllib3.connectionpool] "POST /xhr/json/auth.php HTTP/1.1" 200 None 2014-07-15 19:00:03,244 (2f60) : DEBUG (logkit:13) - [spotify.components.authentication] Authenticate - success, code: 200, content-type: application/json; charset=utf-8 2014-07-15 19:00:03,244 (2f60) : ERROR (logkit:22) - Invalid account credentials provided, check your username/password (invalid_credentials)