I managed to install the plugin, have saved my credentials, authenticate, browse my play lists.....everything seems to work perfect until I try to play a file. I have reviewed the plugin log file and have pasted it below on the chance that someone may be able to review it and provide me some direction as to the problem. I am running Plex server 0.9.11.7 and have the latest bundle of Spotify2.
Hi,
I managed to install the plugin, have saved my credentials, authenticate, browse my play lists.....everything seems to work perfect until I try to play a file. I have reviewed the plugin log file and have pasted it below on the chance that someone may be able to review it and provide me some direction as to the problem. I am running Plex server 0.9.11.7 and have the latest bundle of Spotify2.
2015-01-11 09:37:48,078 (12d0) : DEBUG (runtime:717) - Handling request GET /music/spotify/metadata/spotify%3Atrack%3A2z8vl2xGErFhTxEcIssKEM 2015-01-11 09:37:48,078 (5d8) : INFO (logkit:16) - [plugin.server] Received request for "spotify:track:2z8vl2xGErFhTxEcIssKEM" 2015-01-11 09:37:48,078 (5d8) : DEBUG (logkit:13) - [plugin.profiles] Missing "X-Plex-Device" header, returning generic profile - headers: {'Remote-Addr': '192.168.0.x', 'Accept-Language': 'en-US,en;q=0.8,fr;q=0.6', 'Accept-Encoding': 'gzip, deflate, sdch', 'Connection': 'keep-alive', 'Accept': '/', 'User-Agent': 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_10_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/39.0.2171.95 Safari/537.36', 'Dnt': '1', 'Host': '192.168.0.x:12555', 'Cookie': '_ga=GA1.1.2011410112.1417905488', 'Referer': 'http://192.168.0.x:32400/web/index.html', 'X-Requested-With': 'ShockwaveFlash/16.0.0.235'} 2015-01-11 09:37:48,078 (5d8) : INFO (logkit:16) - [plugin.server] Profile: "Generic", Range: None 2015-01-11 09:37:48,078 (12d0) : DEBUG (runtime:814) - Found route matching /music/spotify/metadata/spotify:track:2z8vl2xGErFhTxEcIssKEM 2015-01-11 09:37:48,078 (5d8) : INFO (logkit:16) - [plugin.track] Returning existing stream (r_range:)
2015-01-11 09:37:48,078 (12d0) : DEBUG (logkit:13) - plugin_callback - method: <decorator object at 0x0212B730>, kwargs: {'uri': u'spotify:track:2z8vl2xGErFhTxEcIssKEM'}, async: True
2015-01-11 09:37:48,078 (5d8) : DEBUG (logkit:13) - [pyemitter] [spotify.core.request.Request ]:on(events: ['success'], func: <bound method Track.on_track_uri of <plugin.track.Track object at 0x02CC0E50>>)
2015-01-11 09:37:48,078 (12d0) : DEBUG (logkit:13) - authenticated
2015-01-11 09:37:48,078 (5d8) : DEBUG (logkit:13) - [pyemitter] [spotify.core.request.Request ]:on(events: ['error'], func: <function at 0x04A31330>)
2015-01-11 09:37:48,078 (12d0) : DEBUG (logkit:13) - fetching metadata for uri: "spotify:track:2z8vl2xGErFhTxEcIssKEM"
2015-01-11 09:37:48,078 (5d8) : DEBUG (logkit:13) - [spotify.components.connection] send encoded: '{"args":["mp3160","5455aacbc25147d4bab383c2ae1669f8"],"name":"sp/track_uri","id":"3129"}'
2015-01-11 09:37:48,078 (12d0) : DEBUG (logkit:13) - [spotify.components.metadata] metadata(spotify:track:2z8vl2xGErFhTxEcIssKEM)
2015-01-11 09:37:48,078 (5d8) : DEBUG (logkit:13) - [pyemitter] [spotify.core.request.Request ]:on(events: ['error'], func: <bound method Track.on_track_error of <plugin.track.Track object at 0x02CC0E50>>)
2015-01-11 09:37:48,078 (12d0) : DEBUG (logkit:13) - [spotify.hermes.cache] retrieved "hm://metadata/track/5455aacbc25147d4bab383c2ae1669f8" from cache (timestamp: 1420929350.5, valid: False)
2015-01-11 09:37:48,078 (12d0) : DEBUG (logkit:13) - [spotify.mercury.request] prepared 1 requests
2015-01-11 09:37:48,078 (12d0) : DEBUG (logkit:13) - [pyemitter] [spotify.hermes.request.HermesRequest]:on(events: ['success'], func: <function on_track at 0x04A316B0>)
2015-01-11 09:37:48,078 (12d0) : DEBUG (logkit:13) - [pyemitter] [spotify.hermes.request.HermesRequest]:on(events: ['error'], func: <function at 0x04A313F0>)
2015-01-11 09:37:48,078 (12d0) : DEBUG (logkit:13) - [spotify.mercury.request] Waiting for 1 item(s) - [u'hm://metadata/track/5455aacbc25147d4bab383c2ae1669f8']
2015-01-11 09:37:48,092 (12d0) : DEBUG (logkit:13) - [spotify.components.connection] send encoded: '{"args":[0,"CjRobTovL21ldGFkYXRhL3RyYWNrLzU0NTVhYWNiYzI1MTQ3ZDRiYWIzODNjMmFlMTY2OWY4EgAaA0dFVCoA"],"name":"sp/hm_b64","id":"3130"}'
2015-01-11 09:37:48,233 (c5c) : DEBUG (logkit:13) - [pyemitter] [spotify.components.connection.Client]:emit(event: 'message', args: (), kwargs: {'message': '{"error":[12,0,""],"id":3129}'})
2015-01-11 09:37:48,233 (294) : DEBUG (logkit:13) - [pyemitter] [spotify.core.request.Request ]:emit(event: 'error', args: ([12, 0, u''],), kwargs: {})
2015-01-11 09:37:48,233 (294) : DEBUG (logkit:13) - [spotify.components.base] Error ([12, 0, u''],) returned for request: <spotify.core.request.Request object at 0x02D90AB0>
2015-01-11 09:37:48,233 (294) : WARNING (logkit:19) - [pyemitter] Exception raised in callback <function at 0x04A31330> for event "error" - Traceback (most recent call last):
File "C:\Users{User}\AppData\Local\Plex Media Server\Plug-ins\Spotify2.bundle\Contents\Libraries\Shared\pyemitter.py", line 176, in __call_sync
callback(_args, _kwargs)
File "C:\Users{User}\AppData\Local\Plex Media Server\Plug-ins\Spotify2.bundle\Contents\Libraries\Shared\spotify\components\base.py", line 49, in
request.on('error', lambda args: on_error(func, *args))
File "C:\Users{User}\AppData\Local\Plex Media Server\Plug-ins\Spotify2.bundle\Contents\Libraries\Shared\spotify\components\base.py", line 45, in on_error
func(None)
File "C:\Users{User}\AppData\Local\Plex Media Server\Plug-ins\Spotify2.bundle\Contents\Libraries\Shared\plugin\track.py", line 82, in on_track_uri
self.info = response.get('result')
AttributeError: 'NoneType' object has no attribute 'get'
2015-01-11 09:37:48,233 (294) : WARNING (logkit:19) - [plugin.track] track error: [12, 0, u''] 2015-01-11 09:37:48,233 (294) : DEBUG (logkit:13) - [pyemitter] [plugin.track.Track ]:emit(event: 'track_uri', args: (None,), kwargs: {}) 2015-01-11 09:37:48,250 (c5c) : DEBUG (logkit:13) - [pyemitter] [spotify.components.connection.Client]:emit(event: 'message', args: (), kwargs: {'message': '{"id":3130,"result":["CjRobTovL21ldGFkYXRhL3RyYWNrLzU0NTVhYWNiYzI1MTQ3ZDRiYWIzODNjMmFlMTY2OWY4Ehp2bmQuc3BvdGlmeS9tZXRhZGF0YS10cmFjayCQAzIYCgpNRC1WZXJzaW9uEgoxNDIwODI2NDIwMg8KBk1DLVRUTBIFNzIwMDAyGQoPTUMtQ2FjaGUtUG9saWN5EgZwdWJsaWMyDwoHTUMtRVRhZxIEtPuO9Q==","ChBUVarLwlFH1Lqzg8KuFmn4Eg9JcyBJdCBBIEJhbmdlcj8apQIKEGbxnzS/JE7fkskBG3qGJpwSD0lzIEl0IEEgQmFuZ2VyPxobChCRnMDe7HJH+5GkKTw2NQO5EgdPZGQgTW9iKhdDZW50cmFsIFN0YXRpb24gUmVjb3JkczIHCLwfEBIYJkoeChSj8dOI0yeIHaR+E613qNmVmJugFhAAGNgEINgESh4KFDNndYnQ01vN3xfkEcCrk4UnlpeTEAEYgAEggAFKHgoUPSGCC8nXTVH2VFV3D/luYEdCrREQAhiACiCACooBYAoeChSj8dOI0yeIHaR+E613qNmVmJugFhAAGNgEINgECh4KFDNndYnQ01vN3xfkEcCrk4UnlpeTEAEYgAEggAEKHgoUPSGCC8nXTVH2VFV3D/luYEdCrREQAhiACiCACiIbChCRnMDe7HJH+5GkKTw2NQO5EgdPZGQgTW9iKAIwAjiQphtAalIUCgRpc3JjEgxBVUNOMzEyMDczNjhaDAgACAEIAwgEEgJBVWIYChQmyYmHutjV74dgpsAD2h63WgT99RACYhgKFDlawJmJC+9i3IODiihHXy5WMSHqEAFiGAoURL+G+/g8TLJYssqBwCtXGPpD6ioQAGICEAViGAoUoL77IO1aQgKRMR2L9HegbLmnkyoQB3oYChTmvCI4eRvZUPLoFdmzFWQhfYrwfBAG"]}'}) 2015-01-11 09:37:48,250 (f4c) : DEBUG (logkit:13) - [spotify.mercury.request] Received 1 item(s) - [u'hm://metadata/track/5455aacbc25147d4bab383c2ae1669f8'] 2015-01-11 09:37:48,250 (f4c) : DEBUG (logkit:13) - [spotify.mercury.request] Building object(s) from 1 item(s) 2015-01-11 09:37:48,250 (f4c) : DEBUG (logkit:13) - [spotify.mercury.request] Returning 1 object(s) to response callback 2015-01-11 09:37:48,250 (f4c) : DEBUG (logkit:13) - [pyemitter] [spotify.hermes.request.HermesRequest]:emit(event: 'success', args: (<spotify.objects.track.Track object at 0x02E8F1D0>,), kwargs: {}) 2015-01-11 09:37:48,296 (12d0) : DEBUG (runtime:106) - Sending packed state data (109 bytes) 2015-01-11 09:37:48,296 (12d0) : DEBUG (runtime:918) - Response: [200] MediaContainer, 1575 bytes