[08-11-01 00:49:41.0057] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:41.0078] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:41.0297] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:41.4067] Slim::Player::StreamingController::playerReadyToStream (1601) 00:04:20:06:be:04
[08-11-01 00:49:41.4071] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onPlayout (794) onPlayout, logging playback
[08-11-01 00:49:41.4079] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_doLog (844) Logging track playback: 188 seconds, trackId: Tra.14176882, stationId: 
[08-11-01 00:49:41.4201] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: ReadyToStream in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_NextIfMore
[08-11-01 00:49:41.4206] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state IDLE
[08-11-01 00:49:41.4217] Slim::Player::StreamingController::nextsong (741) The next song is number 26, was 25
[08-11-01 00:49:41.4275] Slim::Player::Song::new (64) index 26 -> rhapd://Tra.14176883.mp3
[08-11-01 00:49:41.4283] Slim::Player::Song::new (101) live=2
[08-11-01 00:49:41.4286] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state TRACKWAIT
[08-11-01 00:49:41.4291] Slim::Player::Song::getNextSong (176) rhapd://Tra.14176883.mp3
[08-11-01 00:49:41.4297] Slim::Player::Song::getNextSong (198) scanning URL rhapd://Tra.14176883.mp3
[08-11-01 00:49:41.4303] Slim::Player::Song::getNextSong (176) rhapd://Tra.14176883.mp3
[08-11-01 00:49:41.4311] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getPlaybackSession (379) 00:04:20:06:be:04 Requesting new playback session...
[08-11-01 00:49:41.4371] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: ReadyToStream - new state PLAYING-TRACKWAIT
[08-11-01 00:49:41.8727] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (396) 00:04:20:06:be:04 New playback session obtained: 1225518582886:money-shopping@jbdotat.fastmail.fm:40134
[08-11-01 00:49:41.8732] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getTrackInfo (468) Getting next track playback info from SN
[08-11-01 00:49:42.0242] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (830) Logging returned: 1
[08-11-01 00:49:42.0293] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:42.2702] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (447) getTrackInfo ok: {
  account => {
        cobrandId => 40_134,
        logon => "money-shopping\@jbdotat.fastmail.fm",
        playbackSessionId => "1225518582886:money-shopping\@jbdotat.fastmail.fm:40134",
      },
  mediaUrl => "http://llnw-1166.listen.com/6/6/5/7/3/121737566.rad",
  trackMetadata => {
        albumId => "Alb.14174725",
        artistId => "Art.7243017",
        discIndex => 1,
        displayAlbumName => "5:55",
        displayArtistName => "Charlotte Gainsbourg",
        genreId => "g.169",
        name => "The Songs That We Sing",
        playbackSeconds => 176,
        price => undef,
        rightFlags => 574,
        trackId => "Tra.14176883",
        trackIndex => 5,
      },
  trackPlaybackFormat => { bitRate => 192, "format" => "RAD MP3", radVersion => 3 },
}
[08-11-01 00:49:42.2707] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_gotTrackInfo (499) 00:04:20:06:be:04 Sending playback information: Tra.14176883 / money-shopping@jbdotat.fastmail.fm / 40134 / 1225518582886:money-shopping@jbdotat.fastmail.fm:40134
[08-11-01 00:49:42.3902] Slim::Player::StreamingController::_nextTrackReady (633) 00:04:20:06:be:04: nextTrack will be index 26
[08-11-01 00:49:42.3908] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: NextTrackReady in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_StreamIfReady
[08-11-01 00:49:42.3912] Slim::Player::StreamingController::_Stream (894) 00:04:20:06:be:04: preparing to stream song index 26
[08-11-01 00:49:42.3915] Slim::Player::StreamingController::_Stream (899) Song queue is now 26,25
[08-11-01 00:49:42.3921] Slim::Player::Song::open (297) rhapd://Tra.14176883.mp3
[08-11-01 00:49:42.3935] Slim::Player::TranscodingHelper::getConvertCommand2 (310) rateLimit = 0, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-11-01 00:49:42.3940] Slim::Player::TranscodingHelper::enabledFormat (190) Checking to see if mp3-mp3-*-* is enabled
[08-11-01 00:49:42.3943] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-11-01 00:49:42.3946] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-11-01 00:49:42.3949] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-squeezebox2-*
[08-11-01 00:49:42.3952] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-*-*
[08-11-01 00:49:42.3955] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-11-01 00:49:42.3958] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-11-01 00:49:42.3961] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-squeezebox2-*
[08-11-01 00:49:42.3964] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-*-*
[08-11-01 00:49:42.3967] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-11-01 00:49:42.3970] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-11-01 00:49:42.3973] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-squeezebox2-*
[08-11-01 00:49:42.3976] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-*-*
[08-11-01 00:49:42.3979] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-11-01 00:49:42.3983] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-11-01 00:49:42.3986] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-squeezebox2-*
[08-11-01 00:49:42.3989] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-*-*
[08-11-01 00:49:42.3992] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-11-01 00:49:42.3995] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-11-01 00:49:42.3998] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-squeezebox2-*
[08-11-01 00:49:42.4001] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-*-*
[08-11-01 00:49:42.4004] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-11-01 00:49:42.4007] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-11-01 00:49:42.4010] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-squeezebox2-*
[08-11-01 00:49:42.4013] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-*-*
[08-11-01 00:49:42.4018] Slim::Player::TranscodingHelper::enabledFormat (190) Checking to see if mp3-mp3-*-* is enabled
[08-11-01 00:49:42.4021] Slim::Player::TranscodingHelper::checkBin (231)    enabled
[08-11-01 00:49:42.4024] Slim::Player::TranscodingHelper::checkBin (233)   Found command: -
[08-11-01 00:49:42.4036] Slim::Player::TranscodingHelper::getConvertCommand2 (436) Matched: mp3->mp3 via: -
[08-11-01 00:49:42.4040] Slim::Player::Song::open (323) Transcoder: streamMode=I, streamformat=mp3
[08-11-01 00:49:42.4046] Slim::Player::Song::open (333) URL supports direct streaming [rhapd://Tra.14176883.mp3->http://llnw-1166.listen.com/6/6/5/7/3/121737566.rad]
[08-11-01 00:49:42.4089] Slim::Player::SongStreamController::new (32) live=2
[08-11-01 00:49:42.4097] Slim::Player::SongStreamController::DESTROY (45) live=1
[08-11-01 00:49:42.4102] Slim::Player::StreamingController::_Stream (937) 00:04:20:06:be:04: stream
[08-11-01 00:49:42.4557] Slim::Player::Squeezebox::stream_s (721) This player supports direct streaming for http://llnw-1166.listen.com/6/6/5/7/3/121737566.rad as http://llnw-1166.listen.com/6/6/5/7/3/121737566.rad, let's do it.
[08-11-01 00:49:42.4724] Slim::Player::Squeezebox::stream_s (759) setting up direct stream (3496976223:80) autostart: 3.
[08-11-01 00:49:42.4727] Slim::Player::Squeezebox::stream_s (760) request string: GET /6/6/5/7/3/121737566.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-1166.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.3/23752
Icy-Metadata: 1

[08-11-01 00:49:42.4793] Slim::Player::StreamingController::_Stream (962) Song queue is now 26,25
[08-11-01 00:49:42.4876] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state STREAMING
[08-11-01 00:49:42.4882] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: NextTrackReady - new state PLAYING-STREAMING
[08-11-01 00:49:42.4909] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:42.4920] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:42.4928] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:42.5246] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:42.6070] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:42.6076] Slim::Player::Squeezebox2::directHeaders (383) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache
Accept-Ranges: bytes
Content-Length: 4245732
Content-Type: text/plain
Age: 98474
Date: Sat, 01 Nov 2008 05:49:43 GMT
Last-Modified: Thu, 31 Jul 2008 05:03:49 GMT
Connection: close

