[08-08-20 21:50:29.2015] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode stop from playout-play
[08-08-20 21:50:29.2027] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: stop
[08-08-20 21:50:29.2030] Slim::Player::Source::playmode (461) 00:04:20:06:be:04: Stopping and clearing out old chunks
[08-08-20 21:50:29.2200] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-20 21:50:29.2205] Slim::Player::Source::resetSongQueue (1568) Resetting song queue
[08-08-20 21:50:29.2302] Slim::Player::Source::resetSongQueue (1578) Song queue is now 3
[08-08-20 21:50:29.2858] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: stop
[08-08-20 21:50:29.3022] Slim::Player::Source::streamingSongIndex (1494) Adding song index 0 to song queue
[08-08-20 21:50:29.3025] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-20 21:50:29.3028] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 0
[08-08-20 21:50:30.5783] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (395) Handling command "jump", playmode: stop
[08-08-20 21:50:30.5824] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (486) Ending any previous playback session
[08-08-20 21:50:30.5841] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\6"
[08-08-20 21:50:31.2970] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\6"
[08-08-20 21:50:31.2980] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (259) Found IP for secure-direct.rhapsody.com: 207.188.0.25
[08-08-20 21:50:31.3230] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\0\xCF\xBC\0\31\1\xBB"
[08-08-20 21:50:31.3234] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getPlaybackSession (279) 00:04:20:06:be:04 Requesting new playback session...
[08-08-20 21:50:31.3261] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: 2
[08-08-20 21:50:31.6358] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getMetadataFor (1188) Need to fetch metadata for: Tra.19757251, Tra.19757252, Tra.19757253, Tra.19757254, Tra.19757255, Tra.19757256, Tra.19757257, Tra.19757246, Tra.19757247, Tra.19757248, Tra.19757249, Tra.19757250
[08-08-20 21:50:31.6864] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\2"
[08-08-20 21:50:31.6870] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (339) New playback session obtained, resetting sessionErrors count to 0
[08-08-20 21:50:31.6884] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.19757251"
[08-08-20 21:50:32.5354] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x003http://llnw-1182.listen.com/8/2/4/6/7/122476428.rad"
[08-08-20 21:50:36.4259] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode stop from stop
[08-08-20 21:50:36.4263] Slim::Player::Source::playmode (316) Already in playmode stop : ignoring mode change
[08-08-20 21:50:36.4266] Slim::Player::Source::jumpto (1306) Jumping to 0
[08-08-20 21:50:36.4269] Slim::Player::Source::streamingSongIndex (1494) Adding song index 0 to song queue
[08-08-20 21:50:36.4272] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-20 21:50:36.4275] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 0
[08-08-20 21:50:36.4282] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from stop
[08-08-20 21:50:36.4288] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-20 21:50:36.4297] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.19757251.mp3
[08-08-20 21:50:36.4303] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.19757251.mp3]
[08-08-20 21:50:36.4360] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-08-20 21:50:36.4387] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.19757251.mp3 as http://llnw-1182.listen.com/8/2/4/6/7/122476428.rad, let's do it.
[08-08-20 21:50:36.5002] Slim::Player::Squeezebox::stream (1055) setting up direct stream (3496975670:80) autostart: 3.
[08-08-20 21:50:36.5006] Slim::Player::Squeezebox::stream (1056) request string: GET /8/2/4/6/7/122476428.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-1182.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2/22756
Icy-Metadata: 1

[08-08-20 21:50:36.5062] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-08-20 21:50:36.5396] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotBulkMetadata (676) Caching metadata for 12 tracks
[08-08-20 21:50:36.6432] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK
Date: Thu, 21 Aug 2008 02:50:33 GMT
Server: Apache/2.2.9
Last-Modified: Sat, 02 Aug 2008 07:02:58 GMT
Accept-Ranges: bytes
Content-Length: 4950548
Content-Type: text/plain
Connection: close

