Accept: */*
Host: llnw-618.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2.1/23629
Icy-Metadata: 1

[08-10-29 21:36:15.9405] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-10-29 21:36:15.9599] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (1302) Logging returned: 1
[08-10-29 21:36:16.0533] Slim::Player::Squeezebox2::directHeaders (333) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache
Accept-Ranges: bytes
Content-Length: 5694548
Content-Type: text/plain
Age: 2373
Date: Thu, 30 Oct 2008 02:36:16 GMT
Last-Modified: Fri, 20 Apr 2007 20:53:50 GMT
Connection: close

[08-10-29 21:36:16.0544] Slim::Player::Squeezebox2::directHeaders (392) Processing 8 headers
[08-10-29 21:36:16.0552] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Server: Apache
[08-10-29 21:36:16.0558] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Accept-Ranges: bytes
[08-10-29 21:36:16.0564] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Content-Length: 5694548
[08-10-29 21:36:16.0577] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Content-Type: text/plain
[08-10-29 21:36:16.0585] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Age: 2373
[08-10-29 21:36:16.0591] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Date: Thu, 30 Oct 2008 02:36:16 GMT
[08-10-29 21:36:16.0597] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Last-Modified: Fri, 20 Apr 2007 20:53:50 GMT
[08-10-29 21:36:16.0606] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Connection: close
[08-10-29 21:36:16.1254] Slim::Player::Squeezebox2::directHeaders (450) Got a stream type: mp3 bitrate: 192000 title: 
[08-10-29 21:36:16.1264] Slim::Player::Squeezebox2::directHeaders (502) Beginning direct stream!
[08-10-29 21:36:25.2856] Slim::Player::Source::trackStartEvent (1607) Got a track starting event
[08-10-29 21:36:25.2867] Slim::Player::Source::trackStartEvent (1614) Song 1 had already started, so it's not longer in the queue
[08-10-29 21:36:25.2874] Slim::Player::Source::trackStartEvent (1623) Song 2 has now started playing
[08-10-29 21:36:25.2898] Slim::Player::Source::trackStartEvent (1651) Song queue is now 2
[08-10-29 21:40:14.4049] Slim::Player::Source::decoderUnderrun (607) 00:04:20:06:be:04: Decoder underrun while this mode: playout-play
[08-10-29 21:40:14.4061] Slim::Player::Source::nextsong (1702) The next song is number 3, was 2
[08-10-29 21:40:14.4073] Slim::Player::Source::nextsong (1702) The next song is number 3, was 2
[08-10-29 21:40:14.4083] Slim::Player::Source::nextsong (1702) The next song is number 3, was 2
[08-10-29 21:40:14.4095] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (397) End of track, logging usage info (239 seconds)...
[08-10-29 21:40:14.4109] Slim::Plugin::RhapsodyDirect::ProtocolHandler::sendLogging (1311) Logging track playback: 239 seconds, trackId: Tra.13306058, stationId: 
[08-10-29 21:40:14.4486] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.13306059"
[08-10-29 21:40:14.7879] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x001http://llnw-641.listen.com/4/1/1/3/0/67003114.rad"
[08-10-29 21:40:14.8456] Slim::Player::Source::skipahead (958) **skipahead: opening next song
[08-10-29 21:40:14.8467] Slim::Player::Source::gotoNext (1352) Opening next song...
[08-10-29 21:40:14.8476] Slim::Player::Source::nextsong (1702) The next song is number 3, was 2
[08-10-29 21:40:14.8500] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-10-29 21:40:14.8508] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-10-29 21:40:14.8514] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-10-29 21:40:14.8520] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-10-29 21:40:14.8526] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-10-29 21:40:14.8532] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-10-29 21:40:14.8557] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-10-29 21:40:14.8564] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-10-29 21:40:14.8570] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-10-29 21:40:14.8580] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-10-29 21:40:14.8586] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-10-29 21:40:14.8593] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-10-29 21:40:14.8599] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-10-29 21:40:14.8605] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-10-29 21:40:14.8611] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-10-29 21:40:14.8620] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-10-29 21:40:14.8626] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-10-29 21:40:14.8632] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-10-29 21:40:14.8638] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-10-29 21:40:14.8644] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-10-29 21:40:14.8651] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-10-29 21:40:14.8659] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-10-29 21:40:14.8665] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-10-29 21:40:14.8671] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-10-29 21:40:14.8677] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-10-29 21:40:14.8683] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-10-29 21:40:14.8689] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-10-29 21:40:14.8697] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-10-29 21:40:14.9543] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-10-29 21:40:14.9555] Slim::Player::Source::gotoNext (1439) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-10-29 21:40:14.9562] Slim::Player::Source::streamingSongIndex (1496) Adding song index 3 to song queue
[08-10-29 21:40:14.9568] Slim::Player::Source::streamingSongIndex (1517) Song queue is now 3,2
[08-10-29 21:40:14.9574] Slim::Player::Source::skipahead (964) **skipahead: restarting
[08-10-29 21:40:14.9581] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from playout-play
[08-10-29 21:40:14.9595] Slim::Player::Source::resetSong (1741) Resetting song buffer.
[08-10-29 21:40:14.9700] Slim::Player::Source::openSong (1845) Trying to open: rhapd://Tra.13306059.mp3
[08-10-29 21:40:14.9720] Slim::Player::Source::openSong (1881) URL supports direct streaming [rhapd://Tra.13306059.mp3]
[08-10-29 21:40:14.9937] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-10-29 21:40:15.0032] Slim::Player::Squeezebox::stream (1027) This player supports direct streaming for rhapd://Tra.13306059.mp3 as http://llnw-641.listen.com/4/1/1/3/0/67003114.rad, let's do it.
[08-10-29 21:40:15.0133] Slim::Player::Squeezebox::stream (1066) setting up direct stream (3496976156:80) autostart: 3.
[08-10-29 21:40:15.0143] Slim::Player::Squeezebox::stream (1067) request string: GET /4/1/1/3/0/67003114.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-641.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2.1/23629
Icy-Metadata: 1

[08-10-29 21:40:15.0339] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-10-29 21:40:15.0594] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (1302) Logging returned: 1
[08-10-29 21:40:15.1875] Slim::Player::Squeezebox2::directHeaders (333) Processing headers for direct streaming:
HTTP/1.0 200 OK
Date: Thu, 30 Oct 2008 02:40:15 GMT
Server: Apache
Last-Modified: Fri, 20 Apr 2007 21:34:59 GMT
Accept-Ranges: bytes
Content-Length: 4520692
Content-Type: text/plain
Connection: close

[08-10-29 21:40:15.1885] Slim::Player::Squeezebox2::directHeaders (392) Processing 7 headers
[08-10-29 21:40:15.1892] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Date: Thu, 30 Oct 2008 02:40:15 GMT
[08-10-29 21:40:15.1900] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Server: Apache
[08-10-29 21:40:15.1906] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Last-Modified: Fri, 20 Apr 2007 21:34:59 GMT
[08-10-29 21:40:15.1912] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Accept-Ranges: bytes
[08-10-29 21:40:15.1918] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Content-Length: 4520692
[08-10-29 21:40:15.1925] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Content-Type: text/plain
[08-10-29 21:40:15.1930] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Connection: close
[08-10-29 21:40:15.2453] Slim::Player::Squeezebox2::directHeaders (450) Got a stream type: mp3 bitrate: 192000 title: 
[08-10-29 21:40:15.2463] Slim::Player::Squeezebox2::directHeaders (502) Beginning direct stream!
[08-10-29 21:40:24.4081] Slim::Player::Source::trackStartEvent (1607) Got a track starting event
[08-10-29 21:40:24.4089] Slim::Player::Source::trackStartEvent (1614) Song 2 had already started, so it's not longer in the queue
[08-10-29 21:40:24.4095] Slim::Player::Source::trackStartEvent (1623) Song 3 has now started playing
[08-10-29 21:40:24.4123] Slim::Player::Source::trackStartEvent (1651) Song queue is now 3
[08-10-29 21:40:24.5949] Slim::Player::Source::outputUnderrun (804) 00:04:20:06:be:04: Output buffer underrun (decoder: 9791 / output: 0)
[08-10-29 21:40:24.5962] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pausenow from playout-play
[08-10-29 21:40:24.5985] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pausenow
[08-10-29 21:40:24.6810] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pausenow
[08-10-29 21:40:28.8164] Slim::Player::Source::rebuffer (902) Rebuffering: 47543 / 120000
[08-10-29 21:40:29.8319] Slim::Player::Source::rebuffer (902) Rebuffering: 330683 / 120000
[08-10-29 21:40:29.8334] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-10-29 21:40:29.8354] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-10-29 21:40:29.8741] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: play
[08-10-29 21:40:29.9309] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-10-29 21:40:30.1150] Slim::Player::Source::outputUnderrun (804) 00:04:20:06:be:04: Output buffer underrun (decoder: 387695 / output: 0)
[08-10-29 21:40:30.1159] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pausenow from play
[08-10-29 21:40:30.1180] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pausenow
[08-10-29 21:40:30.1217] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pausenow
[08-10-29 21:40:31.1321] Slim::Player::Source::rebuffer (902) Rebuffering: 396407 / 120000
[08-10-29 21:40:31.1334] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-10-29 21:40:31.1354] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-10-29 21:40:31.1393] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: play
[08-10-29 21:40:31.1438] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-10-29 21:40:46.6911] Slim::Player::Source::outputUnderrun (804) 00:04:20:06:be:04: Output buffer underrun (decoder: 4531 / output: 0)
[08-10-29 21:40:46.6919] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pausenow from play
[08-10-29 21:40:46.6937] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pausenow
[08-10-29 21:40:46.6966] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pausenow
[08-10-29 21:40:47.7053] Slim::Player::Source::rebuffer (902) Rebuffering: 5983 / 120000
[08-10-29 21:40:49.0755] Slim::Player::Source::rebuffer (902) Rebuffering: 114883 / 120000
[08-10-29 21:40:50.0829] Slim::Player::Source::rebuffer (902) Rebuffering: 290575 / 120000
[08-10-29 21:40:50.0844] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-10-29 21:40:50.0870] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-10-29 21:40:50.0896] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: play
[08-10-29 21:40:50.0939] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-10-29 21:41:01.4932] Slim::Player::Source::outputUnderrun (804) 00:04:20:06:be:04: Output buffer underrun (decoder: 117235 / output: 0)
[08-10-29 21:41:01.4940] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pausenow from play
[08-10-29 21:41:01.4982] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pausenow
[08-10-29 21:41:01.5011] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pausenow
[08-10-29 21:41:02.5125] Slim::Player::Source::rebuffer (902) Rebuffering: 118687 / 120000
[08-10-29 21:41:03.5199] Slim::Player::Source::rebuffer (902) Rebuffering: 176767 / 120000
[08-10-29 21:41:03.5208] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-10-29 21:41:03.5232] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-10-29 21:41:03.5259] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: play
[08-10-29 21:41:03.5291] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-10-29 21:41:22.0243] Slim::Player::Source::outputUnderrun (804) 00:04:20:06:be:04: Output buffer underrun (decoder: 44047 / output: 0)
[08-10-29 21:41:22.0251] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pausenow from play
[08-10-29 21:41:22.0278] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pausenow
[08-10-29 21:41:22.0313] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pausenow
[08-10-29 21:41:23.0388] Slim::Player::Source::rebuffer (902) Rebuffering: 44047 / 120000
[08-10-29 21:41:24.0505] Slim::Player::Source::rebuffer (902) Rebuffering: 157303 / 120000
[08-10-29 21:41:24.0516] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-10-29 21:41:24.0543] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-10-29 21:41:24.0568] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: play
[08-10-29 21:41:24.0608] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-10-29 21:41:28.7052] Slim::Player::Source::decoderUnderrun (607) 00:04:20:06:be:04: Decoder underrun while this mode: play
[08-10-29 21:41:28.7063] Slim::Player::Source::gotoNext (1352) Opening next song...
[08-10-29 21:41:28.7075] Slim::Player::Source::nextsong (1702) The next song is number 4, was 3
[08-10-29 21:41:28.7093] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-10-29 21:41:28.7103] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-10-29 21:41:28.7109] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-10-29 21:41:28.7115] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-10-29 21:41:28.7122] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-10-29 21:41:28.7128] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-10-29 21:41:28.7134] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-10-29 21:41:28.7153] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-10-29 21:41:28.7159] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-10-29 21:41:28.7166] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-10-29 21:41:28.7172] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-10-29 21:41:28.7179] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-10-29 21:41:28.7189] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-10-29 21:41:28.7196] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-10-29 21:41:28.7202] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-10-29 21:41:28.7208] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-10-29 21:41:28.7214] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-10-29 21:41:28.7223] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-10-29 21:41:28.7230] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-10-29 21:41:28.7237] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-10-29 21:41:28.7242] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-10-29 21:41:28.7249] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-10-29 21:41:28.7257] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-10-29 21:41:28.7263] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-10-29 21:41:28.7269] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-10-29 21:41:28.7275] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-10-29 21:41:28.7282] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-10-29 21:41:28.7288] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-10-29 21:41:28.8578] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-10-29 21:41:28.8711] Slim::Player::Source::gotoNext (1417) Playing out before starting next song. (old format: mp3, new: mp3)
[08-10-29 21:41:28.8719] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode playout-play from play
[08-10-29 21:41:28.8746] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: playout-play
[08-10-29 21:41:28.8775] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: playout-play
[08-10-29 21:41:28.8792] Slim::Player::Source::dropStreamingConnection (1461) No pending chunks - we're dropping the streaming connection
[08-10-29 21:41:28.8924] Slim::Player::Source::decoderUnderrun (620) 00:04:20:06:be:04: forcing playout mode on unexpected decoder underrun: new mode playout-play
[08-10-29 21:41:28.8932] Slim::Player::Source::nextsong (1702) The next song is number 4, was 3
[08-10-29 21:41:28.8947] Slim::Player::Source::nextsong (1702) The next song is number 4, was 3
[08-10-29 21:41:28.8962] Slim::Player::Source::nextsong (1702) The next song is number 4, was 3
[08-10-29 21:41:28.9007] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (397) End of track, logging usage info (189 seconds)...
[08-10-29 21:41:28.9046] Slim::Plugin::RhapsodyDirect::ProtocolHandler::sendLogging (1311) Logging track playback: 189 seconds, trackId: Tra.13306059, stationId: 
[08-10-29 21:41:28.9197] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.13306060"
[08-10-29 21:41:28.9225] Slim::Player::Source::underrun (726) 00:04:20:06:be:04: Underrun while this mode: playout-play
[08-10-29 21:41:28.9466] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onUnderrun (644) Underrun, stopping, playmode: playout-play
[08-10-29 21:41:28.9476] Slim::Player::Source::nextsong (1702) The next song is number 4, was 3
[08-10-29 21:41:28.9491] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode stop from playout-stop
[08-10-29 21:41:28.9578] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: stop
[08-10-29 21:41:28.9589] Slim::Player::Source::playmode (461) 00:04:20:06:be:04: Stopping and clearing out old chunks
[08-10-29 21:41:28.9619] Slim::Player::Source::resetSong (1741) Resetting song buffer.
[08-10-29 21:41:28.9627] Slim::Player::Source::resetSongQueue (1570) Resetting song queue
[08-10-29 21:41:28.9634] Slim::Player::Source::resetSongQueue (1580) Song queue is now 3
[08-10-29 21:41:28.9710] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: stop
[08-10-29 21:41:28.9725] Slim::Player::Source::streamingSongIndex (1496) Adding song index 0 to song queue
[08-10-29 21:41:28.9731] Slim::Player::Source::streamingSongIndex (1500) Clearing out song queue first
[08-10-29 21:41:28.9738] Slim::Player::Source::streamingSongIndex (1517) Song queue is now 0
[08-10-29 21:41:29.0357] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (678) Skipping to next track (4)
[08-10-29 21:41:29.0832] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (420) Handling command "jump", playmode: stop
[08-10-29 21:41:29.1112] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (511) Ending any previous playback session
[08-10-29 21:41:29.1302] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\6"
[08-10-29 21:41:29.1811] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\xFC"
[08-10-29 21:41:29.1832] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (313) 00:04:20:06:be:04 Received RPDS -4, SSL connection already in use, retrying later
[08-10-29 21:41:29.1991] Slim::Plugin::RhapsodyDirect::ProtocolHandler::stopCallback (1062) Player stopped (stop ) but another track was already starting, ignoring
[08-10-29 21:41:29.2368] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x001http://llnw-615.listen.com/1/5/3/4/0/67004351.rad"
[08-10-29 21:41:29.2380] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (334) 00:04:20:06:be:04 Ignoring unrequested or old RPDS packet (got 3, expected N/A)
[08-10-29 21:41:29.8961] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (1302) Logging returned: 1
[08-10-29 21:41:32.4234] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (361) 00:04:20:06:be:04 Re-sending RPDS packet: "\6"
[08-10-29 21:41:32.4259] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\6"
[08-10-29 21:41:33.0016] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\6"
[08-10-29 21:41:33.2416] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (284) Found IP for secure-direct.rhapsody.com: 207.188.0.25
[08-10-29 21:41:33.3092] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\0\xCF\xBC\0\31\1\xBB"
[08-10-29 21:41:33.3102] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getPlaybackSession (304) 00:04:20:06:be:04 Requesting new playback session...
[08-10-29 21:41:33.3172] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: 2
[08-10-29 21:41:33.8946] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\2"
[08-10-29 21:41:33.8957] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (347) New playback session obtained, resetting sessionErrors count to 0
[08-10-29 21:41:33.8987] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.13306060"
[08-10-29 21:41:34.4847] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x001http://llnw-615.listen.com/1/5/3/4/0/67004351.rad"
[08-10-29 21:41:34.5328] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode stop from stop
[08-10-29 21:41:34.5335] Slim::Player::Source::playmode (316) Already in playmode stop : ignoring mode change
[08-10-29 21:41:34.5342] Slim::Player::Source::jumpto (1308) Jumping to 4
[08-10-29 21:41:34.5348] Slim::Player::Source::streamingSongIndex (1496) Adding song index 4 to song queue
[08-10-29 21:41:34.5354] Slim::Player::Source::streamingSongIndex (1500) Clearing out song queue first
[08-10-29 21:41:34.5361] Slim::Player::Source::streamingSongIndex (1517) Song queue is now 4
[08-10-29 21:41:34.5374] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from stop
[08-10-29 21:41:34.5385] Slim::Player::Source::resetSong (1741) Resetting song buffer.
[08-10-29 21:41:34.6115] Slim::Player::Source::openSong (1845) Trying to open: rhapd://Tra.13306060.mp3
[08-10-29 21:41:34.6126] Slim::Player::Source::openSong (1881) URL supports direct streaming [rhapd://Tra.13306060.mp3]
[08-10-29 21:41:34.6781] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-10-29 21:41:34.6841] Slim::Player::Squeezebox::stream (1027) This player supports direct streaming for rhapd://Tra.13306060.mp3 as http://llnw-615.listen.com/1/5/3/4/0/67004351.rad, let's do it.
[08-10-29 21:41:34.7300] Slim::Player::Squeezebox::stream (1066) setting up direct stream (3496975515:80) autostart: 3.
[08-10-29 21:41:34.7308] Slim::Player::Squeezebox::stream (1067) 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.2.1/23629
Icy-Metadata: 1

[08-10-29 21:41:34.7485] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-10-29 21:41:34.8827] Slim::Player::Squeezebox::buffering (295) Buffering... 0 / 73728
[08-10-29 21:41:34.9372] Slim::Player::Squeezebox2::directHeaders (333) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache
Accept-Ranges: bytes
Content-Length: 4245732
Content-Type: text/plain
Date: Thu, 30 Oct 2008 02:41:35 GMT
Last-Modified: Fri, 20 Apr 2007 19:23:39 GMT
Connection: close

[08-10-29 21:41:34.9384] Slim::Player::Squeezebox2::directHeaders (392) Processing 7 headers
[08-10-29 21:41:34.9393] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Server: Apache
[08-10-29 21:41:34.9399] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Accept-Ranges: bytes
[08-10-29 21:41:34.9405] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Content-Length: 4245732
[08-10-29 21:41:34.9412] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Content-Type: text/plain
[08-10-29 21:41:34.9418] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Date: Thu, 30 Oct 2008 02:41:35 GMT
[08-10-29 21:41:34.9425] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Last-Modified: Fri, 20 Apr 2007 19:23:39 GMT
[08-10-29 21:41:34.9433] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (71) RhapsodyDirect header: Connection: close
[08-10-29 21:41:34.9854] Slim::Player::Squeezebox2::directHeaders (450) Got a stream type: mp3 bitrate: 192000 title: 
[08-10-29 21:41:34.9864] Slim::Player::Squeezebox2::directHeaders (502) Beginning direct stream!
[08-10-29 21:41:35.2920] Slim::Player::Squeezebox::buffering (295) Buffering... 1243 / 73728
[08-10-29 21:41:35.6974] Slim::Player::Squeezebox::buffering (295) Buffering... 1243 / 73728
[08-10-29 21:41:35.9285] Slim::Player::Source::trackStartEvent (1607) Got a track starting event
[08-10-29 21:41:35.9293] Slim::Player::Source::trackStartEvent (1623) Song 4 has now started playing
[08-10-29 21:41:35.9320] Slim::Player::Source::trackStartEvent (1651) Song queue is now 4
[08-10-29 21:41:52.8208] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\xFB"
[08-10-29 21:41:52.8216] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (165) RPDS: getEA failed
[08-10-29 21:42:11.8277] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\xFB"
[08-10-29 21:42:11.8288] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (165) RPDS: getEA failed