[08-11-01 00:49:42.6081] Slim::Player::Squeezebox2::directHeaders (447) Processing 8 headers
[08-11-01 00:49:42.6084] Slim::Player::Squeezebox2::directHeaders (453) Calling Slim::Plugin::RhapsodyDirect::ProtocolHandler ::parseDirectHeaders
[08-11-01 00:49:42.6088] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Server: Apache
[08-11-01 00:49:42.6091] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Accept-Ranges: bytes
[08-11-01 00:49:42.6094] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Length: 4245732
[08-11-01 00:49:42.6097] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Type: text/plain
[08-11-01 00:49:42.6100] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Age: 98474
[08-11-01 00:49:42.6103] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Date: Sat, 01 Nov 2008 05:49:43 GMT
[08-11-01 00:49:42.6106] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Last-Modified: Thu, 31 Jul 2008 05:03:49 GMT
[08-11-01 00:49:42.6109] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Connection: close
[08-11-01 00:49:42.6424] Slim::Player::Squeezebox2::directHeaders (511) Got a stream type: mp3 bitrate: 192000 title: 
[08-11-01 00:49:42.7815] Slim::Player::Squeezebox2::directHeaders (570) Beginning direct stream!
[08-11-01 00:49:43.0348] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:44.1290] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:45.2193] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:46.0460] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:47.0473] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:47.3290] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:47.3299] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:48.0460] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:49.0321] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:50.0456] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:51.0087] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:51.0101] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:51.0473] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:51.3757] Slim::Player::StreamingController::playerTrackStarted (1586) 00:04:20:06:be:04
[08-11-01 00:49:51.3762] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Started in PLAYING-STREAMING -> Slim::Player::StreamingController::_Playing
[08-11-01 00:49:51.3765] Slim::Player::StreamingController::_setPlayingState (1743) new playing state PLAYING
[08-11-01 00:49:51.3768] Slim::Player::StreamingController::_Playing (342) Song 25 is not longer in the queue
[08-11-01 00:49:51.3773] Slim::Player::Song::DESTROY (111) live=1: index=25, url=rhapd://Tra.14176882.mp3
[08-11-01 00:49:51.3784] Slim::Player::StreamingController::_Playing (348) Song 26 has now started playing
[08-11-01 00:49:51.3798] Slim::Player::StreamingController::_Playing (375) Song queue is now 26
[08-11-01 00:49:51.5845] Slim::Player::StreamingController::playerOutputUnderrun (1620) 00:04:20:06:be:04: decoder: 7 / output: 0
[08-11-01 00:49:51.5850] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: OutputUnderrun in PLAYING-STREAMING -> Slim::Player::StreamingController::_Rebuffer
[08-11-01 00:49:51.5926] Slim::Player::StreamingController::_setPlayingState (1743) new playing state BUFFERING
[08-11-01 00:49:51.6305] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: OutputUnderrun - new state BUFFERING-STREAMING
[08-11-01 00:49:51.6324] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:49:51.6333] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:49:51.7817] Slim::Player::Player::_buffering (1163) Buffering... 2911 / 81920
[08-11-01 00:49:51.9305] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:49:51.9318] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:49:52.2187] Slim::Player::Player::_buffering (1163) Buffering... 34855 / 81920
[08-11-01 00:49:52.2982] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:49:52.2990] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:49:52.5312] Slim::Player::Player::_buffering (1163) Buffering... 82771 / 81920
[08-11-01 00:49:52.5316] Slim::Player::StreamingController::playerBufferReady (1652) 00:04:20:06:be:04
[08-11-01 00:49:52.5320] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: BufferReady in BUFFERING-STREAMING -> Slim::Player::StreamingController::_WaitToSync
[08-11-01 00:49:52.5589] Slim::Player::StreamingController::_setPlayingState (1743) new playing state WAITING_TO_SYNC
[08-11-01 00:49:52.5593] Slim::Player::StreamingController::_setPlayingState (1743) new playing state PLAYING
[08-11-01 00:49:52.5849] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: BufferReady - new state PLAYING-STREAMING
[08-11-01 00:49:52.6063] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:52.6080] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:52.6154] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:52.6165] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:52.6470] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:52.7121] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:52.8090] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:52.8329] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:52.8959] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:53.4695] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:56.0052] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:56.0064] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:49:59.6570] Slim::Player::StreamingController::playerOutputUnderrun (1620) 00:04:20:06:be:04: decoder: 9435 / output: 0
[08-11-01 00:49:59.6575] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: OutputUnderrun in PLAYING-STREAMING -> Slim::Player::StreamingController::_Rebuffer
[08-11-01 00:49:59.6578] Slim::Player::StreamingController::_setPlayingState (1743) new playing state BUFFERING
[08-11-01 00:49:59.6642] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: OutputUnderrun - new state BUFFERING-STREAMING
[08-11-01 00:49:59.6726] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:49:59.6738] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:49:59.8041] Slim::Player::Player::_buffering (1163) Buffering... 10887 / 81920
[08-11-01 00:49:59.8211] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:49:59.8221] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:00.1163] Slim::Player::Player::_buffering (1163) Buffering... 34119 / 81920
[08-11-01 00:50:00.1531] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:00.1544] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:00.4224] Slim::Player::Player::_buffering (1163) Buffering... 82035 / 81920
[08-11-01 00:50:00.4229] Slim::Player::StreamingController::playerBufferReady (1652) 00:04:20:06:be:04
[08-11-01 00:50:00.4232] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: BufferReady in BUFFERING-STREAMING -> Slim::Player::StreamingController::_WaitToSync
[08-11-01 00:50:00.4235] Slim::Player::StreamingController::_setPlayingState (1743) new playing state WAITING_TO_SYNC
[08-11-01 00:50:00.4239] Slim::Player::StreamingController::_setPlayingState (1743) new playing state PLAYING
[08-11-01 00:50:00.4256] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: BufferReady - new state PLAYING-STREAMING
[08-11-01 00:50:00.4363] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:00.4376] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:00.4387] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:00.4395] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:00.6159] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:00.7190] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:00.7378] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:00.8215] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:00.8225] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:00.8761] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:01.1037] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:01.1047] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:06.0226] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:06.0239] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:11.0429] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:11.0438] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:16.0226] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:16.0234] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:21.0337] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:21.0346] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:27.6177] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:27.6185] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:32.3591] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:32.3599] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:32.4092] Slim::Player::StreamingController::playerEndOfStream (1660) 00:04:20:06:be:04
[08-11-01 00:50:32.4096] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: EndOfStream in PLAYING-STREAMING -> Slim::Player::StreamingController::_AutoStart
[08-11-01 00:50:32.4099] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state STREAMOUT
[08-11-01 00:50:32.4103] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: EndOfStream - new state PLAYING-STREAMOUT
[08-11-01 00:50:36.2016] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:36.2024] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:36.2031] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:36.2039] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:37.4355] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:38.0734] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:38.0742] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:38.1697] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:39.1774] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:40.1779] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:41.1754] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:42.1781] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:43.0187] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:43.0200] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:43.1782] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:44.0547] Slim::Player::StreamingController::playerReadyToStream (1601) 00:04:20:06:be:04
[08-11-01 00:50:44.0552] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onPlayout (794) onPlayout, logging playback
[08-11-01 00:50:44.0560] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_doLog (844) Logging track playback: 176 seconds, trackId: Tra.14176883, stationId: 
[08-11-01 00:50:44.0597] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: ReadyToStream in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_NextIfMore
[08-11-01 00:50:44.0604] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state IDLE
[08-11-01 00:50:44.0610] Slim::Player::StreamingController::nextsong (741) The next song is number 27, was 26
[08-11-01 00:50:44.0669] Slim::Player::Song::new (64) index 27 -> rhapd://Tra.14176884.mp3
[08-11-01 00:50:44.0676] Slim::Player::Song::new (101) live=2
[08-11-01 00:50:44.0681] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state TRACKWAIT
[08-11-01 00:50:44.0685] Slim::Player::Song::getNextSong (176) rhapd://Tra.14176884.mp3
[08-11-01 00:50:44.0689] Slim::Player::Song::getNextSong (198) scanning URL rhapd://Tra.14176884.mp3
[08-11-01 00:50:44.0696] Slim::Player::Song::getNextSong (176) rhapd://Tra.14176884.mp3
[08-11-01 00:50:44.0703] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getPlaybackSession (379) 00:04:20:06:be:04 Requesting new playback session...
[08-11-01 00:50:44.0744] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: ReadyToStream - new state PLAYING-TRACKWAIT
[08-11-01 00:50:44.0852] Slim::Player::StreamingController::playerStopped (1563) 00:04:20:06:be:04
[08-11-01 00:50:44.0856] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Stopped in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_PlayIfReady
[08-11-01 00:50:44.0859] Slim::Player::StreamingController::_setPlayingState (1743) new playing state STOPPED
[08-11-01 00:50:44.0863] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: Stopped - new state STOPPED-TRACKWAIT
[08-11-01 00:50:44.1772] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:44.4645] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (396) 00:04:20:06:be:04 New playback session obtained: 1225518645479:money-shopping@jbdotat.fastmail.fm:40134
[08-11-01 00:50:44.4650] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getTrackInfo (468) Getting next track playback info from SN
[08-11-01 00:50:44.4923] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (830) Logging returned: 1
[08-11-01 00:50:44.8622] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (447) getTrackInfo ok: {
  account => {
        cobrandId => 40_134,
        logon => "money-shopping\@jbdotat.fastmail.fm",
        playbackSessionId => "1225518645479:money-shopping\@jbdotat.fastmail.fm:40134",
      },
  mediaUrl => "http://llnw-1154.listen.com/5/4/7/8/3/121738745.rad",
  trackMetadata => {
        albumId => "Alb.14174725",
        artistId => "Art.7243017",
        discIndex => 1,
        displayAlbumName => "5:55",
        displayArtistName => "Charlotte Gainsbourg",
        genreId => "g.169",
        name => "Beauty Mark",
        playbackSeconds => 186,
        price => undef,
        rightFlags => 574,
        trackId => "Tra.14176884",
        trackIndex => 6,
      },
  trackPlaybackFormat => { bitRate => 192, "format" => "RAD MP3", radVersion => 3 },
}
[08-11-01 00:50:44.8627] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_gotTrackInfo (499) 00:04:20:06:be:04 Sending playback information: Tra.14176884 / money-shopping@jbdotat.fastmail.fm / 40134 / 1225518645479:money-shopping@jbdotat.fastmail.fm:40134
[08-11-01 00:50:44.8865] Slim::Player::StreamingController::_nextTrackReady (633) 00:04:20:06:be:04: nextTrack will be index 27
[08-11-01 00:50:44.8870] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: NextTrackReady in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_Stream
[08-11-01 00:50:44.8873] Slim::Player::StreamingController::_Stream (894) 00:04:20:06:be:04: preparing to stream song index 27
[08-11-01 00:50:44.8882] Slim::Player::StreamingController::_Stream (899) Song queue is now 27,26
[08-11-01 00:50:44.8888] Slim::Player::Song::open (297) rhapd://Tra.14176884.mp3
[08-11-01 00:50:44.8911] Slim::Player::TranscodingHelper::getConvertCommand2 (310) rateLimit = 0, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-11-01 00:50:44.8915] Slim::Player::TranscodingHelper::enabledFormat (190) Checking to see if mp3-mp3-*-* is enabled
[08-11-01 00:50:44.8919] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-11-01 00:50:44.8922] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-11-01 00:50:44.8924] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-squeezebox2-*
[08-11-01 00:50:44.8927] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-*-*
[08-11-01 00:50:44.8933] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-11-01 00:50:44.8936] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-11-01 00:50:44.8939] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-squeezebox2-*
[08-11-01 00:50:44.8942] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-*-*
[08-11-01 00:50:44.8945] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-11-01 00:50:44.8948] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-11-01 00:50:44.8953] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-squeezebox2-*
[08-11-01 00:50:44.8956] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-*-*
[08-11-01 00:50:44.8959] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-11-01 00:50:44.8963] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-11-01 00:50:44.8967] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-squeezebox2-*
[08-11-01 00:50:44.8971] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-*-*
[08-11-01 00:50:44.8973] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-11-01 00:50:44.8976] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-11-01 00:50:44.8979] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-squeezebox2-*
[08-11-01 00:50:44.8982] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-*-*
[08-11-01 00:50:44.8985] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-11-01 00:50:44.8988] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-11-01 00:50:44.8994] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-squeezebox2-*
[08-11-01 00:50:44.8997] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-*-*
[08-11-01 00:50:44.9001] Slim::Player::TranscodingHelper::enabledFormat (190) Checking to see if mp3-mp3-*-* is enabled
[08-11-01 00:50:44.9004] Slim::Player::TranscodingHelper::checkBin (231)    enabled
[08-11-01 00:50:44.9007] Slim::Player::TranscodingHelper::checkBin (233)   Found command: -
[08-11-01 00:50:44.9010] Slim::Player::TranscodingHelper::getConvertCommand2 (436) Matched: mp3->mp3 via: -
[08-11-01 00:50:44.9014] Slim::Player::Song::open (323) Transcoder: streamMode=I, streamformat=mp3
[08-11-01 00:50:44.9019] Slim::Player::Song::open (333) URL supports direct streaming [rhapd://Tra.14176884.mp3->http://llnw-1154.listen.com/5/4/7/8/3/121738745.rad]
[08-11-01 00:50:44.9058] Slim::Player::SongStreamController::new (32) live=2
[08-11-01 00:50:44.9065] Slim::Player::SongStreamController::DESTROY (45) live=1
[08-11-01 00:50:44.9075] Slim::Player::StreamingController::_Stream (937) 00:04:20:06:be:04: stream
[08-11-01 00:50:44.9172] Slim::Player::Squeezebox::stream_s (721) This player supports direct streaming for http://llnw-1154.listen.com/5/4/7/8/3/121738745.rad as http://llnw-1154.listen.com/5/4/7/8/3/121738745.rad, let's do it.
[08-11-01 00:50:44.9244] Slim::Player::Squeezebox::stream_s (759) setting up direct stream (1150175833:80) autostart: 3.
[08-11-01 00:50:44.9248] Slim::Player::Squeezebox::stream_s (760) request string: GET /5/4/7/8/3/121738745.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-1154.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.3/23752
Icy-Metadata: 1

[08-11-01 00:50:44.9328] Slim::Player::StreamingController::_Stream (962) Song queue is now 27,26
[08-11-01 00:50:44.9336] Slim::Player::StreamingController::_setPlayingState (1743) new playing state BUFFERING
[08-11-01 00:50:44.9339] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state STREAMING
[08-11-01 00:50:44.9347] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: NextTrackReady - new state BUFFERING-STREAMING
[08-11-01 00:50:44.9406] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:44.9416] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:44.9428] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:44.9437] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:44.9470] Slim::Player::StreamingController::playerOutputUnderrun (1620) 00:04:20:06:be:04: decoder: 0 / output: 0
[08-11-01 00:50:44.9474] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: OutputUnderrun in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:44.9508] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:45.0036] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:45.0046] Slim::Player::Squeezebox2::directHeaders (383) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache
Accept-Ranges: bytes
Content-Length: 4452884
Content-Type: text/plain
Age: 174555
Date: Sat, 01 Nov 2008 05:50:46 GMT
Last-Modified: Thu, 31 Jul 2008 05:00:23 GMT
Connection: close