[08-08-20 21:50:36.6437] Slim::Player::Squeezebox2::directHeaders (349) Processing 7 headers
[08-08-20 21:50:36.6441] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Date: Thu, 21 Aug 2008 02:50:33 GMT
[08-08-20 21:50:36.6444] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Server: Apache/2.2.9
[08-08-20 21:50:36.6447] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Last-Modified: Sat, 02 Aug 2008 07:02:58 GMT
[08-08-20 21:50:36.6450] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-20 21:50:36.6453] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Content-Length: 4950548
[08-08-20 21:50:36.6742] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-20 21:50:36.6747] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-20 21:50:36.6878] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 73728
[08-08-20 21:50:37.1009] Slim::Player::Squeezebox::buffering (303) Buffering... 1449 / 73728
[08-08-20 21:50:37.5190] Slim::Player::Squeezebox::buffering (303) Buffering... 40628 / 73728
[08-08-20 21:50:37.9228] Slim::Player::Squeezebox::buffering (303) Buffering... 258392 / 73728
[08-08-20 21:50:38.0007] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-20 21:50:38.0011] Slim::Player::Source::trackStartEvent (1621) Song 0 has now started playing
[08-08-20 21:50:38.0028] Slim::Player::Source::trackStartEvent (1649) Song queue is now 0
[08-08-20 21:53:55.8417] Slim::Player::Source::decoderUnderrun (607) 00:04:20:06:be:04: Decoder underrun while this mode: playout-play
[08-08-20 21:53:55.8421] Slim::Player::Source::nextsong (1700) The next song is number 1, was 0
[08-08-20 21:53:55.8428] Slim::Player::Source::nextsong (1700) The next song is number 1, was 0
[08-08-20 21:53:55.8434] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (372) End of track, logging usage info (207 seconds)...
[08-08-20 21:53:55.8440] Slim::Plugin::RhapsodyDirect::ProtocolHandler::sendLogging (1275) Logging track playback: 207 seconds, trackId: Tra.19757251, stationId: , user: money-shopping@jbdotat.fastmail.fm
[08-08-20 21:53:55.8868] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.19757252"
[08-08-20 21:53:56.2595] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x003http://llnw-1106.listen.com/0/6/8/6/7/122476860.rad"
[08-08-20 21:53:56.3558] Slim::Player::Source::skipahead (956) **skipahead: opening next song
[08-08-20 21:53:56.3562] Slim::Player::Source::gotoNext (1350) Opening next song...
[08-08-20 21:53:56.3567] Slim::Player::Source::nextsong (1700) The next song is number 1, was 0
[08-08-20 21:53:56.3575] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-08-20 21:53:56.3579] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-08-20 21:53:56.3582] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-08-20 21:53:56.3585] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-08-20 21:53:56.3588] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-08-20 21:53:56.3595] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-08-20 21:53:56.3598] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-08-20 21:53:56.3601] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-08-20 21:53:56.3604] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-08-20 21:53:56.3607] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-08-20 21:53:56.3610] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-08-20 21:53:56.3613] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-08-20 21:53:56.3616] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-08-20 21:53:56.3619] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-08-20 21:53:56.3622] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-08-20 21:53:56.3625] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-08-20 21:53:56.3628] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-08-20 21:53:56.3631] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-08-20 21:53:56.3634] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-08-20 21:53:56.3637] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-08-20 21:53:56.3640] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-08-20 21:53:56.3643] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-08-20 21:53:56.3646] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-08-20 21:53:56.3649] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-08-20 21:53:56.3652] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-08-20 21:53:56.3655] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-08-20 21:53:56.3658] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-08-20 21:53:56.3661] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-08-20 21:53:56.4210] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-08-20 21:53:56.4219] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-08-20 21:53:56.4223] Slim::Player::Source::streamingSongIndex (1494) Adding song index 1 to song queue
[08-08-20 21:53:56.4226] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 1,0
[08-08-20 21:53:56.4229] Slim::Player::Source::skipahead (962) **skipahead: restarting
[08-08-20 21:53:56.4233] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from playout-play
[08-08-20 21:53:56.4240] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-20 21:53:56.4281] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.19757252.mp3
[08-08-20 21:53:56.4287] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.19757252.mp3]
[08-08-20 21:53:56.4323] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-08-20 21:53:56.4346] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.19757252.mp3 as http://llnw-1106.listen.com/0/6/8/6/7/122476860.rad, let's do it.
[08-08-20 21:53:56.4661] Slim::Player::Squeezebox::stream (1055) setting up direct stream (3496975676:80) autostart: 3.
[08-08-20 21:53:56.4665] Slim::Player::Squeezebox::stream (1056) request string: GET /0/6/8/6/7/122476860.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-1106.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2/22756
Icy-Metadata: 1

