[08-10-30 02:04:30.1079] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:04:30.1103] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:04:30.1149] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:04:30.1260] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:04:30.1590] Slim::Player::Player::_buffering (1163) Buffering... 0 / 20480
[08-10-30 02:04:30.1697] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:04:30.1710] Slim::Player::Squeezebox2::directHeaders (383) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache/2.0.58
Accept-Ranges: bytes
Content-Length: 4520692
Content-Type: text/plain
Age: 11691
Date: Thu, 30 Oct 2008 07:04:30 GMT
Last-Modified: Fri, 20 Apr 2007 21:34:59 GMT
Connection: close

[08-10-30 02:04:30.1722] Slim::Player::Squeezebox2::directHeaders (447) Processing 8 headers
[08-10-30 02:04:30.1729] Slim::Player::Squeezebox2::directHeaders (453) Calling Slim::Plugin::RhapsodyDirect::ProtocolHandler ::parseDirectHeaders
[08-10-30 02:04:30.1748] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Server: Apache/2.0.58
[08-10-30 02:04:30.1762] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Accept-Ranges: bytes
[08-10-30 02:04:30.1776] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Length: 4520692
[08-10-30 02:04:30.1786] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Type: text/plain
[08-10-30 02:04:30.1796] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Age: 11691
[08-10-30 02:04:30.1804] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Date: Thu, 30 Oct 2008 07:04:30 GMT
[08-10-30 02:04:30.1812] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Last-Modified: Fri, 20 Apr 2007 21:34:59 GMT
[08-10-30 02:04:30.1821] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Connection: close
[08-10-30 02:04:30.2555] Slim::Player::Squeezebox2::directHeaders (511) Got a stream type: mp3 bitrate: 192000 title: 
[08-10-30 02:04:30.2577] Slim::Player::Squeezebox2::directHeaders (570) Beginning direct stream!
[08-10-30 02:04:30.2782] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:04:30.2800] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:04:30.9234] Slim::Player::StreamingController::playerTrackStarted (1586) 00:04:20:06:be:04
[08-10-30 02:04:30.9243] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Started in BUFFERING-STREAMING -> Slim::Player::StreamingController::_Playing
[08-10-30 02:04:30.9250] Slim::Player::StreamingController::_setPlayingState (1743) new playing state PLAYING
[08-10-30 02:04:30.9257] Slim::Player::StreamingController::_Playing (342) Song 8 is not longer in the queue
[08-10-30 02:04:30.9263] Slim::Player::StreamingController::_Playing (348) Song 3 has now started playing
[08-10-30 02:04:30.9289] Slim::Player::StreamingController::_Playing (375) Song queue is now 3
[08-10-30 02:04:30.9296] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: Started - new state PLAYING-STREAMING
[08-10-30 02:04:30.9387] Slim::Player::Song::DESTROY (111) live=2: index=8, url=rhapd://Tra.13306051.mp3
[08-10-30 02:04:31.1767] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:32.1922] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:33.2031] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:34.0117] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:34.0135] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:34.1838] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:35.1849] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:36.2251] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:37.1777] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:38.1819] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:39.0714] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:39.0732] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:39.1885] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:40.2278] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:41.1805] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:42.1803] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:43.1840] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:44.0080] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:44.0129] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:44.1784] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:45.1744] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:46.1776] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:47.1862] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:48.1812] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:49.0645] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:49.0666] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:49.1752] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:50.2077] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:51.1890] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:52.1813] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:53.1840] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:54.2365] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:54.3784] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:54.3804] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:55.2532] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:56.1784] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:04:57.0465] Slim::Player::StreamingController::play (1519) 00:04:20:06:be:04
[08-10-30 02:04:57.0475] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Play in PLAYING-STREAMING -> Slim::Player::StreamingController::_StopGetNext
[08-10-30 02:04:57.0483] Slim::Player::StreamingController::_eventAction (278) params: index => 4 seekdata => undef
[08-10-30 02:04:57.0492] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onStop (782) onStop, logging playback
[08-10-30 02:04:57.0509] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_doLog (844) Logging track playback: 25 seconds, trackId: Tra.13306059, stationId: 
[08-10-30 02:04:57.0603] Slim::Player::StreamingController::_Stop (526) Song queue is now 3
[08-10-30 02:04:57.0611] Slim::Player::SongStreamController::DESTROY (45) live=0
[08-10-30 02:04:57.0620] Slim::Player::StreamingController::_setPlayingState (1743) new playing state STOPPED
[08-10-30 02:04:57.0627] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state IDLE
[08-10-30 02:04:57.0741] Slim::Player::Song::new (64) index 4 -> rhapd://Tra.13306060.mp3
[08-10-30 02:04:57.0759] Slim::Player::Song::new (101) live=3
[08-10-30 02:04:57.0767] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state TRACKWAIT
[08-10-30 02:04:57.0776] Slim::Player::Song::getNextSong (176) rhapd://Tra.13306060.mp3
[08-10-30 02:04:57.0787] Slim::Player::Song::getNextSong (198) scanning URL rhapd://Tra.13306060.mp3
[08-10-30 02:04:57.0797] Slim::Player::Song::getNextSong (176) rhapd://Tra.13306060.mp3
[08-10-30 02:04:57.0813] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getPlaybackSession (379) 00:04:20:06:be:04 Requesting new playback session...
[08-10-30 02:04:57.2305] Slim::Player::StreamingController::_playersMessage (678) Getting stream info...: The Songs That We Sing (album version) by Charlotte Gainsbourg from 5:55
[08-10-30 02:04:57.2347] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: Play - new state STOPPED-TRACKWAIT
[08-10-30 02:04:57.2412] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:04:57.2430] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:04:57.6244] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (396) 00:04:20:06:be:04 New playback session obtained: 1225350297948:money-shopping@jbdotat.fastmail.fm:40134
[08-10-30 02:04:57.6257] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getTrackInfo (468) Getting next track playback info from SN
[08-10-30 02:04:59.2403] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:04:59.2424] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:01.5013] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (447) getTrackInfo ok: {
  account => {
        cobrandId => 40_134,
        logon => "money-shopping\@jbdotat.fastmail.fm",
        playbackSessionId => "1225350297948:money-shopping\@jbdotat.fastmail.fm:40134",
      },
  mediaUrl => "http://llnw-615.listen.com/1/5/3/4/0/67004351.rad",
  trackMetadata => {
        albumId => "Alb.13305039",
        artistId => "Art.7243017",
        discIndex => 1,
        displayAlbumName => "5:55",
        displayArtistName => "Charlotte Gainsbourg",
        genreId => "g.169",
        name => "The Songs That We Sing (album version)",
        playbackSeconds => 178,
        price => undef,
        rightFlags => 62,
        trackId => "Tra.13306060",
        trackIndex => 5,
      },
  trackPlaybackFormat => { bitRate => 192, "format" => "RAD MP3", radVersion => 3 },
}
[08-10-30 02:05:01.5024] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_gotTrackInfo (499) 00:04:20:06:be:04 Sending playback information: Tra.13306060 / money-shopping@jbdotat.fastmail.fm / 40134 / 1225350297948:money-shopping@jbdotat.fastmail.fm:40134
[08-10-30 02:05:01.5223] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (830) Logging returned: 1
[08-10-30 02:05:01.6329] Slim::Player::StreamingController::_nextTrackReady (633) 00:04:20:06:be:04: nextTrack will be index 4
[08-10-30 02:05:01.6338] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: NextTrackReady in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_Stream
[08-10-30 02:05:01.6345] Slim::Player::StreamingController::_Stream (894) 00:04:20:06:be:04: preparing to stream song index 4
[08-10-30 02:05:01.6351] Slim::Player::StreamingController::_Stream (899) Song queue is now 4,3
[08-10-30 02:05:01.6375] Slim::Player::Song::open (297) rhapd://Tra.13306060.mp3
[08-10-30 02:05:01.6398] Slim::Player::TranscodingHelper::getConvertCommand2 (310) rateLimit = 0, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-10-30 02:05:01.6409] Slim::Player::TranscodingHelper::enabledFormat (190) Checking to see if mp3-mp3-*-* is enabled
[08-10-30 02:05:01.6416] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-10-30 02:05:01.6423] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-10-30 02:05:01.6429] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-squeezebox2-*
[08-10-30 02:05:01.6435] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-*-*
[08-10-30 02:05:01.6441] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-10-30 02:05:01.6448] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-10-30 02:05:01.6454] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-squeezebox2-*
[08-10-30 02:05:01.6460] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-*-*
[08-10-30 02:05:01.6466] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-10-30 02:05:01.6472] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-10-30 02:05:01.6481] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-squeezebox2-*
[08-10-30 02:05:01.6494] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-*-*
[08-10-30 02:05:01.6500] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-10-30 02:05:01.6508] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-10-30 02:05:01.6514] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-squeezebox2-*
[08-10-30 02:05:01.6520] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-*-*
[08-10-30 02:05:01.6527] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-10-30 02:05:01.6534] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-10-30 02:05:01.6541] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-squeezebox2-*
[08-10-30 02:05:01.6548] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-*-*
[08-10-30 02:05:01.6554] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-10-30 02:05:01.6561] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-10-30 02:05:01.6567] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-squeezebox2-*
[08-10-30 02:05:01.6579] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-*-*
[08-10-30 02:05:01.6586] Slim::Player::TranscodingHelper::enabledFormat (190) Checking to see if mp3-mp3-*-* is enabled
[08-10-30 02:05:01.6593] Slim::Player::TranscodingHelper::checkBin (231)    enabled
[08-10-30 02:05:01.6598] Slim::Player::TranscodingHelper::checkBin (233)   Found command: -
[08-10-30 02:05:01.6606] Slim::Player::TranscodingHelper::getConvertCommand2 (436) Matched: mp3->mp3 via: -
[08-10-30 02:05:01.6619] Slim::Player::Song::open (323) Transcoder: streamMode=I, streamformat=mp3
[08-10-30 02:05:01.6631] Slim::Player::Song::open (333) URL supports direct streaming [rhapd://Tra.13306060.mp3->http://llnw-615.listen.com/1/5/3/4/0/67004351.rad]
[08-10-30 02:05:01.6714] Slim::Player::SongStreamController::new (32) live=1
[08-10-30 02:05:01.6742] Slim::Player::StreamingController::_Stream (937) 00:04:20:06:be:04: stream
[08-10-30 02:05:01.6877] Slim::Player::Squeezebox::stream_s (721) This player supports direct streaming for http://llnw-615.listen.com/1/5/3/4/0/67004351.rad as http://llnw-615.listen.com/1/5/3/4/0/67004351.rad, let's do it.
[08-10-30 02:05:01.6998] Slim::Player::Squeezebox::stream_s (759) setting up direct stream (1150188329:80) autostart: 3.
[08-10-30 02:05:01.7006] Slim::Player::Squeezebox::stream_s (760) request string: GET /1/5/3/4/0/67004351.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-615.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.3/23730
Icy-Metadata: 1

[08-10-30 02:05:01.7144] Slim::Player::StreamingController::_Stream (962) Song queue is now 4,3
[08-10-30 02:05:01.7165] Slim::Player::StreamingController::_setPlayingState (1743) new playing state BUFFERING
[08-10-30 02:05:01.7176] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state STREAMING
[08-10-30 02:05:01.7192] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: NextTrackReady - new state BUFFERING-STREAMING
[08-10-30 02:05:01.7275] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:01.7294] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:01.7316] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:01.7331] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:01.7553] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:01.8127] Slim::Player::Player::_buffering (1163) Buffering... 0 / 20480
[08-10-30 02:05:01.8313] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:01.8332] 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: 41261
Date: Thu, 30 Oct 2008 07:05:02 GMT
Last-Modified: Fri, 20 Apr 2007 19:23:39 GMT
Connection: close

[08-10-30 02:05:01.8344] Slim::Player::Squeezebox2::directHeaders (447) Processing 8 headers
[08-10-30 02:05:01.8353] Slim::Player::Squeezebox2::directHeaders (453) Calling Slim::Plugin::RhapsodyDirect::ProtocolHandler ::parseDirectHeaders
[08-10-30 02:05:01.8373] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Server: Apache
[08-10-30 02:05:01.8381] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Accept-Ranges: bytes
[08-10-30 02:05:01.8394] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Length: 4245732
[08-10-30 02:05:01.8402] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Type: text/plain
[08-10-30 02:05:01.8411] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Age: 41261
[08-10-30 02:05:01.8420] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Date: Thu, 30 Oct 2008 07:05:02 GMT
[08-10-30 02:05:01.8427] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Last-Modified: Fri, 20 Apr 2007 19:23:39 GMT
[08-10-30 02:05:01.8433] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Connection: close
[08-10-30 02:05:01.9105] Slim::Player::Squeezebox2::directHeaders (511) Got a stream type: mp3 bitrate: 192000 title: 
[08-10-30 02:05:01.9121] Slim::Player::Squeezebox2::directHeaders (570) Beginning direct stream!
[08-10-30 02:05:01.9264] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:01.9291] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:02.1270] Slim::Player::Player::_buffering (1163) Buffering... 0 / 20480
[08-10-30 02:05:02.2100] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:02.2117] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:02.4320] Slim::Player::Player::_buffering (1163) Buffering... 1231 / 20480
[08-10-30 02:05:02.4484] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:02.4504] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:02.7366] Slim::Player::Player::_buffering (1163) Buffering... 1231 / 20480
[08-10-30 02:05:02.7590] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:02.7608] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:02.8709] Slim::Player::StreamingController::playerTrackStarted (1586) 00:04:20:06:be:04
[08-10-30 02:05:02.8719] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Started in BUFFERING-STREAMING -> Slim::Player::StreamingController::_Playing
[08-10-30 02:05:02.8726] Slim::Player::StreamingController::_setPlayingState (1743) new playing state PLAYING
[08-10-30 02:05:02.8733] Slim::Player::StreamingController::_Playing (342) Song 3 is not longer in the queue
[08-10-30 02:05:02.8744] Slim::Player::Song::DESTROY (111) live=2: index=3, url=rhapd://Tra.13306059.mp3
[08-10-30 02:05:02.8775] Slim::Player::StreamingController::_Playing (348) Song 4 has now started playing
[08-10-30 02:05:02.8810] Slim::Player::StreamingController::_Playing (375) Song queue is now 4
[08-10-30 02:05:02.8818] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: Started - new state PLAYING-STREAMING
[08-10-30 02:05:03.0207] Slim::Player::StreamingController::playerOutputUnderrun (1620) 00:04:20:06:be:04: decoder: 27598 / output: 0
[08-10-30 02:05:03.0216] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: OutputUnderrun in PLAYING-STREAMING -> Slim::Player::StreamingController::_Rebuffer
[08-10-30 02:05:03.0223] Slim::Player::StreamingController::_setPlayingState (1743) new playing state BUFFERING
[08-10-30 02:05:03.0946] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: OutputUnderrun - new state BUFFERING-STREAMING
[08-10-30 02:05:03.2171] Slim::Player::Player::_buffering (1163) Buffering... 27598 / 81920
[08-10-30 02:05:03.3665] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:03.3692] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:03.3727] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:03.3743] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:03.5228] Slim::Player::Player::_buffering (1163) Buffering... 29358 / 81920
[08-10-30 02:05:03.5392] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:03.5420] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:03.9074] Slim::Player::Player::_buffering (1163) Buffering... 38070 / 81920
[08-10-30 02:05:03.9210] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:03.9227] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:04.0121] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:04.0138] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:04.2458] Slim::Player::Player::_buffering (1163) Buffering... 94698 / 81920
[08-10-30 02:05:04.2467] Slim::Player::StreamingController::playerBufferReady (1652) 00:04:20:06:be:04
[08-10-30 02:05:04.2474] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: BufferReady in BUFFERING-STREAMING -> Slim::Player::StreamingController::_WaitToSync
[08-10-30 02:05:04.2796] Slim::Player::StreamingController::_setPlayingState (1743) new playing state WAITING_TO_SYNC
[08-10-30 02:05:04.2952] Slim::Player::StreamingController::_setPlayingState (1743) new playing state PLAYING
[08-10-30 02:05:04.3236] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: BufferReady - new state PLAYING-STREAMING
[08-10-30 02:05:04.3754] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:04.3781] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:04.3805] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:04.3824] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:04.4240] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:04.5001] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:04.5743] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:04.6718] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:04.6940] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:09.0170] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:09.0198] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:14.0603] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:14.0621] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:19.0083] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:19.0109] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:22.4448] Slim::Player::StreamingController::playerOutputUnderrun (1620) 00:04:20:06:be:04: decoder: 701226 / output: 0
[08-10-30 02:05:22.4458] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: OutputUnderrun in PLAYING-STREAMING -> Slim::Player::StreamingController::_Rebuffer
[08-10-30 02:05:22.4465] Slim::Player::StreamingController::_setPlayingState (1743) new playing state BUFFERING
[08-10-30 02:05:22.4583] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: OutputUnderrun - new state BUFFERING-STREAMING
[08-10-30 02:05:22.4607] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:22.4633] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:22.5846] Slim::Player::Player::_buffering (1163) Buffering... 702678 / 81920
[08-10-30 02:05:22.5855] Slim::Player::StreamingController::playerBufferReady (1652) 00:04:20:06:be:04
[08-10-30 02:05:22.5866] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: BufferReady in BUFFERING-STREAMING -> Slim::Player::StreamingController::_WaitToSync
[08-10-30 02:05:22.5873] Slim::Player::StreamingController::_setPlayingState (1743) new playing state WAITING_TO_SYNC
[08-10-30 02:05:22.5882] Slim::Player::StreamingController::_setPlayingState (1743) new playing state PLAYING
[08-10-30 02:05:22.5916] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: BufferReady - new state PLAYING-STREAMING
[08-10-30 02:05:22.6080] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:22.6099] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:22.6116] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:22.6258] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:22.6630] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:22.6982] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:22.7495] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:22.8018] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:22.8496] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:22.9050] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:22.9642] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.0771] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.0798] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.3252] Slim::Player::StreamingController::playerOutputUnderrun (1620) 00:04:20:06:be:04: decoder: 774102 / output: 0
[08-10-30 02:05:24.3263] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: OutputUnderrun in PLAYING-STREAMING -> Slim::Player::StreamingController::_Rebuffer
[08-10-30 02:05:24.3270] Slim::Player::StreamingController::_setPlayingState (1743) new playing state BUFFERING
[08-10-30 02:05:24.3410] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: OutputUnderrun - new state BUFFERING-STREAMING
[08-10-30 02:05:24.3497] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:24.3518] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:24.4709] Slim::Player::Player::_buffering (1163) Buffering... 774102 / 81920
[08-10-30 02:05:24.4718] Slim::Player::StreamingController::playerBufferReady (1652) 00:04:20:06:be:04
[08-10-30 02:05:24.4736] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: BufferReady in BUFFERING-STREAMING -> Slim::Player::StreamingController::_WaitToSync
[08-10-30 02:05:24.4743] Slim::Player::StreamingController::_setPlayingState (1743) new playing state WAITING_TO_SYNC
[08-10-30 02:05:24.4751] Slim::Player::StreamingController::_setPlayingState (1743) new playing state PLAYING
[08-10-30 02:05:24.5175] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: BufferReady - new state PLAYING-STREAMING
[08-10-30 02:05:24.5315] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.5334] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.5354] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.5402] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.5734] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.6264] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.6770] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.7284] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.7782] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.8302] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:24.8923] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:29.2855] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:29.2873] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:34.0130] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:34.0150] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:39.0064] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:39.0082] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:42.1784] Slim::Player::StreamingController::playerEndOfStream (1660) 00:04:20:06:be:04
[08-10-30 02:05:42.1794] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: EndOfStream in PLAYING-STREAMING -> Slim::Player::StreamingController::_AutoStart
[08-10-30 02:05:42.1801] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state STREAMOUT
[08-10-30 02:05:42.1809] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: EndOfStream - new state PLAYING-STREAMOUT
[08-10-30 02:05:44.0065] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:44.0092] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:49.0067] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:49.0092] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:54.0100] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:54.0119] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:55.4897] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:05:55.6431] Slim::Player::StreamingController::playerReadyToStream (1601) 00:04:20:06:be:04
[08-10-30 02:05:55.6442] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onPlayout (794) onPlayout, logging playback
[08-10-30 02:05:55.6466] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_doLog (844) Logging track playback: 176 seconds, trackId: Tra.13306060, stationId: 
[08-10-30 02:05:55.6547] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: ReadyToStream in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_NextIfMore
[08-10-30 02:05:55.6555] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state IDLE
[08-10-30 02:05:55.6566] Slim::Player::StreamingController::nextsong (741) The next song is number 5, was 4
[08-10-30 02:05:55.6683] Slim::Player::Song::new (64) index 5 -> rhapd://Tra.13306061.mp3
[08-10-30 02:05:55.6705] Slim::Player::Song::new (101) live=3
[08-10-30 02:05:55.6712] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state TRACKWAIT
[08-10-30 02:05:55.6723] Slim::Player::Song::getNextSong (176) rhapd://Tra.13306061.mp3
[08-10-30 02:05:55.6734] Slim::Player::Song::getNextSong (198) scanning URL rhapd://Tra.13306061.mp3
[08-10-30 02:05:55.6745] Slim::Player::Song::getNextSong (176) rhapd://Tra.13306061.mp3
[08-10-30 02:05:55.6760] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getPlaybackSession (379) 00:04:20:06:be:04 Requesting new playback session...
[08-10-30 02:05:55.6859] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: ReadyToStream - new state PLAYING-TRACKWAIT
[08-10-30 02:05:55.7127] Slim::Player::StreamingController::playerStopped (1563) 00:04:20:06:be:04
[08-10-30 02:05:55.7136] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Stopped in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_PlayIfReady
[08-10-30 02:05:55.7144] Slim::Player::StreamingController::_setPlayingState (1743) new playing state STOPPED
[08-10-30 02:05:55.7159] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: Stopped - new state STOPPED-TRACKWAIT
[08-10-30 02:05:56.0964] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (396) 00:04:20:06:be:04 New playback session obtained: 1225350356417:money-shopping@jbdotat.fastmail.fm:40134
[08-10-30 02:05:56.0980] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getTrackInfo (468) Getting next track playback info from SN
[08-10-30 02:05:56.3170] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (830) Logging returned: 1
[08-10-30 02:05:56.5014] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:57.4995] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:58.4969] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:59.0062] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:59.0079] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:05:59.4890] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:00.2898] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (447) getTrackInfo ok: {
  account => {
        cobrandId => 40_134,
        logon => "money-shopping\@jbdotat.fastmail.fm",
        playbackSessionId => "1225350356417:money-shopping\@jbdotat.fastmail.fm:40134",
      },
  mediaUrl => "http://llnw-656.listen.com/5/6/3/4/0/67004365.rad",
  trackMetadata => {
        albumId => "Alb.13305039",
        artistId => "Art.7243017",
        discIndex => 1,
        displayAlbumName => "5:55",
        displayArtistName => "Charlotte Gainsbourg",
        genreId => "g.169",
        name => "Beauty Mark",
        playbackSeconds => 186,
        price => undef,
        rightFlags => 62,
        trackId => "Tra.13306061",
        trackIndex => 6,
      },
  trackPlaybackFormat => { bitRate => 192, "format" => "RAD MP3", radVersion => 3 },
}
[08-10-30 02:06:00.2912] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_gotTrackInfo (499) 00:04:20:06:be:04 Sending playback information: Tra.13306061 / money-shopping@jbdotat.fastmail.fm / 40134 / 1225350356417:money-shopping@jbdotat.fastmail.fm:40134
[08-10-30 02:06:00.3834] Slim::Player::StreamingController::_nextTrackReady (633) 00:04:20:06:be:04: nextTrack will be index 5
[08-10-30 02:06:00.3849] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: NextTrackReady in STOPPED-TRACKWAIT -> Slim::Player::StreamingController::_Stream
[08-10-30 02:06:00.3859] Slim::Player::StreamingController::_Stream (894) 00:04:20:06:be:04: preparing to stream song index 5
[08-10-30 02:06:00.3869] Slim::Player::StreamingController::_Stream (899) Song queue is now 5,4
[08-10-30 02:06:00.3885] Slim::Player::Song::open (297) rhapd://Tra.13306061.mp3
[08-10-30 02:06:00.3913] Slim::Player::TranscodingHelper::getConvertCommand2 (310) rateLimit = 0, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-10-30 02:06:00.3922] Slim::Player::TranscodingHelper::enabledFormat (190) Checking to see if mp3-mp3-*-* is enabled
[08-10-30 02:06:00.3932] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-10-30 02:06:00.3943] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-10-30 02:06:00.3952] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-squeezebox2-*
[08-10-30 02:06:00.3958] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wma-*-*
[08-10-30 02:06:00.3964] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-10-30 02:06:00.3971] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-10-30 02:06:00.3978] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-squeezebox2-*
[08-10-30 02:06:00.3984] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-ogg-*-*
[08-10-30 02:06:00.3991] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-10-30 02:06:00.3997] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-10-30 02:06:00.4003] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-squeezebox2-*
[08-10-30 02:06:00.4009] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-flc-*-*
[08-10-30 02:06:00.4015] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-10-30 02:06:00.4022] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-10-30 02:06:00.4028] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-squeezebox2-*
[08-10-30 02:06:00.4036] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-aif-*-*
[08-10-30 02:06:00.4042] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-10-30 02:06:00.4049] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-10-30 02:06:00.4055] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-squeezebox2-*
[08-10-30 02:06:00.4061] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-wav-*-*
[08-10-30 02:06:00.4072] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-10-30 02:06:00.4079] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-10-30 02:06:00.4087] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-squeezebox2-*
[08-10-30 02:06:00.4093] Slim::Player::TranscodingHelper::checkBin (223) Checking formats for: mp3-mp3-*-*
[08-10-30 02:06:00.4099] Slim::Player::TranscodingHelper::enabledFormat (190) Checking to see if mp3-mp3-*-* is enabled
[08-10-30 02:06:00.4108] Slim::Player::TranscodingHelper::checkBin (231)    enabled
[08-10-30 02:06:00.4114] Slim::Player::TranscodingHelper::checkBin (233)   Found command: -
[08-10-30 02:06:00.4121] Slim::Player::TranscodingHelper::getConvertCommand2 (436) Matched: mp3->mp3 via: -
[08-10-30 02:06:00.4129] Slim::Player::Song::open (323) Transcoder: streamMode=I, streamformat=mp3
[08-10-30 02:06:00.4144] Slim::Player::Song::open (333) URL supports direct streaming [rhapd://Tra.13306061.mp3->http://llnw-656.listen.com/5/6/3/4/0/67004365.rad]
[08-10-30 02:06:00.4222] Slim::Player::SongStreamController::new (32) live=2
[08-10-30 02:06:00.4237] Slim::Player::SongStreamController::DESTROY (45) live=1
[08-10-30 02:06:00.4256] Slim::Player::StreamingController::_Stream (937) 00:04:20:06:be:04: stream
[08-10-30 02:06:00.4375] Slim::Player::Squeezebox::stream_s (721) This player supports direct streaming for http://llnw-656.listen.com/5/6/3/4/0/67004365.rad as http://llnw-656.listen.com/5/6/3/4/0/67004365.rad, let's do it.
[08-10-30 02:06:00.4489] Slim::Player::Squeezebox::stream_s (759) setting up direct stream (3496975680:80) autostart: 3.
[08-10-30 02:06:00.4498] Slim::Player::Squeezebox::stream_s (760) request string: GET /5/6/3/4/0/67004365.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-656.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.3/23730
Icy-Metadata: 1

[08-10-30 02:06:00.4657] Slim::Player::StreamingController::_Stream (962) Song queue is now 5,4
[08-10-30 02:06:00.4683] Slim::Player::StreamingController::_setPlayingState (1743) new playing state BUFFERING
[08-10-30 02:06:00.4696] Slim::Player::StreamingController::_setStreamingState (1752) new streaming state STREAMING
[08-10-30 02:06:00.4708] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: NextTrackReady - new state BUFFERING-STREAMING
[08-10-30 02:06:00.4771] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:00.5504] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:00.5531] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:00.5546] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:00.5561] Slim::Player::StreamingController::playerOutputUnderrun (1620) 00:04:20:06:be:04: decoder: 0 / output: 0
[08-10-30 02:06:00.5568] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: OutputUnderrun in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:00.5637] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:00.5654] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:00.5671] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:00.5680] 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: 15695
Date: Thu, 30 Oct 2008 07:06:00 GMT
Last-Modified: Fri, 20 Apr 2007 19:13:19 GMT
Connection: close

