[08-11-08 15:48:03.1425] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:04.1406] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:05.0049] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:05.0062] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:05.1386] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:06.1371] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:07.1425] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:07.8370] Slim::Player::StreamingController::playerReadyToStream (1605) 00:04:20:06:be:04
[08-11-08 15:48:07.8374] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onPlayout (747) onPlayout, logging playback
[08-11-08 15:48:07.8383] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_doLog (797) Logging track playback: 185 seconds, trackId: Tra.15813668, stationId: 
[08-11-08 15:48:07.8419] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: ReadyToStream in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_NextIfMore
[08-11-08 15:48:07.8423] Slim::Player::StreamingController::_setStreamingState (1756) new streaming state IDLE
[08-11-08 15:48:07.8428] Slim::Player::StreamingController::nextsong (741) The next song is number 1, was 0
[08-11-08 15:48:07.8488] Slim::Player::Song::new (64) index 1 -> rhapd://Tra.15813669.mp3
[08-11-08 15:48:07.8496] Slim::Player::Song::new (101) live=4
[08-11-08 15:48:07.8500] Slim::Player::StreamingController::_setStreamingState (1756) new streaming state TRACKWAIT
[08-11-08 15:48:07.8504] Slim::Player::Song::getNextSong (178) rhapd://Tra.15813669.mp3
[08-11-08 15:48:07.8508] Slim::Player::Song::getNextSong (200) scanning URL rhapd://Tra.15813669.mp3
[08-11-08 15:48:07.8514] Slim::Player::Song::getNextSong (178) rhapd://Tra.15813669.mp3
[08-11-08 15:48:07.8524] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getTrackInfo (411) Getting next track playback info from SN
[08-11-08 15:48:07.8566] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: ReadyToStream - new state PLAYING-TRACKWAIT
[08-11-08 15:48:08.1503] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:08.1912] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (783) Logging returned: 1
[08-11-08 15:48:08.3483] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (388) getTrackInfo ok: {
  account => {
        cobrandId => 40_134,
        logon => "money-shopping\@jbdotat.fastmail.fm",
        playbackSessionId => "1226113871753:money-shopping\@jbdotat.fastmail.fm:40134",
      },
  mediaUrl => "http://llnw-589.listen.com/8/9/7/7/8/76287798.rad",
  trackMetadata => {
        albumId => "Alb.15810853",
        artistId => "Art.962",
        discIndex => 1,
        displayAlbumName => "Discover George Jones",
        displayArtistName => "George Jones",
        genreId => "g.407",
        name => "These Days (I Barely Get By)",
        playbackSeconds => 181,
        price => undef,
        rightFlags => 574,
        trackId => "Tra.15813669",
        trackIndex => 2,
      },
  trackPlaybackFormat => { bitRate => 192, "format" => "RAD MP3", radVersion => 3 },
}
[08-11-08 15:48:08.4095] Slim::Player::StreamingController::_nextTrackReady (633) 00:04:20:06:be:04: nextTrack will be index 1
[08-11-08 15:48:08.4099] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: NextTrackReady in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_StreamIfReady
[08-11-08 15:48:08.4106] Slim::Player::StreamingController::_Stream (894) 00:04:20:06:be:04: preparing to stream song index 1
[08-11-08 15:48:08.4109] Slim::Player::StreamingController::_Stream (899) Song queue is now 1,0
[08-11-08 15:48:08.4115] Slim::Player::Song::open (302) rhapd://Tra.15813669.mp3
[08-11-08 15:48:08.4130] Slim::Player::Song::open (323) seek=false time=0 canSeek=1
[08-11-08 15:48:08.4138] Slim::Player::TranscodingHelper::getConvertCommand2 (313) rateLimit = 0, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-11-08 15:48:08.4142] Slim::Player::TranscodingHelper::enabledFormat (193) Checking to see if mp3-mp3-*-* is enabled
[08-11-08 15:48:08.4145] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-11-08 15:48:08.4149] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-11-08 15:48:08.4152] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wma-squeezebox2-*
[08-11-08 15:48:08.4155] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wma-*-*
[08-11-08 15:48:08.4158] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-11-08 15:48:08.4161] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-11-08 15:48:08.4164] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-ogg-squeezebox2-*
[08-11-08 15:48:08.4167] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-ogg-*-*
[08-11-08 15:48:08.4170] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-11-08 15:48:08.4175] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-11-08 15:48:08.4178] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-flc-squeezebox2-*
[08-11-08 15:48:08.4183] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-flc-*-*
[08-11-08 15:48:08.4187] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-11-08 15:48:08.4191] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-11-08 15:48:08.4194] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-aif-squeezebox2-*
[08-11-08 15:48:08.4197] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-aif-*-*
[08-11-08 15:48:08.4201] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-11-08 15:48:08.4204] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-11-08 15:48:08.4207] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wav-squeezebox2-*
[08-11-08 15:48:08.4210] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wav-*-*
[08-11-08 15:48:08.4213] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-11-08 15:48:08.4216] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-11-08 15:48:08.4220] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-mp3-squeezebox2-*
[08-11-08 15:48:08.4223] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-mp3-*-*
[08-11-08 15:48:08.4226] Slim::Player::TranscodingHelper::enabledFormat (193) Checking to see if mp3-mp3-*-* is enabled
[08-11-08 15:48:08.4229] Slim::Player::TranscodingHelper::checkBin (234)    enabled
[08-11-08 15:48:08.4232] Slim::Player::TranscodingHelper::checkBin (236)   Found command: -
[08-11-08 15:48:08.4236] Slim::Player::TranscodingHelper::getConvertCommand2 (439) Matched: mp3->mp3 via: -
[08-11-08 15:48:08.4242] Slim::Player::Song::open (340) Transcoder: streamMode=I, streamformat=mp3
[08-11-08 15:48:08.4247] Slim::Player::Song::open (354) URL supports direct streaming [rhapd://Tra.15813669.mp3->http://llnw-589.listen.com/8/9/7/7/8/76287798.rad]
[08-11-08 15:48:08.4699] Slim::Player::SongStreamController::new (32) live=2
[08-11-08 15:48:08.4707] Slim::Player::SongStreamController::DESTROY (45) live=1
[08-11-08 15:48:08.4711] Slim::Player::StreamingController::_Stream (937) 00:04:20:06:be:04: stream
[08-11-08 15:48:08.4715] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onStream (485) 00:04:20:06:be:04 Sending playback information: Tra.15813669 / money-shopping@jbdotat.fastmail.fm / 40134 / 1226113871753:money-shopping@jbdotat.fastmail.fm:40134
[08-11-08 15:48:08.4784] Slim::Player::Squeezebox::stream_s (707) This player supports direct streaming for http://llnw-589.listen.com/8/9/7/7/8/76287798.rad as http://llnw-589.listen.com/8/9/7/7/8/76287798.rad, let's do it.
[08-11-08 15:48:08.5686] Slim::Player::Squeezebox::stream_s (745) setting up direct stream (3496975669:80) autostart: 3.
[08-11-08 15:48:08.5690] Slim::Player::Squeezebox::stream_s (746) request string: GET /8/9/7/7/8/76287798.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-589.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.3/23831
Icy-Metadata: 1

[08-11-08 15:48:08.5760] Slim::Player::StreamingController::_Stream (966) Song queue is now 1,0
[08-11-08 15:48:08.5768] Slim::Player::StreamingController::_setStreamingState (1756) new streaming state STREAMING
[08-11-08 15:48:08.5771] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: NextTrackReady - new state PLAYING-STREAMING
[08-11-08 15:48:08.5805] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:08.5844] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:08.5853] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:08.5926] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:08.7099] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:08.7105] Slim::Player::Squeezebox2::directHeaders (388) Processing headers for direct streaming:
HTTP/1.0 200 OK
Date: Sat, 08 Nov 2008 21:48:11 GMT
Server: Apache
Last-Modified: Thu, 23 Aug 2007 01:30:59 GMT
Accept-Ranges: bytes
Content-Length: 4333444
Content-Type: text/plain
Connection: close