[08-08-20 21:53:56.4738] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-08-20 21:53:56.6284] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache/2.2.9
Accept-Ranges: bytes
Content-Length: 5657228
Content-Type: text/plain
Date: Thu, 21 Aug 2008 02:53:56 GMT
Last-Modified: Sat, 02 Aug 2008 08:16:09 GMT
Connection: close

[08-08-20 21:53:56.6289] Slim::Player::Squeezebox2::directHeaders (349) Processing 7 headers
[08-08-20 21:53:56.6292] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Server: Apache/2.2.9
[08-08-20 21:53:56.6295] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-20 21:53:56.6298] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Content-Length: 5657228
[08-08-20 21:53:56.6408] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-20 21:53:56.6413] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-20 21:53:56.6509] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (1266) Logging returned: true
[08-08-20 21:54:05.8185] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-20 21:54:05.8189] Slim::Player::Source::trackStartEvent (1612) Song 0 had already started, so it's not longer in the queue
[08-08-20 21:54:05.8192] Slim::Player::Source::trackStartEvent (1621) Song 1 has now started playing
[08-08-20 21:54:05.8206] Slim::Player::Source::trackStartEvent (1649) Song queue is now 1
[08-08-20 21:54:06.0470] Slim::Player::Source::outputUnderrun (800) 00:04:20:06:be:04: Output buffer underrun (decoder: 1916101 / output: 0)
[08-08-20 21:54:06.0476] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pause from playout-play
[08-08-20 21:54:06.0487] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pause
[08-08-20 21:54:06.1186] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pause
[08-08-20 21:54:07.1627] Slim::Player::Source::rebuffer (900) Rebuffering: 2105293 / 120000
[08-08-20 21:54:07.1632] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-08-20 21:54:07.1646] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-08-20 21:54:07.1716] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: playout-play
[08-08-20 21:54:07.1744] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-08-20 21:54:10.9901] Slim::Player::Source::outputUnderrun (800) 00:04:20:06:be:04: Output buffer underrun (decoder: 3141845 / output: 0)
[08-08-20 21:54:10.9905] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pause from playout-play
[08-08-20 21:54:10.9916] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pause
[08-08-20 21:54:10.9930] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pause
[08-08-20 21:54:12.0020] Slim::Player::Source::rebuffer (900) Rebuffering: 3119705 / 120000
[08-08-20 21:54:12.0024] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-08-20 21:54:12.0034] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-08-20 21:54:12.0047] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: playout-play
[08-08-20 21:54:12.0068] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-08-20 21:54:15.5269] Slim::Player::Source::outputUnderrun (800) 00:04:20:06:be:04: Output buffer underrun (decoder: 3141781 / output: 0)
[08-08-20 21:54:15.5273] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pause from playout-play
[08-08-20 21:54:15.5284] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pause
[08-08-20 21:54:15.5299] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pause
[08-08-20 21:54:16.5489] Slim::Player::Source::rebuffer (900) Rebuffering: 3145405 / 120000
[08-08-20 21:54:16.5493] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-08-20 21:54:16.5503] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-08-20 21:54:16.5516] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: playout-play
[08-08-20 21:54:16.5544] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-08-20 21:54:18.8274] Slim::Player::Source::outputUnderrun (800) 00:04:20:06:be:04: Output buffer underrun (decoder: 3143201 / output: 0)
[08-08-20 21:54:18.8288] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pause from playout-play
[08-08-20 21:54:18.8299] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pause
[08-08-20 21:54:18.8313] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pause
[08-08-20 21:54:19.8925] Slim::Player::Source::rebuffer (900) Rebuffering: 3142765 / 120000
[08-08-20 21:54:19.8930] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-08-20 21:54:19.8945] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-08-20 21:54:19.8958] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: playout-play
[08-08-20 21:54:19.8979] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-08-20 21:54:22.1258] Slim::Player::Source::outputUnderrun (800) 00:04:20:06:be:04: Output buffer underrun (decoder: 3144033 / output: 0)
[08-08-20 21:54:22.1262] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pause from playout-play
[08-08-20 21:54:22.1273] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pause
[08-08-20 21:54:22.1288] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pause
[08-08-20 21:54:23.1475] Slim::Player::Source::rebuffer (900) Rebuffering: 3140997 / 120000
[08-08-20 21:54:23.1479] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-08-20 21:54:23.1495] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-08-20 21:54:23.1508] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: playout-play
[08-08-20 21:54:23.1530] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-08-20 21:54:53.3308] Slim::Player::Source::decoderUnderrun (607) 00:04:20:06:be:04: Decoder underrun while this mode: playout-play
[08-08-20 21:54:53.3313] Slim::Player::Source::nextsong (1700) The next song is number 2, was 1
[08-08-20 21:54:53.3319] Slim::Player::Source::nextsong (1700) The next song is number 2, was 1
[08-08-20 21:54:53.3325] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (372) End of track, logging usage info (237 seconds)...
[08-08-20 21:54:53.3331] Slim::Plugin::RhapsodyDirect::ProtocolHandler::sendLogging (1275) Logging track playback: 237 seconds, trackId: Tra.19757252, stationId: , user: money-shopping@jbdotat.fastmail.fm
[08-08-20 21:54:53.3371] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.19757253"
[08-08-20 21:54:53.3452] Slim::Player::Source::underrun (722) 00:04:20:06:be:04: Underrun while this mode: playout-play
[08-08-20 21:54:53.3537] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onUnderrun (619) Underrun, stopping, playmode: playout-play
[08-08-20 21:54:53.3544] Slim::Player::Source::nextsong (1700) The next song is number 2, was 1
[08-08-20 21:54:53.3548] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode stop from playout-stop
[08-08-20 21:54:53.3559] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: stop
[08-08-20 21:54:53.3563] Slim::Player::Source::playmode (461) 00:04:20:06:be:04: Stopping and clearing out old chunks
[08-08-20 21:54:53.3578] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-20 21:54:53.3582] Slim::Player::Source::resetSongQueue (1568) Resetting song queue
[08-08-20 21:54:53.3585] Slim::Player::Source::resetSongQueue (1578) Song queue is now 1
[08-08-20 21:54:53.3594] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: stop
[08-08-20 21:54:53.3598] Slim::Player::Source::streamingSongIndex (1494) Adding song index 0 to song queue
[08-08-20 21:54:53.3603] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-20 21:54:53.3610] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 0
[08-08-20 21:54:53.3650] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (653) Skipping to next track (2)
[08-08-20 21:54:53.3660] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (395) Handling command "jump", playmode: stop
[08-08-20 21:54:53.3732] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (486) Ending any previous playback session
[08-08-20 21:54:53.3754] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\6"
[08-08-20 21:54:53.3794] Slim::Plugin::RhapsodyDirect::ProtocolHandler::stopCallback (1037) Player stopped (stop ) but another track was already starting, ignoring
[08-08-20 21:54:53.3826] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\xFC"
[08-08-20 21:54:53.3831] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (305) 00:04:20:06:be:04 Received RPDS -4, SSL connection already in use, retrying later
[08-08-20 21:54:53.7361] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x003http://llnw-1166.listen.com/6/6/0/6/7/122476066.rad"
[08-08-20 21:54:53.7367] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (326) 00:04:20:06:be:04 Ignoring unrequested or old RPDS packet (got 3, expected N/A)
[08-08-20 21:54:53.8349] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (1266) Logging returned: true
[08-08-20 21:54:55.3913] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (353) 00:04:20:06:be:04 Re-sending RPDS packet: "\6"
[08-08-20 21:54:55.3926] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\6"
[08-08-20 21:54:55.8906] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\6"
[08-08-20 21:54:55.8920] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (259) Found IP for secure-direct.rhapsody.com: 207.188.0.25
[08-08-20 21:54:55.8967] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\0\xCF\xBC\0\31\1\xBB"
[08-08-20 21:54:55.8971] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getPlaybackSession (279) 00:04:20:06:be:04 Requesting new playback session...
[08-08-20 21:54:55.8999] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: 2
[08-08-20 21:54:56.2072] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\2"
[08-08-20 21:54:56.2079] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (339) New playback session obtained, resetting sessionErrors count to 0
[08-08-20 21:54:56.2092] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.19757253"
[08-08-20 21:54:56.5158] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x003http://llnw-1166.listen.com/6/6/0/6/7/122476066.rad"
[08-08-20 21:54:56.6240] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode stop from stop
[08-08-20 21:54:56.6244] Slim::Player::Source::playmode (316) Already in playmode stop : ignoring mode change
[08-08-20 21:54:56.6247] Slim::Player::Source::jumpto (1306) Jumping to 2
[08-08-20 21:54:56.6250] Slim::Player::Source::streamingSongIndex (1494) Adding song index 2 to song queue
[08-08-20 21:54:56.6253] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-20 21:54:56.6257] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 2
[08-08-20 21:54:56.6263] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from stop
[08-08-20 21:54:56.6268] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-20 21:54:56.6278] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.19757253.mp3
[08-08-20 21:54:56.6283] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.19757253.mp3]
[08-08-20 21:54:56.6392] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-08-20 21:54:56.6418] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.19757253.mp3 as http://llnw-1166.listen.com/6/6/0/6/7/122476066.rad, let's do it.
[08-08-20 21:54:56.6710] Slim::Player::Squeezebox::stream (1055) setting up direct stream (1150175255:80) autostart: 3.
[08-08-20 21:54:56.6714] Slim::Player::Squeezebox::stream (1056) request string: GET /6/6/0/6/7/122476066.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-1166.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2/22756
Icy-Metadata: 1

[08-08-20 21:54:56.6793] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-08-20 21:54:56.7735] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 73728
[08-08-20 21:54:56.8331] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache/2.2.9
Accept-Ranges: bytes
Content-Length: 6934356
Content-Type: text/plain
Date: Thu, 21 Aug 2008 02:54:56 GMT
Last-Modified: Sat, 02 Aug 2008 14:56:00 GMT
Connection: close

[08-08-20 21:54:56.8336] Slim::Player::Squeezebox2::directHeaders (349) Processing 7 headers
[08-08-20 21:54:56.8341] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Server: Apache/2.2.9
[08-08-20 21:54:56.8350] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-20 21:54:56.8353] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Content-Length: 6934356
[08-08-20 21:54:56.8587] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-20 21:54:56.8592] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-20 21:54:57.1797] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 73728
[08-08-20 21:54:57.5951] Slim::Player::Squeezebox::buffering (303) Buffering... 1237 / 73728
[08-08-20 21:54:57.9128] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-20 21:54:57.9132] Slim::Player::Source::trackStartEvent (1621) Song 2 has now started playing
[08-08-20 21:54:57.9146] Slim::Player::Source::trackStartEvent (1649) Song queue is now 2