[08-11-01 00:50:45.0052] Slim::Player::Squeezebox2::directHeaders (447) Processing 8 headers
[08-11-01 00:50:45.0055] Slim::Player::Squeezebox2::directHeaders (453) Calling Slim::Plugin::RhapsodyDirect::ProtocolHandler ::parseDirectHeaders
[08-11-01 00:50:45.0059] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Server: Apache
[08-11-01 00:50:45.0062] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Accept-Ranges: bytes
[08-11-01 00:50:45.0065] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Length: 4452884
[08-11-01 00:50:45.0068] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Type: text/plain
[08-11-01 00:50:45.0071] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Age: 174555
[08-11-01 00:50:45.0074] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Date: Sat, 01 Nov 2008 05:50:46 GMT
[08-11-01 00:50:45.0078] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Last-Modified: Thu, 31 Jul 2008 05:00:23 GMT
[08-11-01 00:50:45.0081] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Connection: close
[08-11-01 00:50:45.0407] Slim::Player::Squeezebox2::directHeaders (511) Got a stream type: mp3 bitrate: 192000 title: 
[08-11-01 00:50:45.0416] Slim::Player::Squeezebox2::directHeaders (570) Beginning direct stream!
[08-11-01 00:50:45.0500] Slim::Player::Player::_buffering (1163) Buffering... 0 / 20480
[08-11-01 00:50:45.0650] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:45.0662] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:45.3599] Slim::Player::Player::_buffering (1163) Buffering... 1230 / 20480
[08-11-01 00:50:45.4498] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:45.4509] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-01 00:50:45.6724] Slim::Player::Player::_buffering (1163) Buffering... 1230 / 20480
[08-11-01 00:50:45.7040] Slim::Player::StreamingController::playerTrackStarted (1586) 00:04:20:06:be:04
[08-11-01 00:50:45.7047] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Started in BUFFERING-STREAMING -> Slim::Player::StreamingController::_Playing
[08-11-01 00:50:45.7051] Slim::Player::StreamingController::_setPlayingState (1743) new playing state PLAYING
[08-11-01 00:50:45.7055] Slim::Player::StreamingController::_Playing (342) Song 26 is not longer in the queue
[08-11-01 00:50:45.7060] Slim::Player::Song::DESTROY (111) live=1: index=26, url=rhapd://Tra.14176883.mp3
[08-11-01 00:50:45.7071] Slim::Player::StreamingController::_Playing (348) Song 27 has now started playing
[08-11-01 00:50:45.7086] Slim::Player::StreamingController::_Playing (375) Song queue is now 27
[08-11-01 00:50:45.7090] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: Started - new state PLAYING-STREAMING
[08-11-01 00:50:45.7109] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-01 00:50:45.7116] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync