[08-12-03 21:51:35.5009] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:36.5008] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:37.5634] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:38.5477] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:39.5321] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:40.5165] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:41.5008] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:42.5008] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:43.5008] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:44.5635] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:45.5478] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:46.5321] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:47.5165] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:48.5009] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:49.5009] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:50.5008] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:51.5634] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:52.5478] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:53.5321] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:54.5158] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:55.5126] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:56.5009] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:57.5124] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:58.5635] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:59.2423] Slim::Player::StreamingController::playerReadyToStream (1613) 00:04:20:06:be:04
[08-12-03 21:51:59.2427] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onPlayout (747) onPlayout, logging playback
[08-12-03 21:51:59.2435] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_doLog (797) Logging track playback: 191 seconds, trackId: Tra.10252997, stationId: 
[08-12-03 21:51:59.2737] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: ReadyToStream in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_NextIfMore
[08-12-03 21:51:59.2741] Slim::Player::StreamingController::_setStreamingState (1776) new streaming state IDLE
[08-12-03 21:51:59.2746] Slim::Player::StreamingController::nextsong (745) The next song is number 9, was 8
[08-12-03 21:51:59.2914] Slim::Player::Song::new (64) index 9 -> rhapd://Tra.10252998.mp3
[08-12-03 21:51:59.2921] Slim::Player::Song::new (101) live=2
[08-12-03 21:51:59.2925] Slim::Player::StreamingController::_setStreamingState (1776) new streaming state TRACKWAIT
[08-12-03 21:51:59.2929] Slim::Player::Song::getNextSong (178) rhapd://Tra.10252998.mp3
[08-12-03 21:51:59.2933] Slim::Player::Song::getNextSong (200) scanning URL rhapd://Tra.10252998.mp3
[08-12-03 21:51:59.2939] Slim::Player::Song::getNextSong (178) rhapd://Tra.10252998.mp3
[08-12-03 21:51:59.2947] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getTrackInfo (411) Getting next track playback info from SN
[08-12-03 21:51:59.2987] Slim::Player::StreamingController::_eventAction (301) 00:04:20:06:be:04: ReadyToStream - new state PLAYING-TRACKWAIT
[08-12-03 21:51:59.5122] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:51:59.7906] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (783) Logging returned: 1
[08-12-03 21:52:00.0207] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (388) getTrackInfo ok: {
  account => {
        cobrandId => 40_134,
        logon => "money-shopping\@jbdotat.fastmail.fm",
        playbackSessionId => "1228357543854:money-shopping\@jbdotat.fastmail.fm:40134",
      },
  mediaUrl => "http://llnw-669.listen.com/6/9/0/7/9/64997096.rad",
  trackMetadata => {
        albumId => "Alb.10250362",
        artistId => "Art.10250121",
        discIndex => 1,
        displayAlbumName => "Gulag Orkestar",
        displayArtistName => "Beirut",
        genreId => "g.204",
        name => "The Canals Of Our City",
        playbackSeconds => 141,
        price => undef,
        rightFlags => 574,
        trackId => "Tra.10252998",
        trackIndex => 10,
      },
  trackPlaybackFormat => { bitRate => 192, "format" => "RAD MP3", radVersion => 3 },
}
[08-12-03 21:52:00.1190] Slim::Player::StreamingController::_nextTrackReady (637) 00:04:20:06:be:04: nextTrack will be index 9
[08-12-03 21:52:00.1194] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: NextTrackReady in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_StreamIfReady
[08-12-03 21:52:00.1200] Slim::Player::StreamingController::_Stream (898) 00:04:20:06:be:04: preparing to stream song index 9
[08-12-03 21:52:00.1204] Slim::Player::StreamingController::_Stream (903) Song queue is now 9,8
[08-12-03 21:52:00.1209] Slim::Player::Song::open (302) rhapd://Tra.10252998.mp3
[08-12-03 21:52:00.1221] Slim::Player::Song::open (323) seek=false time=0 canSeek=1
[08-12-03 21:52:00.1230] Slim::Player::TranscodingHelper::getConvertCommand2 (323) rateLimit = 0, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-12-03 21:52:00.1234] Slim::Player::TranscodingHelper::enabledFormat (194) Checking to see if mp3-mp3-*-* is enabled
[08-12-03 21:52:00.1237] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-12-03 21:52:00.1240] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-12-03 21:52:00.1243] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wma-squeezebox2-*
[08-12-03 21:52:00.1253] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wma-*-*
[08-12-03 21:52:00.1258] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-12-03 21:52:00.1261] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-12-03 21:52:00.1264] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-ogg-squeezebox2-*
[08-12-03 21:52:00.1267] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-ogg-*-*
[08-12-03 21:52:00.1270] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-12-03 21:52:00.1273] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-12-03 21:52:00.1276] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-flc-squeezebox2-*
[08-12-03 21:52:00.1279] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-flc-*-*
[08-12-03 21:52:00.1282] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-12-03 21:52:00.1285] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-12-03 21:52:00.1288] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-aif-squeezebox2-*
[08-12-03 21:52:00.1291] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-aif-*-*
[08-12-03 21:52:00.1293] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-12-03 21:52:00.1296] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-12-03 21:52:00.1299] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wav-squeezebox2-*
[08-12-03 21:52:00.1303] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wav-*-*
[08-12-03 21:52:00.1306] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-12-03 21:52:00.1309] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-12-03 21:52:00.1312] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-mp3-squeezebox2-*
[08-12-03 21:52:00.1315] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-mp3-*-*
[08-12-03 21:52:00.1318] Slim::Player::TranscodingHelper::enabledFormat (194) Checking to see if mp3-mp3-*-* is enabled
[08-12-03 21:52:00.1321] Slim::Player::TranscodingHelper::checkBin (235)    enabled
[08-12-03 21:52:00.1324] Slim::Player::TranscodingHelper::checkBin (237)   Found command: -
[08-12-03 21:52:00.1328] Slim::Player::TranscodingHelper::getConvertCommand2 (454) Matched: mp3->mp3 via: -
[08-12-03 21:52:00.1331] Slim::Player::Song::open (340) Transcoder: streamMode=I, streamformat=mp3
[08-12-03 21:52:00.1336] Slim::Player::Song::open (354) URL supports direct streaming [rhapd://Tra.10252998.mp3->http://llnw-669.listen.com/6/9/0/7/9/64997096.rad]
[08-12-03 21:52:00.1371] Slim::Player::SongStreamController::new (32) live=2
[08-12-03 21:52:00.1379] Slim::Player::SongStreamController::DESTROY (45) DESTROY(Slim::Player::SongStreamController=HASH(0x655de6c)) live=1
[08-12-03 21:52:00.1382] Slim::Player::StreamingController::_Stream (941) 00:04:20:06:be:04: stream
[08-12-03 21:52:00.1386] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onStream (485) 00:04:20:06:be:04 Sending playback information: Tra.10252998 / money-shopping@jbdotat.fastmail.fm / 40134 / 1228357543854:money-shopping@jbdotat.fastmail.fm:40134
[08-12-03 21:52:00.2322] Slim::Player::Squeezebox::stream_s (734) This player supports direct streaming for http://llnw-669.listen.com/6/9/0/7/9/64997096.rad as http://llnw-669.listen.com/6/9/0/7/9/64997096.rad, let's do it.
[08-12-03 21:52:00.2396] Slim::Player::Squeezebox::stream_s (772) setting up direct stream (3496975724:80) autostart: 3.
[08-12-03 21:52:00.2400] Slim::Player::Squeezebox::stream_s (773) request string: GET /6/9/0/7/9/64997096.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-669.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.3/24179
Icy-Metadata: 1

[08-12-03 21:52:00.2658] Slim::Player::StreamingController::_Stream (970) Song queue is now 9,8
[08-12-03 21:52:00.2664] Slim::Player::StreamingController::_setStreamingState (1776) new streaming state STREAMING
[08-12-03 21:52:00.2667] Slim::Player::StreamingController::_eventAction (301) 00:04:20:06:be:04: NextTrackReady - new state PLAYING-STREAMING
[08-12-03 21:52:00.2692] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:52:00.2721] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:52:00.2735] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:52:00.2818] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:52:00.3131] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:52:00.3144] Slim::Player::Squeezebox2::directHeaders (394) Processing headers for direct streaming:
HTTP/1.0 200 OK
[08-12-03 21:54:19.5378] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:20.4383] Slim::Player::StreamingController::playerReadyToStream (1613) 00:04:20:06:be:04
[08-12-03 21:54:20.4389] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onPlayout (747) onPlayout, logging playback
[08-12-03 21:54:20.4396] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_doLog (797) Logging track playback: 140 seconds, trackId: Tra.10252998, stationId: 
[08-12-03 21:54:20.4808] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: ReadyToStream in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_NextIfMore
[08-12-03 21:54:20.4812] Slim::Player::StreamingController::_setStreamingState (1776) new streaming state IDLE
[08-12-03 21:54:20.4818] Slim::Player::StreamingController::nextsong (745) The next song is number 10, was 9
[08-12-03 21:54:20.4942] Slim::Player::Song::new (64) index 10 -> rhapd://Tra.10252999.mp3
[08-12-03 21:54:20.4949] Slim::Player::Song::new (101) live=2
[08-12-03 21:54:20.4954] Slim::Player::StreamingController::_setStreamingState (1776) new streaming state TRACKWAIT
[08-12-03 21:54:20.4959] Slim::Player::Song::getNextSong (178) rhapd://Tra.10252999.mp3
[08-12-03 21:54:20.4963] Slim::Player::Song::getNextSong (200) scanning URL rhapd://Tra.10252999.mp3
[08-12-03 21:54:20.4969] Slim::Player::Song::getNextSong (178) rhapd://Tra.10252999.mp3
[08-12-03 21:54:20.4982] Slim::Plugin::RhapsodyDirect::ProtocolHandler::_getTrackInfo (411) Getting next track playback info from SN
[08-12-03 21:54:20.5024] Slim::Player::StreamingController::_eventAction (301) 00:04:20:06:be:04: ReadyToStream - new state PLAYING-TRACKWAIT
[08-12-03 21:54:20.5448] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:21.1355] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (783) Logging returned: 1
[08-12-03 21:54:21.2595] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (388) getTrackInfo ok: {
  account => {
        cobrandId => 40_134,
        logon => "money-shopping\@jbdotat.fastmail.fm",
        playbackSessionId => "1228357543854:money-shopping\@jbdotat.fastmail.fm:40134",
      },
  mediaUrl => "http://llnw-639.listen.com/3/9/2/7/9/64997293.rad",
  trackMetadata => {
        albumId => "Alb.10250362",
        artistId => "Art.10250121",
        discIndex => 1,
        displayAlbumName => "Gulag Orkestar",
        displayArtistName => "Beirut",
        genreId => "g.204",
        name => "After The Curtain",
        playbackSeconds => 174,
        price => undef,
        rightFlags => 574,
        trackId => "Tra.10252999",
        trackIndex => 11,
      },
  trackPlaybackFormat => { bitRate => 192, "format" => "RAD MP3", radVersion => 3 },
}
[08-12-03 21:54:21.2829] Slim::Player::StreamingController::_nextTrackReady (637) 00:04:20:06:be:04: nextTrack will be index 10
[08-12-03 21:54:21.2834] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: NextTrackReady in PLAYING-TRACKWAIT -> Slim::Player::StreamingController::_StreamIfReady
[08-12-03 21:54:21.2838] Slim::Player::StreamingController::_Stream (898) 00:04:20:06:be:04: preparing to stream song index 10
[08-12-03 21:54:21.2841] Slim::Player::StreamingController::_Stream (903) Song queue is now 10,9
[08-12-03 21:54:21.2849] Slim::Player::Song::open (302) rhapd://Tra.10252999.mp3
[08-12-03 21:54:21.2861] Slim::Player::Song::open (323) seek=false time=0 canSeek=1
[08-12-03 21:54:21.2870] Slim::Player::TranscodingHelper::getConvertCommand2 (323) rateLimit = 0, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-12-03 21:54:21.2875] Slim::Player::TranscodingHelper::enabledFormat (194) Checking to see if mp3-mp3-*-* is enabled
[08-12-03 21:54:21.2879] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-12-03 21:54:21.2882] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-12-03 21:54:21.2885] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wma-squeezebox2-*
[08-12-03 21:54:21.2887] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wma-*-*
[08-12-03 21:54:21.2891] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-12-03 21:54:21.2894] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-12-03 21:54:21.2897] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-ogg-squeezebox2-*
[08-12-03 21:54:21.2900] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-ogg-*-*
[08-12-03 21:54:21.2902] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-12-03 21:54:21.2905] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-12-03 21:54:21.2978] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-flc-squeezebox2-*
[08-12-03 21:54:21.2983] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-flc-*-*
[08-12-03 21:54:21.2987] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-12-03 21:54:21.2990] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-12-03 21:54:21.2993] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-aif-squeezebox2-*
[08-12-03 21:54:21.2996] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-aif-*-*
[08-12-03 21:54:21.2998] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-12-03 21:54:21.3001] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-12-03 21:54:21.3005] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wav-squeezebox2-*
[08-12-03 21:54:21.3008] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-wav-*-*
[08-12-03 21:54:21.3011] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-12-03 21:54:21.3014] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-12-03 21:54:21.3017] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-mp3-squeezebox2-*
[08-12-03 21:54:21.3020] Slim::Player::TranscodingHelper::checkBin (227) Checking formats for: mp3-mp3-*-*
[08-12-03 21:54:21.3023] Slim::Player::TranscodingHelper::enabledFormat (194) Checking to see if mp3-mp3-*-* is enabled
[08-12-03 21:54:21.3026] Slim::Player::TranscodingHelper::checkBin (235)    enabled
[08-12-03 21:54:21.3029] Slim::Player::TranscodingHelper::checkBin (237)   Found command: -
[08-12-03 21:54:21.3033] Slim::Player::TranscodingHelper::getConvertCommand2 (454) Matched: mp3->mp3 via: -
[08-12-03 21:54:21.3036] Slim::Player::Song::open (340) Transcoder: streamMode=I, streamformat=mp3
[08-12-03 21:54:21.3041] Slim::Player::Song::open (354) URL supports direct streaming [rhapd://Tra.10252999.mp3->http://llnw-639.listen.com/3/9/2/7/9/64997293.rad]
[08-12-03 21:54:21.3078] Slim::Player::SongStreamController::new (32) live=2
[08-12-03 21:54:21.3126] Slim::Player::SongStreamController::DESTROY (45) DESTROY(Slim::Player::SongStreamController=HASH(0x6481ad8)) live=1
[08-12-03 21:54:21.3130] Slim::Player::StreamingController::_Stream (941) 00:04:20:06:be:04: stream
[08-12-03 21:54:21.3135] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onStream (485) 00:04:20:06:be:04 Sending playback information: Tra.10252999 / money-shopping@jbdotat.fastmail.fm / 40134 / 1228357543854:money-shopping@jbdotat.fastmail.fm:40134
[08-12-03 21:54:21.3716] Slim::Player::Squeezebox::stream_s (734) This player supports direct streaming for http://llnw-639.listen.com/3/9/2/7/9/64997293.rad as http://llnw-639.listen.com/3/9/2/7/9/64997293.rad, let's do it.
[08-12-03 21:54:21.3894] Slim::Player::Squeezebox::stream_s (772) setting up direct stream (3496975532:80) autostart: 3.
[08-12-03 21:54:21.3899] Slim::Player::Squeezebox::stream_s (773) request string: GET /3/9/2/7/9/64997293.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-639.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.3/24179
Icy-Metadata: 1

[08-12-03 21:54:21.3967] Slim::Player::StreamingController::_Stream (970) Song queue is now 10,9
[08-12-03 21:54:21.3972] Slim::Player::StreamingController::_setStreamingState (1776) new streaming state STREAMING
[08-12-03 21:54:21.3976] Slim::Player::StreamingController::_eventAction (301) 00:04:20:06:be:04: NextTrackReady - new state PLAYING-STREAMING
[08-12-03 21:54:21.4114] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:21.4126] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:21.4135] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:21.4191] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:21.4680] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:21.4692] Slim::Player::Squeezebox2::directHeaders (394) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache
Accept-Ranges: bytes
Content-Length: 4167972
Content-Type: text/plain
Age: 2116
Date: Thu, 04 Dec 2008 03:54:21 GMT
Last-Modified: Sat, 24 Mar 2007 09:33:43 GMT
Connection: close

[08-12-03 21:54:21.4698] Slim::Player::Squeezebox2::directHeaders (461) Processing 8 headers
[08-12-03 21:54:21.4701] Slim::Player::Squeezebox2::directHeaders (467) Calling Slim::Plugin::RhapsodyDirect::ProtocolHandler ::parseDirectHeaders
[08-12-03 21:54:21.4704] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Server: Apache
[08-12-03 21:54:21.4708] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Accept-Ranges: bytes
[08-12-03 21:54:21.4711] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Length: 4167972
[08-12-03 21:54:21.4714] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Content-Type: text/plain
[08-12-03 21:54:21.4717] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Age: 2116
[08-12-03 21:54:21.4720] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Date: Thu, 04 Dec 2008 03:54:21 GMT
[08-12-03 21:54:21.4723] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Last-Modified: Sat, 24 Mar 2007 09:33:43 GMT
[08-12-03 21:54:21.4726] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (128) RhapsodyDirect header: Connection: close
[08-12-03 21:54:21.5470] Slim::Player::Client::streamingProgressBar (1127) url=http://llnw-639.listen.com/3/9/2/7/9/64997293.rad, duration=173.665, bitrate=undef, contentLength=undef
[08-12-03 21:54:21.5584] Slim::Player::Client::streamingProgressBar (1161) Duration of stream set to 173.665 seconds
[08-12-03 21:54:21.5588] Slim::Player::Squeezebox2::directHeaders (525) Got a stream type: mp3 bitrate: 192000 title: 
[08-12-03 21:54:21.5595] Slim::Player::Squeezebox2::directHeaders (573) Beginning direct stream!
[08-12-03 21:54:21.5658] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:22.5431] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:23.5429] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:24.6034] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:25.5470] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:26.6166] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:27.6031] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:28.6105] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:29.6093] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:30.4186] Slim::Player::StreamingController::playerTrackStarted (1598) 00:04:20:06:be:04
[08-12-03 21:54:30.4191] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: Started in PLAYING-STREAMING -> Slim::Player::StreamingController::_Playing
[08-12-03 21:54:30.4194] Slim::Player::StreamingController::_setPlayingState (1763) new playing state PLAYING
[08-12-03 21:54:30.4198] Slim::Player::StreamingController::_Playing (346) Song 9 is not longer in the queue
[08-12-03 21:54:30.4203] Slim::Player::Song::DESTROY (111) DESTROY(Slim::Player::Song=HASH(0x64a6be8)) live=1: index=9, url=rhapd://Tra.10252998.mp3
[08-12-03 21:54:30.4216] Slim::Player::StreamingController::_Playing (352) Song 10 has now started playing
[08-12-03 21:54:30.4231] Slim::Player::StreamingController::_Playing (379) Song queue is now 10
[08-12-03 21:54:30.6059] Slim::Player::StreamingController::playerOutputUnderrun (1632) 00:04:20:06:be:04: decoder: 1398772 / output: 0
[08-12-03 21:54:30.6063] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: OutputUnderrun in PLAYING-STREAMING -> Slim::Player::StreamingController::_Rebuffer
[08-12-03 21:54:30.6066] Slim::Player::StreamingController::_setPlayingState (1763) new playing state BUFFERING
[08-12-03 21:54:30.6164] Slim::Player::StreamingController::_eventAction (301) 00:04:20:06:be:04: OutputUnderrun - new state BUFFERING-STREAMING
[08-12-03 21:54:30.6180] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-12-03 21:54:30.6187] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-12-03 21:54:30.6194] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-12-03 21:54:30.7429] Slim::Player::Player::_buffering (1171) Buffering... 1400444 / 120000
[08-12-03 21:54:30.7433] Slim::Player::StreamingController::playerBufferReady (1664) 00:04:20:06:be:04
[08-12-03 21:54:30.7437] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: BufferReady in BUFFERING-STREAMING -> Slim::Player::StreamingController::_WaitToSync
[08-12-03 21:54:30.7441] Slim::Player::StreamingController::_setPlayingState (1763) new playing state WAITING_TO_SYNC
[08-12-03 21:54:30.7444] Slim::Player::StreamingController::_setPlayingState (1763) new playing state PLAYING
[08-12-03 21:54:30.7462] Slim::Player::StreamingController::_eventAction (301) 00:04:20:06:be:04: BufferReady - new state PLAYING-STREAMING
[08-12-03 21:54:30.7873] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:30.8437] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:30.8447] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:30.8455] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:30.8534] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:30.9004] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:30.9518] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:31.0026] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:31.0524] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:31.1031] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:33.9452] Slim::Player::StreamingController::playerOutputUnderrun (1632) 00:04:20:06:be:04: decoder: 1985468 / output: 0
[08-12-03 21:54:33.9456] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: OutputUnderrun in PLAYING-STREAMING -> Slim::Player::StreamingController::_Rebuffer
[08-12-03 21:54:33.9460] Slim::Player::StreamingController::_setPlayingState (1763) new playing state BUFFERING
[08-12-03 21:54:33.9520] Slim::Player::StreamingController::_eventAction (301) 00:04:20:06:be:04: OutputUnderrun - new state BUFFERING-STREAMING
[08-12-03 21:54:33.9532] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-12-03 21:54:33.9540] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp
[08-12-03 21:54:34.0787] Slim::Player::Player::_buffering (1171) Buffering... 1991276 / 120000
[08-12-03 21:54:34.0791] Slim::Player::StreamingController::playerBufferReady (1664) 00:04:20:06:be:04
[08-12-03 21:54:34.0794] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: BufferReady in BUFFERING-STREAMING -> Slim::Player::StreamingController::_WaitToSync
[08-12-03 21:54:34.0797] Slim::Player::StreamingController::_setPlayingState (1763) new playing state WAITING_TO_SYNC
[08-12-03 21:54:34.0801] Slim::Player::StreamingController::_setPlayingState (1763) new playing state PLAYING
[08-12-03 21:54:34.0818] Slim::Player::StreamingController::_eventAction (301) 00:04:20:06:be:04: BufferReady - new state PLAYING-STREAMING
[08-12-03 21:54:34.0921] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:34.0930] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:34.0938] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:34.0947] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:34.1368] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:34.1891] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:34.2387] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:34.2896] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:34.3402] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:34.3906] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:34.4417] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMING -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:39.6156] Slim::Player::StreamingController::playerEndOfStream (1672) 00:04:20:06:be:04
[08-12-03 21:54:39.6160] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: EndOfStream in PLAYING-STREAMING -> Slim::Player::StreamingController::_AutoStart
[08-12-03 21:54:39.6164] Slim::Player::StreamingController::_setStreamingState (1776) new streaming state STREAMOUT
[08-12-03 21:54:39.6168] Slim::Player::StreamingController::_eventAction (301) 00:04:20:06:be:04: EndOfStream - new state PLAYING-STREAMOUT
[08-12-03 21:54:41.6282] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:42.6263] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:43.6259] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:44.6259] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:45.6259] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:46.6241] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:47.6172] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:48.6210] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:50.9027] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync
[08-12-03 21:54:50.9035] Slim::Player::StreamingController::_eventAction (269) 00:04:20:06:be:04: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingController::_CheckSync