[08-11-08 15:48:08.7114] Slim::Player::Squeezebox2::directHeaders (455) Processing 7 headers
[08-11-08 15:48:08.7118] Slim::Player::Squeezebox2::directHeaders (461) Calling Slim::Plugin::RhapsodyDirect::ProtocolHandler ::parseDirectHeaders
[08-11-08 15:48:08.7122] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Date: Sat, 08 Nov 2008 21:48:11 GMT
[08-11-08 15:48:08.7125] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Server: Apache
[08-11-08 15:48:08.7128] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Last-Modified: Thu, 23 Aug 2007 01:30:59 GMT
[08-11-08 15:48:08.7131] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Accept-Ranges: bytes
[08-11-08 15:48:08.7135] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Length: 4333444
[08-11-08 15:48:08.7138] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Type: text/plain
[08-11-08 15:48:08.7141] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Connection: close
[08-11-08 15:48:08.8038] Slim::Player::Client::streamingProgressBar (1127) url=http://llnw-589.listen.com/8/9/7/7/8/76287798.rad, duration=undef, bitrate=192000, contentLength=4333444
[08-11-08 15:48:08.8596] Slim::Player::Client::streamingProgressBar (1168) Duration of stream set to 180.560166666667 seconds based on length of 4333444 and bitrate of 192000
[08-11-08 15:48:08.8601] Slim::Player::Squeezebox2::directHeaders (519) Got a stream type: mp3 bitrate: 192000 title: 
[08-11-08 15:48:08.8608] Slim::Player::Squeezebox2::directHeaders (567) Beginning direct stream!
[08-11-08 15:48:09.1707] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:10.0053] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:10.0066] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:10.2085] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:11.2035] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:12.1995] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:13.2040] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:14.2033] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:15.0060] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:15.0073] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:15.2509] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:16.2083] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:17.2090] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:17.8161] Slim::Player::StreamingController::playerTrackStarted (1590) 00:04:20:06:be:04
[08-11-08 15:48:17.8165] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Started in PLAYING-STREAMING -> Slim::Player::StreamingController::_Playing
[08-11-08 15:48:17.8168] Slim::Player::StreamingController::_setPlayingState (1747) new playing state PLAYING
[08-11-08 15:48:17.8172] Slim::Player::StreamingController::_Playing (342) Song 0 is not longer in the queue
[08-11-08 15:48:17.8177] Slim::Player::Song::DESTROY (111) live=3: index=0, url=rhapd://Tra.15813668.mp3
[08-11-08 15:48:17.8181] Slim::Player::StreamingController::_Playing (348) Song 1 has now started playing
[08-11-08 15:48:17.8196] Slim::Player::StreamingController::_Playing (375) Song queue is now 1
[08-11-08 15:48:18.1995] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:19.2044] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:20.0046] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:20.0059] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:20.1963] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:20.8593] Slim::Player::StreamingController::playerOutputUnderrun (1624) 00:04:20:06:be:04: decoder: 3132708 / output: 0
[08-11-08 15:48:20.8598] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: OutputUnderrun in PLAYING-STREAMING -> Slim::Player::StreamingController::_Rebuffer
[08-11-08 15:48:20.8601] Slim::Player::StreamingController::_setPlayingState (1747) new playing state BUFFERING
[08-11-08 15:48:20.8837] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: OutputUnderrun - new state BUFFERING-STREAMING
[08-11-08 15:48:20.8883] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:48:20.8891] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:48:21.0158] Slim::Player::Player::_buffering (1163) Buffering... 3144324 / 120000
[08-11-08 15:48:21.0162] Slim::Player::StreamingController::playerBufferReady (1656) 00:04:20:06:be:04
[08-11-08 15:48:21.0248] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: BufferReady in BUFFERING-STREAMING -> Slim::Player::StreamingController::_WaitToSync
[08-11-08 15:48:21.0372] Slim::Player::StreamingController::_setPlayingState (1747) new playing state WAITING_TO_SYNC
[08-11-08 15:48:21.0751] Slim::Player::StreamingController::_setPlayingState (1747) new playing state PLAYING
[08-11-08 15:48:21.2163] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: BufferReady - new state PLAYING-STREAMING
[08-11-08 15:48:21.2496] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:21.2505] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:21.2513] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:21.2521] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:21.2838] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:21.3354] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:21.4115] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:21.4617] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:21.5366] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:25.0048] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:25.0060] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:25.7003] Slim::Player::StreamingController::playerEndOfStream (1664) 00:04:20:06:be:04
[08-11-08 15:48:25.7007] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: EndOfStream in PLAYING-STREAMING -> Slim::Player::StreamingController::_AutoStart
[08-11-08 15:48:25.7012] Slim::Player::StreamingController::_setStreamingState (1756) new streaming state STREAMOUT
[08-11-08 15:48:25.7016] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: EndOfStream - new state PLAYING-STREAMOUT
[08-11-08 15:48:30.0044] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:30.0057] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:35.0043] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:35.0056] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:40.1549] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:40.1562] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:45.0051] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:45.0065] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:50.0038] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:50.0050] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:50.3291] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:51.3268] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:52.3675] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:53.3590] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:54.3588] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:55.0037] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:55.0051] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:55.3576] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:56.3693] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:57.3693] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:58.3593] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:48:59.3695] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:00.0042] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:00.0056] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:00.3597] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:01.3604] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:02.3611] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:02.7359] Slim::Player::StreamingController::playerReadyToStream (1605) 00:04:20:06:be:04
[08-11-08 15:49:02.7365] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onPlayout (747) onPlayout, logging playback
[08-11-08 15:49:02.7373] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_doLog (797) Logging track playback: 180 seconds, trackId: Tra.15813669, stationId: 
[08-11-08 15:49:02.7442] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: ReadyToStream in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_NextIfMore
[08-11-08 15:49:02.7446] Slim::Player::StreamingController::_setStreamingState (1756) new streaming state IDLE
[08-11-08 15:49:02.7454] Slim::Player::StreamingController::nextsong (741) The next song is number 2, was 1
[08-11-08 15:49:02.7520] Slim::Player::Song::new (64) index 2 -> rhapd://Tra.15813670.mp3
[08-11-08 15:49:02.7527] Slim::Player::Song::new (101) live=4
[08-11-08 15:49:02.7535] Slim::Player::StreamingController::_setStreamingState (1756) new streaming state TRACKWAIT
[08-11-08 15:49:02.7539] Slim::Player::Song::getNextSong (178) rhapd://Tra.15813670.mp3
[08-11-08 15:49:02.7545] Slim::Player::Song::getNextSong (200) scanning URL rhapd://Tra.15813670.mp3
[08-11-08 15:49:02.7553] Slim::Player::Song::getNextSong (178) rhapd://Tra.15813670.mp3
[08-11-08 15:49:02.7562] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getTrackInfo (411) Getting next track playback info from SN
[08-11-08 15:49:02.7635] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: ReadyToStream - new state PLAYING-TRACKWAIT
[08-11-08 15:49:02.7680] Slim::Player::StreamingController::playerStopped (1567) 00:04:20:06:be:04
[08-11-08 15:49:02.7685] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Stopped in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_PlayIfReady
[08-11-08 15:49:02.7691] Slim::Player::StreamingController::_setPlayingState (1747) new playing state STOPPED
[08-11-08 15:49:02.7695] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: Stopped - new state STOPPED-TRACKWAIT
[08-11-08 15:49:03.0519] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (783) Logging returned: 1
[08-11-08 15:49:03.2385] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (388) getTrackInfo ok: {
  account => {
        cobrandId => 40_134,
        logon => "money-shopping\@jbdotat.fastmail.fm",
        playbackSessionId => "1226113871753:money-shopping\@jbdotat.fastmail.fm:40134",
      },
  mediaUrl => "http://llnw-582.listen.com/8/2/9/7/8/76287928.rad",
  trackMetadata => {
        albumId => "Alb.15810853",
        artistId => "Art.962",
        discIndex => 1,
        displayAlbumName => "Discover George Jones",
        displayArtistName => "George Jones",
        genreId => "g.407",
        name => "He Stopped Loving Her Today (Single Version)",
        playbackSeconds => 196,
        price => undef,
        rightFlags => 574,
        trackId => "Tra.15813670",
        trackIndex => 3,
      },
  trackPlaybackFormat => { bitRate => 192, "format" => "RAD MP3", radVersion => 3 },
}
[08-11-08 15:49:03.3019] Slim::Player::StreamingController::_nextTrackReady (633) 00:04:20:06:be:04: nextTrack will be index 2
[08-11-08 15:49:03.3023] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: NextTrackReady in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_Stream
[08-11-08 15:49:03.3026] Slim::Player::StreamingController::_Stream (894) 00:04:20:06:be:04: preparing to stream song index 2
[08-11-08 15:49:03.3032] Slim::Player::StreamingController::_Stream (899) Song queue is now 2,1
[08-11-08 15:49:03.3039] Slim::Player::Song::open (302) rhapd://Tra.15813670.mp3
[08-11-08 15:49:03.3054] Slim::Player::Song::open (323) seek=false time=0 canSeek=1
[08-11-08 15:49:03.3062] Slim::Player::TranscodingHelper::getConvertCommand2 (313) rateLimit = 0, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-11-08 15:49:03.3066] Slim::Player::TranscodingHelper::enabledFormat (193) Checking to see if mp3-mp3-*-* is enabled
[08-11-08 15:49:03.3070] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-11-08 15:49:03.3074] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-11-08 15:49:03.3077] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wma-squeezebox2-*
[08-11-08 15:49:03.3080] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wma-*-*
[08-11-08 15:49:03.3083] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-11-08 15:49:03.3086] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-11-08 15:49:03.3089] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-ogg-squeezebox2-*
[08-11-08 15:49:03.3093] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-ogg-*-*
[08-11-08 15:49:03.3096] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-11-08 15:49:03.3099] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-11-08 15:49:03.3102] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-flc-squeezebox2-*
[08-11-08 15:49:03.3105] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-flc-*-*
[08-11-08 15:49:03.3108] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-11-08 15:49:03.3111] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-11-08 15:49:03.3114] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-aif-squeezebox2-*
[08-11-08 15:49:03.3117] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-aif-*-*
[08-11-08 15:49:03.3120] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-11-08 15:49:03.3123] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-11-08 15:49:03.3126] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wav-squeezebox2-*
[08-11-08 15:49:03.3130] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-wav-*-*
[08-11-08 15:49:03.3133] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-11-08 15:49:03.3136] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-11-08 15:49:03.3139] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-mp3-squeezebox2-*
[08-11-08 15:49:03.3142] Slim::Player::TranscodingHelper::checkBin (226) Checking formats for: mp3-mp3-*-*
[08-11-08 15:49:03.3145] Slim::Player::TranscodingHelper::enabledFormat (193) Checking to see if mp3-mp3-*-* is enabled
[08-11-08 15:49:03.3148] Slim::Player::TranscodingHelper::checkBin (234)    enabled
[08-11-08 15:49:03.3151] Slim::Player::TranscodingHelper::checkBin (236)   Found command: -
[08-11-08 15:49:03.3158] Slim::Player::TranscodingHelper::getConvertCommand2 (439) Matched: mp3->mp3 via: -
[08-11-08 15:49:03.3162] Slim::Player::Song::open (340) Transcoder: streamMode=I, streamformat=mp3
[08-11-08 15:49:03.3166] Slim::Player::Song::open (354) URL supports direct streaming [rhapd://Tra.15813670.mp3->http://llnw-582.listen.com/8/2/9/7/8/76287928.rad]
[08-11-08 15:49:03.3210] Slim::Player::SongStreamController::new (32) live=2
[08-11-08 15:49:03.3217] Slim::Player::SongStreamController::DESTROY (45) live=1
[08-11-08 15:49:03.3227] Slim::Player::StreamingController::_Stream (937) 00:04:20:06:be:04: stream
[08-11-08 15:49:03.3231] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onStream (485) 00:04:20:06:be:04 Sending playback information: Tra.15813670 / money-shopping@jbdotat.fastmail.fm / 40134 / 1226113871753:money-shopping@jbdotat.fastmail.fm:40134
[08-11-08 15:49:03.3363] Slim::Player::Squeezebox::stream_s (707) This player supports direct streaming for http://llnw-582.listen.com/8/2/9/7/8/76287928.rad as http://llnw-582.listen.com/8/2/9/7/8/76287928.rad, let's do it.
[08-11-08 15:49:04.3595] Slim::Player::Squeezebox::stream_s (745) setting up direct stream (3496975674:80) autostart: 3.
[08-11-08 15:49:04.3600] Slim::Player::Squeezebox::stream_s (746) request string: GET /8/2/9/7/8/76287928.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-582.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.3/23831
Icy-Metadata: 1

[08-11-08 15:49:04.3672] Slim::Player::StreamingController::_Stream (966) Song queue is now 2,1
[08-11-08 15:49:04.3677] Slim::Player::StreamingController::_setPlayingState (1747) new playing state BUFFERING
[08-11-08 15:49:04.3681] Slim::Player::StreamingController::_setStreamingState (1756) new streaming state STREAMING
[08-11-08 15:49:04.3685] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: NextTrackReady - new state BUFFERING-STREAMING
[08-11-08 15:49:04.3719] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.3733] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.3740] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.3750] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.3790] Slim::Player::Player::_buffering (1163) Buffering... 0 / 20480
[08-11-08 15:49:04.3950] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.3959] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.3966] Slim::Player::StreamingController::playerOutputUnderrun (1624) 00:04:20:06:be:04: decoder: 0 / output: 0
[08-11-08 15:49:04.3969] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: OutputUnderrun in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.3980] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.3991] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.3999] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.4994] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.5004] Slim::Player::Squeezebox2::directHeaders (388) Processing headers for direct streaming:
HTTP/1.0 200 OK
Date: Sat, 08 Nov 2008 21:49:06 GMT
Server: Apache
Last-Modified: Thu, 23 Aug 2007 02:01:03 GMT
Accept-Ranges: bytes
Content-Length: 4681188
Content-Type: text/plain
Connection: close

[08-11-08 15:49:04.5009] Slim::Player::Squeezebox2::directHeaders (455) Processing 7 headers
[08-11-08 15:49:04.5015] Slim::Player::Squeezebox2::directHeaders (461) Calling Slim::Plugin::RhapsodyDirect::ProtocolHandler ::parseDirectHeaders
[08-11-08 15:49:04.5019] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Date: Sat, 08 Nov 2008 21:49:06 GMT
[08-11-08 15:49:04.5022] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Server: Apache
[08-11-08 15:49:04.5026] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Last-Modified: Thu, 23 Aug 2007 02:01:03 GMT
[08-11-08 15:49:04.5029] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Accept-Ranges: bytes
[08-11-08 15:49:04.5032] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Length: 4681188
[08-11-08 15:49:04.5036] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Type: text/plain
[08-11-08 15:49:04.5039] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Connection: close
[08-11-08 15:49:04.6051] Slim::Player::Client::streamingProgressBar (1127) url=http://llnw-582.listen.com/8/2/9/7/8/76287928.rad, duration=undef, bitrate=192000, contentLength=4681188
[08-11-08 15:49:04.6552] Slim::Player::Client::streamingProgressBar (1168) Duration of stream set to 195.0495 seconds based on length of 4681188 and bitrate of 192000
[08-11-08 15:49:04.6559] Slim::Player::Squeezebox2::directHeaders (519) Got a stream type: mp3 bitrate: 192000 title: 
[08-11-08 15:49:04.6567] Slim::Player::Squeezebox2::directHeaders (567) Beginning direct stream!
[08-11-08 15:49:04.6835] Slim::Player::Player::_buffering (1163) Buffering... 0 / 20480
[08-11-08 15:49:04.6885] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:04.6897] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:05.0007] Slim::Player::Player::_buffering (1163) Buffering... 5804 / 20480
[08-11-08 15:49:05.0089] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:05.0102] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:05.0110] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:05.0135] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-11-08 15:49:05.3028] Slim::Player::StreamingController::playerTrackStarted (1590) 00:04:20:06:be:04
[08-11-08 15:49:05.3032] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Started in BUFFERING-STREAMING -> Slim::Player::StreamingController::_Playing
[08-11-08 15:49:05.3035] Slim::Player::StreamingController::_setPlayingState (1747) new playing state PLAYING
[08-11-08 15:49:05.3039] Slim::Player::StreamingController::_Playing (342) Song 1 is not longer in the queue
[08-11-08 15:49:05.3044] Slim::Player::Song::DESTROY (111) live=3: index=1, url=rhapd://Tra.15813669.mp3
[08-11-08 15:49:05.3057] Slim::Player::StreamingController::_Playing (348) Song 2 has now started playing
[08-11-08 15:49:05.3071] Slim::Player::StreamingController::_Playing (375) Song queue is now 2
[08-11-08 15:49:05.3075] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: Started - new state PLAYING-STREAMING
[08-11-08 15:49:05.3704] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:06.3667] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:07.3603] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:08.3602] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:09.3562] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:10.0046] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:10.0055] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:10.3659] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:11.3678] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:12.4169] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:13.4109] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-11-08 15:49:13.8245] Slim::Player::StreamingController::pause (1538) 00:04:20:06:be:04
[08-11-08 15:49:13.8252] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Pause in PLAYING-STREAMING -> Slim::Player::StreamingController::_Pause
[08-11-08 15:49:13.8255] Slim::Player::StreamingController::_setPlayingState (1747) new playing state PAUSED
[08-11-08 15:49:13.8267] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: Pause - new state PAUSED-STREAMING
[08-11-08 15:49:13.8276] Slim::Player::Source::playmode (172) 00:04:20:06:be:04: Current playmode: pause
[08-11-08 15:49:13.8566] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PAUSED-STREAMING -> Slim::Player::StreamingController::_NoOp