Triode;556524 Wrote:
> Some output from player.source debugging from the server would be
> useful.
Alright! Here comes the log, needed to truncate so hopefully I didn't
remove anything vital by mistake...
Code:
--------------------
[10-06-20 22:22:01.9804] main::init (323) Starting Squeezebox Server
(v7.5.1, r30836, Tue Jun 1 06:59:24 MDT 2010) perl 5.010001
[10-06-20 22:22:03.2655]
Slim::Player::TranscodingHelper::loadConversionTables (51) Loading conversion
config files...
[...]
[10-06-20 22:22:04.2326] Plugins::Spotify::Spotifyd::startD (27) starting
spotifyd:
/opt/squeezebox-server/cache/InstalledPlugins/Plugins/Spotify/Bin/i386-linux/spotifyd
[10-06-20 22:22:04.2382] Plugins::Spotify::Spotifyd::startD (37) spotifyd:
running
[...init play states etc...]
[10-06-20 22:22:19.1417] Plugins::Spotify::ProtocolHandler::__ANON__ (59)
caching meta for spotify:track:08e1cmJgaEFjxIfF2ofnwL
[...]
[10-06-20 22:22:19.8358] Plugins::Spotify::ProtocolHandler::__ANON__ (59)
caching meta for spotify:track:736OmWXWeuGNWhl3778CgS
[10-06-20 22:22:24.3686] Plugins::Spotify::ProtocolHandler::__ANON__ (74)
error fetching track data: Error reading headers: Server closed connection
without sending any data back at
/opt/squeezebox-server/CPAN/Net/HTTP/Methods.pm line 306.
...propagated at /opt/squeezebox-server/CPAN/Net/HTTP/NB.pm line 32.
[10-06-20 22:22:24.4074] Plugins::Spotify::ProtocolHandler::__ANON__ (59)
caching meta for spotify:track:4NQSiJ4dDh5tgkCc2EbE2Z
[...]
[10-06-20 22:22:28.5705] Plugins::Spotify::ProtocolHandler::__ANON__ (59)
caching meta for spotify:track:0vFHJLx1jQ4ZmqEP2F4Sdj
[10-06-20 22:22:42.9292] Plugins::Spotify::Playlists::parse (31) playlist
Trance spotify:user:olli:playlist:0NFXj87Js6e9w0rlZyQApN
[10-06-20 22:22:51.7874] Plugins::Spotify::SinglePlaylist::parse (29) track
On A Good Day - 16 Bit Lolitas Remix Album Edit by Oceanlab
[...]
[10-06-20 22:22:52.0336] Plugins::Spotify::SinglePlaylist::parse (29) track
Surrender by Above & Beyond
[10-06-20 22:23:00.8073] Slim::Player::StreamingController::stop (1961)
00:04:20:1e:22:12
[10-06-20 22:23:00.8083] Slim::Player::StreamingController::_eventAction
(271) 00:04:20:1e:22:12: Stop in STOPPED-IDLE ->
Slim::Player::StreamingController::_NoOp
[10-06-20 22:23:00.8093] Slim::Player::Song::DESTROY (159)
DESTROY(Slim::Player::Song=ARRAY(0x6003218)) live=1: index=0,
url=spotify:track:08e1cmJgaEFjxIfF2ofnwL
[10-06-20 22:23:00.8102] Slim::Player::StreamingController::resetSongqueue
(1644) Song queue is now
[10-06-20 22:23:00.8141] Slim::Player::StreamingController::play (1964)
00:04:20:1e:22:12
[10-06-20 22:23:00.8151] Slim::Player::StreamingController::_eventAction
(271) 00:04:20:1e:22:12: Play in STOPPED-IDLE ->
Slim::Player::StreamingController::_StopGetNext
[10-06-20 22:23:00.8160] Slim::Player::StreamingController::_eventAction
(284) params: index => 0 seekdata => undef
[10-06-20 22:23:00.8192] Slim::Player::StreamingController::_setPlayingState
(2232) new playing state STOPPED
[10-06-20 22:23:00.8200]
Slim::Player::StreamingController::_setStreamingState (2245) new streaming
state IDLE
[10-06-20 22:23:00.8215] Slim::Player::Song::new (107) index 0 ->
spotify:track:4YRLABGWfxvxUvmBcT6i8n
[10-06-20 22:23:00.8229] Slim::Player::Song::new (149) live=2
[10-06-20 22:23:00.8238]
Slim::Player::StreamingController::_setStreamingState (2245) new streaming
state TRACKWAIT
[10-06-20 22:23:00.8247] Slim::Player::Song::getNextSong (228)
spotify:track:4YRLABGWfxvxUvmBcT6i8n
[10-06-20 22:23:00.8258] Slim::Player::StreamingController::_nextTrackReady
(730) 00:04:20:1e:22:12: nextTrack will be index 0
[10-06-20 22:23:00.8268] Slim::Player::StreamingController::_eventAction
(271) 00:04:20:1e:22:12: NextTrackReady in STOPPED-TRACKWAIT ->
Slim::Player::StreamingController::_Stream
[10-06-20 22:23:00.8278] Slim::Player::StreamingController::_Stream (1162)
Song queue is now 0
[10-06-20 22:23:00.8287] Slim::Player::StreamingController::_Stream (1165)
00:04:20:1e:22:12: preparing to stream song index 0
[10-06-20 22:23:00.8297] Slim::Player::Song::open (362)
spotify:track:4YRLABGWfxvxUvmBcT6i8n
[10-06-20 22:23:00.8310] Slim::Player::Song::open (386) seek=false time=0
canSeek=1
[10-06-20 22:23:00.8324] Slim::Player::TranscodingHelper::enabledFormat (199)
Checking to see if flc-flc-*-* is enabled
[10-06-20 22:23:00.8399] Slim::Player::TranscodingHelper::checkBin (232)
Checking formats for: flc-flc-boom-00:04:20:1e:22:12
[10-06-20 22:23:00.8431] Slim::Player::TranscodingHelper::enabledFormat (199)
Checking to see if flc-flc-*-* is enabled
[10-06-20 22:23:00.8439] Slim::Player::TranscodingHelper::checkBin (240)
enabled
[10-06-20 22:23:00.8447] Slim::Player::TranscodingHelper::checkBin (242)
Found command: -
[10-06-20 22:23:00.8456] Slim::Player::TranscodingHelper::getConvertCommand2
(424) Matched: flc->flc via: -
[10-06-20 22:23:00.8465] Slim::Player::Song::open (407) Transcoder:
streamMode=I, streamformat=flc
[10-06-20 22:23:00.8477] Slim::Player::Song::open (445) URL supports direct
streaming
[spotify:track:4YRLABGWfxvxUvmBcT6i8n->spotify://192.168.1.2:9001/spotify:track:4YRLABGWfxvxUvmBcT6i8n]
[10-06-20 22:23:00.8490] Slim::Player::SongStreamController::new (32) live=1
[10-06-20 22:23:00.8510] Slim::Player::StreamingController::_Stream (1220)
00:04:20:1e:22:12: stream
[10-06-20 22:23:00.8533] Slim::Player::Boom::play (340) Setting LineIn to 0
for [spotify://192.168.1.2:9001/spotify:track:4YRLABGWfxvxUvmBcT6i8n]
[10-06-20 22:23:00.8543] Slim::Player::Boom::setLineIn (432) Switching to
line in 0
[10-06-20 22:23:00.8603] Slim::Player::StreamingController::_Stream (1255)
Song queue is now 0
[10-06-20 22:23:00.8615] Slim::Player::StreamingController::_setPlayingState
(2232) new playing state BUFFERING
[10-06-20 22:23:00.8624]
Slim::Player::StreamingController::_setStreamingState (2245) new streaming
state STREAMING
[10-06-20 22:23:00.8634] Slim::Player::StreamingController::_eventAction
(303) 00:04:20:1e:22:12: NextTrackReady - new state BUFFERING-STREAMING
[10-06-20 22:23:00.8643] Slim::Player::StreamingController::_eventAction
(303) 00:04:20:1e:22:12: Play - new state BUFFERING-STREAMING
[10-06-20 22:23:00.8797] Slim::Player::StreamingController::_eventAction
(271) 00:04:20:1e:22:12: StatusHeartbeat in BUFFERING-STREAMING ->
Slim::Player::StreamingController::_NoOp
[10-06-20 22:23:00.8946] Slim::Formats::Playlists::M3U::write (220) Error:
Couldn't retrieve objectForUrl: [spotify:track:669FfuFVZS1r8F55pYIxmq] -
skipping!
[...]
[10-06-20 22:23:01.0648] Slim::Formats::Playlists::M3U::write (220) Error:
Couldn't retrieve objectForUrl: [spotify:track:5K5F80f2e0DJDQ2IV8ecxo] -
skipping!
[10-06-20 22:23:01.0674] Slim::Player::Player::_buffering (1149) Buffering...
0 / 81920
[10-06-20 22:23:01.0753] Slim::Player::StreamingController::_eventAction
(271) 00:04:20:1e:22:12: StatusHeartbeat in BUFFERING-STREAMING ->
Slim::Player::StreamingController::_NoOp
[10-06-20 22:23:01.3039] Slim::Formats::Playlists::M3U::write (220) Error:
Couldn't retrieve objectForUrl: [spotify:track:4YRLABGWfxvxUvmBcT6i8n] -
skipping!
[...]
[10-06-20 22:23:01.4871] Slim::Formats::Playlists::M3U::write (220) Error:
Couldn't retrieve objectForUrl: [spotify:track:5K5F80f2e0DJDQ2IV8ecxo] -
skipping!
[10-06-20 22:23:01.4904] Slim::Player::Player::_buffering (1149) Buffering...
0 / 81920
[10-06-20 22:23:01.4960] Slim::Player::StreamingController::_eventAction
(271) 00:04:20:1e:22:12: StatusHeartbeat in BUFFERING-STREAMING ->
Slim::Player::StreamingController::_NoOp
[10-06-20 22:23:01.7933] Slim::Player::Player::_buffering (1149) Buffering...
50863 / 81920
[10-06-20 22:23:01.8010] Slim::Player::StreamingController::_eventAction
(271) 00:04:20:1e:22:12: StatusHeartbeat in BUFFERING-STREAMING ->
Slim::Player::StreamingController::_NoOp
[10-06-20 22:23:01.9225]
Slim::Player::StreamingController::playerTrackStarted (2044) 00:04:20:1e:22:12
[10-06-20 22:23:01.9235] Slim::Player::StreamingController::_eventAction
(271) 00:04:20:1e:22:12: Started in BUFFERING-STREAMING ->
Slim::Player::StreamingController::_Playing
[10-06-20 22:23:01.9243] Slim::Player::StreamingController::_setPlayingState
(2232) new playing state PLAYING
[10-06-20 22:23:01.9254] Slim::Player::StreamingController::_Playing (361)
Song 0 has now started playing
[10-06-20 22:23:01.9274] Slim::Player::StreamingController::_Playing (389)
Song queue is now 0
[10-06-20 22:23:01.9284] Slim::Player::StreamingController::_eventAction
(303) 00:04:20:1e:22:12: Started - new state PLAYING-STREAMING
[10-06-20 22:23:02.5152] Slim::Player::StreamingController::_eventAction
(271) 00:04:20:1e:22:12: StatusHeartbeat in PLAYING-STREAMING ->
Slim::Player::StreamingController::_CheckSync
[...]
--------------------
--
goopile
------------------------------------------------------------------------
goopile's Profile: http://forums.slimdevices.com/member.php?userid=38816
View this thread: http://forums.slimdevices.com/showthread.php?t=79706
_______________________________________________
plugins mailing list
[email protected]
http://lists.slimdevices.com/mailman/listinfo/plugins