[08-10-30 02:06:00.5690] Slim::Player::Squeezebox2::directHeaders (447) Processing 8 headers
[08-10-30 02:06:00.5698] Slim::Player::Squeezebox2::directHeaders (453) Calling Slim::Plugin::RhapsodyDirect::ProtocolHandler ::parseDirectHeaders
[08-10-30 02:06:00.5705] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Server: Apache
[08-10-30 02:06:00.5728] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Accept-Ranges: bytes
[08-10-30 02:06:00.5736] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Length: 4452884
[08-10-30 02:06:00.5743] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Type: text/plain
[08-10-30 02:06:00.5750] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Age: 15695
[08-10-30 02:06:00.5756] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Date: Thu, 30 Oct 2008 07:06:00 GMT
[08-10-30 02:06:00.5762] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Last-Modified: Fri, 20 Apr 2007 19:13:19 GMT
[08-10-30 02:06:00.5771] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Connection: close
[08-10-30 02:06:00.6535] Slim::Player::Squeezebox2::directHeaders (511) Got a stream type: mp3 bitrate: 192000 title: 
[08-10-30 02:06:00.6551] Slim::Player::Squeezebox2::directHeaders (570) Beginning direct stream!
[08-10-30 02:06:00.7145] Slim::Player::Player::_buffering (1163) Buffering... 0 / 20480
[08-10-30 02:06:00.7361] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:00.7381] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:01.0326] Slim::Player::Player::_buffering (1163) Buffering... 1231 / 20480
[08-10-30 02:06:01.0467] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:01.0483] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-10-30 02:06:01.3379] Slim::Player::Player::_buffering (1163) Buffering... 1231 / 20480
[08-10-30 02:06:01.4581] Slim::Player::StreamingController::playerTrackStarted (1586) 00:04:20:06:be:04
[08-10-30 02:06:01.4590] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: Started in BUFFERING-STREAMING -> Slim::Player::StreamingController::_Playing
[08-10-30 02:06:01.4599] Slim::Player::StreamingController::_setPlayingState (1743) new playing state PLAYING
[08-10-30 02:06:01.4606] Slim::Player::StreamingController::_Playing (342) Song 4 is not longer in the queue
[08-10-30 02:06:01.4620] Slim::Player::Song::DESTROY (111) live=2: index=4, url=rhapd://Tra.13306060.mp3
[08-10-30 02:06:01.4644] Slim::Player::StreamingController::_Playing (348) Song 5 has now started playing
[08-10-30 02:06:01.4675] Slim::Player::StreamingController::_Playing (375) Song queue is now 5
[08-10-30 02:06:01.4683] Slim::Player::StreamingController::_eventAction (297) 00:04:20:06:be:04: Started - new state PLAYING-STREAMING
[08-10-30 02:06:01.4722] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:01.4740] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:01.5330] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:02.5729] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:03.4878] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:04.0282] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:04.0307] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:04.5043] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:05.5096] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:06.4916] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:07.5330] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:08.4924] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:09.0080] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:09.0108] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:09.4902] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:10.5118] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:11.5023] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-10-30 02:06:12.4992] Slim::Player::StreamingController::_eventAction (265) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync