trautigan;620124 Wrote: > After a couple of weeks of perfect performance last night Spotify was > once again unusable due to rebuffering probs. > > However luckily the iPlayer plugin was perfect - I did notice wireless > strength on the SB varying from 49% to 60%. > > Anyway, here is the server log > > Code: -------------------- > > > [11-03-22 21:45:24.0869] Slim::Formats::Playlists::M3U::write (220) Error: Couldn't retrieve objectForUrl: [spotify:track:61DO1qTEsYYRyIc3DEhnpO] - skipping! > [11-03-22 21:45:24.0895] Slim::Formats::Playlists::M3U::write (220) Error: Couldn't retrieve objectForUrl: [spotify:track:5CxnVR7ApBII6MWCbBfs1W] - skipping! > [11-03-22 21:45:36.9580] Plugins::Spotify::ProtocolHandler::__ANON__ (201) error fetching track data: Error reading headers: Server closed connection without sending any data back at /<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~3.EXE>Net/HTTP/Methods.pm line 306. > ...propagated at /<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~3.EXE>Net/HTTP/NB.pm line 32. > [11-03-22 21:45:40.7732] Plugins::Spotify::ProtocolHandler::__ANON__ (201) error fetching track data: Error reading headers: Server closed connection without sending any data back at /<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~3.EXE>Net/HTTP/Methods.pm line 306. > ...propagated at /<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~3.EXE>Net/HTTP/NB.pm line 32. > [11-03-22 21:45:40.8299] Plugins::Spotify::Image::_gotError (133) error: Error reading headers: Server closed connection without sending any data back at /<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~3.EXE>Net/HTTP/Methods.pm line 306. > ...propagated at /<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~3.EXE>Net/HTTP/NB.pm line 32. > [11-03-22 21:45:43.9879] Plugins::Spotify::ProtocolHandler::__ANON__ (201) error fetching track data: Error reading headers: Server closed connection without sending any data back at /<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~3.EXE>Net/HTTP/Methods.pm line 306. > ...propagated at /<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~3.EXE>Net/HTTP/NB.pm line 32. > [11-03-22 21:45:44.0242] Plugins::Spotify::Spotifyd::relogin (192) requesting relogin > > -------------------- > > > > and the plugin log > > Code: -------------------- > > > [22:46:29.294] _streambuf_write:256 storing all 2886 bytes, newsize: 5774 > [22:46:29.294] _streambuf_write:257 state BUFFERING -> BUFFERING > [22:46:29.294] music_delivery:499 streamed frames: 591872 accumulated rate: 36864.000000 > [22:46:29.296] music_delivery:375 called with num_frames: 2048 > [22:46:29.296] _streambuf_canwrite:276 player: 0 can't write error: Unknown error 10035 > [22:46:29.296] music_delivery:452 returning without decoding > [22:46:29.296] music_delivery:375 called with num_frames: 2048 > [22:46:29.297] _streambuf_canwrite:276 player: 0 can't write error: Unknown error 10035 > [22:46:29.297] music_delivery:452 returning without decoding > [22:46:29.492] music_delivery:375 called with num_frames: 2048 > [22:46:29.492] _streambuf_canwrite:293 player: 0 wrote 5774 stored bytes > [22:46:29.492] _streambuf_canwrite:309 state BUFFERING -> STREAMING > [22:46:29.493] _streambuf_write:236 player: 0 wrote 2718 new bytes of 2718 min 2718 > [22:46:29.494] _streambuf_write:236 player: 0 wrote 2524 new bytes of 2524 min 2524 > [22:46:29.494] music_delivery:499 streamed frames: 593920 accumulated rate: 36992.000000 > [22:46:29.495] music_delivery:375 called with num_frames: 2048 > [22:46:29.496] _streambuf_write:236 player: 0 wrote 2336 new bytes of 2336 min 2336 > [22:46:29.497] _streambuf_write:236 player: 0 wrote 2499 new bytes of 2499 min 2499 > [22:46:29.497] music_delivery:499 streamed frames: 595968 accumulated rate: 37120.000000 > [22:46:29.499] music_delivery:375 called with num_frames: 2048 > [22:46:29.500] _streambuf_write:236 player: 0 wrote 2987 new bytes of 2987 min 2987 > [22:46:29.500] _streambuf_write:236 player: 0 wrote 3248 new bytes of 3248 min 3248 > [22:46:29.501] music_delivery:499 streamed frames: 598016 accumulated rate: 37248.000000 > [22:46:29.504] music_delivery:375 called with num_frames: 2048 > [22:46:29.505] _streambuf_write:236 player: 0 wrote 0 new bytes of 3098 min 0 > [22:46:29.505] _streambuf_write:247 storing remaining 3098 bytes > [22:46:29.505] _streambuf_write:248 state STREAMING -> BUFFERING > [22:46:29.505] music_delivery:499 streamed frames: 600064 accumulated rate: 37376.000000 > [22:46:29.507] music_delivery:375 called with num_frames: 2048 > [22:46:29.507] _streambuf_canwrite:276 player: 0 can't write error: Unknown error 10035 > [22:46:29.507] music_delivery:452 returning without decoding > [22:46:29.507] music_delivery:375 called with num_frames: 2048 > [22:46:29.507] _streambuf_canwrite:276 player: 0 can't write error: Unknown error 10035 > [22:46:29.507] music_delivery:452 returning without decoding > [22:46:29.700] music_delivery:375 called with num_frames: 2048 > [22:46:29.700] _streambuf_canwrite:293 player: 0 wrote 3098 stored bytes > [22:46:29.700] _streambuf_canwrite:309 state BUFFERING -> STREAMING > [22:46:29.701] _streambuf_write:236 player: 0 wrote 3002 new bytes of 3002 min 3002 > [22:46:29.701] _streambuf_write:236 player: 0 wrote 2929 new bytes of 2929 min 2929 > [22:46:29.701] music_delivery:499 streamed frames: 602112 accumulated rate: 35297.882813 > [22:46:29.703] music_delivery:375 called with num_frames: 2048 > [22:46:29.704] _streambuf_write:236 player: 0 wrote 0 new bytes of 2780 min 0 > [22:46:29.704] _streambuf_write:247 storing remaining 2780 bytes > [22:46:29.704] _streambuf_write:248 state STREAMING -> BUFFERING > [22:46:29.705] _streambuf_write:256 storing all 2575 bytes, newsize: 5355 > [22:46:29.705] _streambuf_write:257 state BUFFERING -> BUFFERING > [22:46:29.705] music_delivery:499 streamed frames: 604160 accumulated rate: 35418.351563 > [22:46:29.707] music_delivery:375 called with num_frames: 2048 > [22:46:29.707] _streambuf_canwrite:276 player: 0 can't write error: Unknown error 10035 > [22:46:29.707] music_delivery:452 returning without decoding > [22:46:29.707] music_delivery:375 called with num_frames: 2048 > [22:46:29.707] _streambuf_canwrite:276 player: 0 can't write error: Unknown error 10035 > [22:46:29.707] music_delivery:452 returning without decoding > [22:46:29.897] music_delivery:375 called with num_frames: 2048 > [22:46:29.897] _streambuf_canwrite:293 player: 0 wrote 5355 stored bytes > [22:46:29.897] _streambuf_canwrite:309 state BUFFERING -> STREAMING > [22:46:29.897] _streambuf_write:236 player: 0 wrote 2406 new bytes of 2406 min 2406 > [22:46:29.898] _streambuf_write:236 player: 0 wrote 2321 new bytes of 2321 min 2321 > [22:46:29.898] music_delivery:499 streamed frames: 606208 accumulated rate: 35538.824219 > [22:46:29.899] music_delivery:375 called with num_frames: 2048 > [22:46:29.900] _streambuf_write:236 player: 0 wrote 2259 new bytes of 2259 min 2259 > [22:46:29.900] music_delivery:499 streamed frames: 608256 accumulated rate: 35659.292969 > [22:46:29.902] music_delivery:375 called with num_frames: 2048 > [22:46:29.903] _streambuf_write:236 player: 0 wrote 2371 new bytes of 2371 min 2371 > [22:46:29.903] _streambuf_write:236 player: 0 wrote 2749 new bytes of 2749 min 2749 > [22:46:29.903] music_delivery:499 streamed frames: 610304 accumulated rate: 35779.765625 > [22:46:29.905] music_delivery:375 called with num_frames: 2048 > [22:46:29.905] _streambuf_write:236 player: 0 wrote 0 new bytes of 3482 min 0 > [22:46:29.905] _streambuf_write:247 storing remaining 3482 bytes > [22:46:29.906] _streambuf_write:248 state STREAMING -> BUFFERING > [22:46:29.906] _streambuf_write:256 storing all 3203 bytes, newsize: 6685 > [22:46:29.906] _streambuf_write:257 state BUFFERING -> BUFFERING > [22:46:29.906] music_delivery:499 streamed frames: 612352 accumulated rate: 35900.234375 > [22:46:29.907] music_delivery:375 called with num_frames: 2048 > [22:46:29.908] _streambuf_canwrite:276 player: 0 can't write error: Unknown error 10035 > [22:46:29.908] music_delivery:452 returning without decoding > [22:46:29.908] music_delivery:375 called with num_frames: 2048 > [22:46:29.908] _streambuf_canwrite:276 player: 0 can't write error: Unknown error 10035 > [22:46:29.908] music_delivery:452 returning without decoding > [22:46:30.095] music_delivery:375 called with num_frames: 2048 > [22:46:30.095] _streambuf_canwrite:276 player: 0 can't write error: Unknown error 10053 > [22:46:30.095] _streambuf_canwrite:285 player: 10053 last player in sync group - closing streaming session > [22:46:30.095] music_delivery:489 error writing > [22:46:30.095] music_delivery:491 state BUFFERING -> WRITEERROR > [22:46:30.095] music_delivery:499 streamed frames: 614400 accumulated rate: 36020.707031 > [22:46:30.095] main:1151 resetting streambuf > [22:46:30.095] _streambuf_reset:64 state WRITEERROR -> IDLE > [22:46:30.097] music_delivery:375 called with num_frames: 2048 > [22:46:30.097] music_delivery:380 streambuf is reset - ignoring frames > [22:48:08.009] main:429 new connection > -------------------- > > > > and yes I know there is a time difference between the two logs - > perhaps someone can explain that too? I'm quite happy to send the > complete logs to prove I haven't cut and pasted from the wrong > bits,there always seems to be this discrepancy...
Difficult to tell from this - it definately shows that you are not getting audio fast enough, but I can't easily tell if it was the spotify end or your local network. I'd expect spotify - does the server log show multiple "relogin" actions - if so did it get any better when these occured? -- Triode ------------------------------------------------------------------------ Triode's Profile: http://forums.slimdevices.com/member.php?userid=17 View this thread: http://forums.slimdevices.com/showthread.php?t=79706 _______________________________________________ plugins mailing list [email protected] http://lists.slimdevices.com/mailman/listinfo/plugins
