[08-08-16 13:22:18.7829] Slim::Player::Source::decoderUnderrun (607) 00:04:20:06:be:04: Decoder underrun while this mode: playout-play
[08-08-16 13:22:18.7945] Slim::Player::Source::nextsong (1700) The next song is number 11, was 10
[08-08-16 13:22:18.7952] Slim::Player::Source::nextsong (1700) The next song is number 11, was 10
[08-08-16 13:22:18.7958] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (372) End of track, logging usage info (218 seconds)...
[08-08-16 13:22:18.7972] Slim::Plugin::RhapsodyDirect::ProtocolHandler::sendLogging (1275) Logging track playback: 218 seconds, trackId: Tra.18935065, stationId: , user: money-shopping@jbdotat.fastmail.fm
[08-08-16 13:22:18.8605] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.18935066"
[08-08-16 13:22:19.1059] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x001http://llnw-901.listen.com/0/1/4/5/2/93925410.rad"
[08-08-16 13:22:19.1415] Slim::Player::Source::skipahead (956) **skipahead: opening next song
[08-08-16 13:22:19.1419] Slim::Player::Source::gotoNext (1350) Opening next song...
[08-08-16 13:22:19.1424] Slim::Player::Source::nextsong (1700) The next song is number 11, was 10
[08-08-16 13:22:19.1530] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-08-16 13:22:19.1534] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-08-16 13:22:19.1537] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-08-16 13:22:19.1540] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-08-16 13:22:19.1547] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-08-16 13:22:19.1550] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-08-16 13:22:19.1553] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-08-16 13:22:19.1556] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-08-16 13:22:19.1575] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-08-16 13:22:19.1578] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-08-16 13:22:19.1581] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-08-16 13:22:19.1584] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-08-16 13:22:19.1587] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-08-16 13:22:19.1590] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-08-16 13:22:19.1593] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-08-16 13:22:19.1596] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-08-16 13:22:19.1599] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-08-16 13:22:19.1602] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-08-16 13:22:19.1605] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-08-16 13:22:19.1608] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-08-16 13:22:19.1611] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-08-16 13:22:19.1614] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-08-16 13:22:19.1617] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-08-16 13:22:19.1620] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-08-16 13:22:19.1623] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-08-16 13:22:19.1626] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-08-16 13:22:19.1629] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-08-16 13:22:19.1632] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-08-16 13:22:19.1981] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-08-16 13:22:19.1985] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-08-16 13:22:19.1989] Slim::Player::Source::streamingSongIndex (1494) Adding song index 11 to song queue
[08-08-16 13:22:19.1992] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 11,10
[08-08-16 13:22:19.1995] Slim::Player::Source::skipahead (962) **skipahead: restarting
[08-08-16 13:22:19.1999] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from playout-play
[08-08-16 13:22:19.2005] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-16 13:22:19.2062] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.18935066.mp3
[08-08-16 13:22:19.2067] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.18935066.mp3]
[08-08-16 13:22:19.2345] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-08-16 13:22:19.2373] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.18935066.mp3 as http://llnw-901.listen.com/0/1/4/5/2/93925410.rad, let's do it.
[08-08-16 13:22:19.2706] Slim::Player::Squeezebox::stream (1055) setting up direct stream (1150175827:80) autostart: 3.
[08-08-16 13:22:19.2710] Slim::Player::Squeezebox::stream (1056) request string: GET /0/1/4/5/2/93925410.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-901.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2/22679
Icy-Metadata: 1

[08-08-16 13:22:19.2784] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-08-16 13:22:19.3058] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (1266) Logging returned: true
[08-08-16 13:22:19.3237] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache/2.0.58
Accept-Ranges: bytes
Content-Length: 3828316
Content-Type: text/plain
Age: 2236
Date: Sat, 16 Aug 2008 18:22:20 GMT
Last-Modified: Fri, 29 Feb 2008 13:33:10 GMT
Connection: close

[08-08-16 13:22:19.3242] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers
[08-08-16 13:22:19.3249] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Server: Apache/2.0.58
[08-08-16 13:22:19.3253] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-16 13:22:19.3256] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Content-Length: 3828316
[08-08-16 13:22:19.3503] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-16 13:22:19.3508] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-16 13:22:28.7507] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-16 13:22:28.7510] Slim::Player::Source::trackStartEvent (1612) Song 10 had already started, so it's not longer in the queue
[08-08-16 13:22:28.7514] Slim::Player::Source::trackStartEvent (1621) Song 11 has now started playing
[08-08-16 13:22:28.7528] Slim::Player::Source::trackStartEvent (1649) Song queue is now 11
[08-08-16 13:24:59.4329] Slim::Player::Source::decoderUnderrun (607) 00:04:20:06:be:04: Decoder underrun while this mode: playout-play
[08-08-16 13:24:59.4333] Slim::Player::Source::nextsong (1700) The next song is number 12, was 11
[08-08-16 13:24:59.4340] Slim::Player::Source::nextsong (1700) The next song is number 12, was 11
[08-08-16 13:24:59.4346] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (372) End of track, logging usage info (160 seconds)...
[08-08-16 13:24:59.4352] Slim::Plugin::RhapsodyDirect::ProtocolHandler::sendLogging (1275) Logging track playback: 160 seconds, trackId: Tra.18935066, stationId: , user: money-shopping@jbdotat.fastmail.fm
[08-08-16 13:24:59.4408] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.18967677"
[08-08-16 13:24:59.7480] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x001http://llnw-996.listen.com/9/6/8/2/3/93832869.rad"
[08-08-16 13:24:59.7924] Slim::Player::Source::skipahead (956) **skipahead: opening next song
[08-08-16 13:24:59.7928] Slim::Player::Source::gotoNext (1350) Opening next song...
[08-08-16 13:24:59.7933] Slim::Player::Source::nextsong (1700) The next song is number 12, was 11
[08-08-16 13:24:59.7942] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-08-16 13:24:59.7946] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-08-16 13:24:59.7949] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-08-16 13:24:59.7952] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-08-16 13:24:59.7956] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-08-16 13:24:59.7959] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-08-16 13:24:59.7962] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-08-16 13:24:59.7965] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-08-16 13:24:59.7969] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-08-16 13:24:59.7972] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-08-16 13:24:59.7975] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-08-16 13:24:59.7978] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-08-16 13:24:59.7981] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-08-16 13:24:59.7984] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-08-16 13:24:59.7987] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-08-16 13:24:59.7990] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-08-16 13:24:59.7995] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-08-16 13:24:59.7998] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-08-16 13:24:59.8001] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-08-16 13:24:59.8004] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-08-16 13:24:59.8007] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-08-16 13:24:59.8010] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-08-16 13:24:59.8013] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-08-16 13:24:59.8016] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-08-16 13:24:59.8020] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-08-16 13:24:59.8023] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-08-16 13:24:59.8026] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-08-16 13:24:59.8029] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-08-16 13:24:59.8371] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-08-16 13:24:59.8375] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-08-16 13:24:59.8378] Slim::Player::Source::streamingSongIndex (1494) Adding song index 12 to song queue
[08-08-16 13:24:59.8381] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 12,11
[08-08-16 13:24:59.8385] Slim::Player::Source::skipahead (962) **skipahead: restarting
[08-08-16 13:24:59.8388] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from playout-play
[08-08-16 13:24:59.8394] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-16 13:24:59.8435] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.18967677.mp3
[08-08-16 13:24:59.8444] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.18967677.mp3]
[08-08-16 13:24:59.8480] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-08-16 13:24:59.8504] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.18967677.mp3 as http://llnw-996.listen.com/9/6/8/2/3/93832869.rad, let's do it.
[08-08-16 13:24:59.9050] Slim::Player::Squeezebox::stream (1055) setting up direct stream (1150175254:80) autostart: 3.
[08-08-16 13:24:59.9054] Slim::Player::Squeezebox::stream (1056) request string: GET /9/6/8/2/3/93832869.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-996.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2/22679
Icy-Metadata: 1

[08-08-16 13:24:59.9131] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-08-16 13:24:59.9620] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache/2.0.58
Accept-Ranges: bytes
Content-Length: 2311068
Content-Type: text/plain
Age: 2238
Date: Sat, 16 Aug 2008 18:25:01 GMT
Last-Modified: Thu, 28 Feb 2008 21:02:26 GMT
Connection: close

[08-08-16 13:24:59.9626] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers
[08-08-16 13:24:59.9629] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Server: Apache/2.0.58
[08-08-16 13:24:59.9632] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-16 13:24:59.9635] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Content-Length: 2311068
[08-08-16 13:24:59.9874] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-16 13:24:59.9878] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-16 13:24:59.9975] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (1266) Logging returned: true
[08-08-16 13:25:12.1653] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-16 13:25:12.1657] Slim::Player::Source::trackStartEvent (1612) Song 11 had already started, so it's not longer in the queue
[08-08-16 13:25:12.1660] Slim::Player::Source::trackStartEvent (1621) Song 12 has now started playing
[08-08-16 13:25:12.1678] Slim::Player::Source::trackStartEvent (1649) Song queue is now 12
[08-08-16 13:26:36.3822] Slim::Player::Source::decoderUnderrun (607) 00:04:20:06:be:04: Decoder underrun while this mode: playout-play
[08-08-16 13:26:36.3827] Slim::Player::Source::nextsong (1700) The next song is number 0, was 12
[08-08-16 13:26:36.3834] Slim::Player::Source::nextsong (1700) The next song is number 0, was 12
[08-08-16 13:26:36.3840] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (372) End of track, logging usage info (96 seconds)...
[08-08-16 13:26:36.3847] Slim::Plugin::RhapsodyDirect::ProtocolHandler::sendLogging (1275) Logging track playback: 96 seconds, trackId: Tra.18967677, stationId: , user: money-shopping@jbdotat.fastmail.fm
[08-08-16 13:26:36.3889] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.18935055"
[08-08-16 13:26:36.6914] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x001http://llnw-905.listen.com/0/5/4/2/3/93832450.rad"
[08-08-16 13:26:37.0557] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (1266) Logging returned: true
[08-08-16 13:26:37.4656] Slim::Player::Source::skipahead (956) **skipahead: opening next song
[08-08-16 13:26:37.4659] Slim::Player::Source::gotoNext (1350) Opening next song...
[08-08-16 13:26:37.4665] Slim::Player::Source::nextsong (1700) The next song is number 0, was 12
[08-08-16 13:26:37.4674] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-08-16 13:26:37.4682] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-08-16 13:26:37.4685] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-08-16 13:26:37.4688] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-08-16 13:26:37.4691] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-08-16 13:26:37.4694] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-08-16 13:26:37.4697] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-08-16 13:26:37.4700] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-08-16 13:26:37.4703] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-08-16 13:26:37.4706] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-08-16 13:26:37.4709] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-08-16 13:26:37.4712] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-08-16 13:26:37.4715] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-08-16 13:26:37.4718] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-08-16 13:26:37.4721] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-08-16 13:26:37.4724] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-08-16 13:26:37.4727] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-08-16 13:26:37.4730] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-08-16 13:26:37.4733] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-08-16 13:26:37.4736] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-08-16 13:26:37.4739] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-08-16 13:26:37.4742] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-08-16 13:26:37.4745] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-08-16 13:26:37.4748] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-08-16 13:26:37.4751] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-08-16 13:26:37.4754] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-08-16 13:26:37.4757] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-08-16 13:26:37.4760] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-08-16 13:26:37.5147] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-08-16 13:26:37.5151] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-08-16 13:26:37.5155] Slim::Player::Source::streamingSongIndex (1494) Adding song index 0 to song queue
[08-08-16 13:26:37.5158] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 0,12
[08-08-16 13:26:37.5161] Slim::Player::Source::skipahead (962) **skipahead: restarting
[08-08-16 13:26:37.5165] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from playout-play
[08-08-16 13:26:37.5170] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-16 13:26:37.5212] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.18935055.mp3
[08-08-16 13:26:37.5217] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.18935055.mp3]
[08-08-16 13:26:37.5253] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-08-16 13:26:37.5276] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.18935055.mp3 as http://llnw-905.listen.com/0/5/4/2/3/93832450.rad, let's do it.
[08-08-16 13:26:38.0783] Slim::Player::Squeezebox::stream (1055) setting up direct stream (3496975666:80) autostart: 3.
[08-08-16 13:26:38.0787] Slim::Player::Squeezebox::stream (1056) request string: GET /0/5/4/2/3/93832450.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-905.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2/22679
Icy-Metadata: 1

[08-08-16 13:26:38.0859] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-08-16 13:26:51.9361] Slim::Player::Source::underrun (722) 00:04:20:06:be:04: Underrun while this mode: playout-play
[08-08-16 13:26:51.9474] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onUnderrun (619) Underrun, stopping, playmode: playout-play
[08-08-16 13:26:51.9479] Slim::Player::Source::nextsong (1700) The next song is number 1, was 0
[08-08-16 13:26:51.9487] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode stop from playout-stop
[08-08-16 13:26:51.9498] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: stop
[08-08-16 13:26:51.9501] Slim::Player::Source::playmode (461) 00:04:20:06:be:04: Stopping and clearing out old chunks
[08-08-16 13:26:51.9517] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-16 13:26:51.9521] Slim::Player::Source::resetSongQueue (1568) Resetting song queue
[08-08-16 13:26:51.9526] Slim::Player::Source::resetSongQueue (1578) Song queue is now 12
[08-08-16 13:26:51.9536] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: stop
[08-08-16 13:26:51.9543] Slim::Player::Source::streamingSongIndex (1494) Adding song index 0 to song queue
[08-08-16 13:26:51.9550] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-16 13:26:51.9554] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 0
[08-08-16 13:26:51.9591] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (653) Skipping to next track (1)
[08-08-16 13:26:51.9602] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (395) Handling command "jump", playmode: stop
[08-08-16 13:26:51.9668] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (486) Ending any previous playback session
[08-08-16 13:26:51.9688] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\6"
[08-08-16 13:26:51.9727] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache/2.0.58
Accept-Ranges: bytes
Content-Length: 3718828
Content-Type: text/plain
Age: 4433
Date: Sat, 16 Aug 2008 18:26:48 GMT
Last-Modified: Thu, 28 Feb 2008 21:02:19 GMT
Connection: close

[08-08-16 13:26:51.9763] Slim::Plugin::RhapsodyDirect::ProtocolHandler::stopCallback (1037) Player stopped (stop ) but another track was already starting, ignoring
[08-08-16 13:26:52.3008] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\6"
[08-08-16 13:26:52.5266] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (259) Found IP for secure-direct.rhapsody.com: 207.188.0.25
[08-08-16 13:26:52.5320] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\0\xCF\xBC\0\31\1\xBB"
[08-08-16 13:26:52.5324] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getPlaybackSession (279) 00:04:20:06:be:04 Requesting new playback session...
[08-08-16 13:26:52.5352] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: 2
[08-08-16 13:26:52.8147] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\2"
[08-08-16 13:26:52.8156] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (339) New playback session obtained, resetting sessionErrors count to 0
[08-08-16 13:26:52.8169] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.18935056"
[08-08-16 13:26:53.3837] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x001http://llnw-947.listen.com/4/7/0/3/3/93833074.rad"
[08-08-16 13:26:54.7107] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode stop from stop
[08-08-16 13:26:54.7111] Slim::Player::Source::playmode (316) Already in playmode stop : ignoring mode change
[08-08-16 13:26:54.7114] Slim::Player::Source::jumpto (1306) Jumping to 1
[08-08-16 13:26:54.7117] Slim::Player::Source::streamingSongIndex (1494) Adding song index 1 to song queue
[08-08-16 13:26:54.7120] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-16 13:26:54.7123] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 1
[08-08-16 13:26:54.7130] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from stop
[08-08-16 13:26:54.7135] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-16 13:26:54.7177] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.18935056.mp3
[08-08-16 13:26:54.7203] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.18935056.mp3]
[08-08-16 13:26:54.7241] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-08-16 13:26:54.7268] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.18935056.mp3 as http://llnw-947.listen.com/4/7/0/3/3/93833074.rad, let's do it.
[08-08-16 13:26:54.8127] Slim::Player::Squeezebox::stream (1055) setting up direct stream (3496975567:80) autostart: 3.
[08-08-16 13:26:54.8131] Slim::Player::Squeezebox::stream (1056) request string: GET /4/7/0/3/3/93833074.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-947.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2/22679
Icy-Metadata: 1

[08-08-16 13:26:54.8201] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-08-16 13:26:54.8563] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 73728
[08-08-16 13:26:54.9024] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache/2.0.58
Accept-Ranges: bytes
Content-Length: 3586324
Content-Type: text/plain
Age: 4292
Date: Sat, 16 Aug 2008 18:26:56 GMT
Last-Modified: Thu, 28 Feb 2008 22:00:56 GMT
Connection: close

[08-08-16 13:26:54.9030] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers
[08-08-16 13:26:54.9033] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Server: Apache/2.0.58
[08-08-16 13:26:54.9037] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-16 13:26:54.9040] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Content-Length: 3586324
[08-08-16 13:26:54.9267] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-16 13:26:54.9272] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-16 13:26:55.2631] Slim::Player::Squeezebox::buffering (303) Buffering... 1225 / 73728
[08-08-16 13:26:55.5476] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-16 13:26:55.5480] Slim::Player::Source::trackStartEvent (1621) Song 1 has now started playing
[08-08-16 13:26:55.5495] Slim::Player::Source::trackStartEvent (1649) Song queue is now 1
[08-08-16 13:29:16.0903] Slim::Player::Source::decoderUnderrun (607) 00:04:20:06:be:04: Decoder underrun while this mode: playout-play
[08-08-16 13:29:16.0908] Slim::Player::Source::nextsong (1700) The next song is number 2, was 1
[08-08-16 13:29:16.0914] Slim::Player::Source::nextsong (1700) The next song is number 2, was 1
[08-08-16 13:29:16.0920] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (372) End of track, logging usage info (150 seconds)...
[08-08-16 13:29:16.0934] Slim::Plugin::RhapsodyDirect::ProtocolHandler::sendLogging (1275) Logging track playback: 150 seconds, trackId: Tra.18935056, stationId: , user: money-shopping@jbdotat.fastmail.fm
[08-08-16 13:29:16.0976] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.18935057"
[08-08-16 13:29:16.3986] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x001http://llnw-915.listen.com/1/5/0/3/3/93833051.rad"
[08-08-16 13:29:16.4356] Slim::Player::Source::skipahead (956) **skipahead: opening next song
[08-08-16 13:29:16.4360] Slim::Player::Source::gotoNext (1350) Opening next song...
[08-08-16 13:29:16.4365] Slim::Player::Source::nextsong (1700) The next song is number 2, was 1
[08-08-16 13:29:16.4381] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-08-16 13:29:16.4385] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-08-16 13:29:16.4391] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-08-16 13:29:16.4394] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-08-16 13:29:16.4397] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-08-16 13:29:16.4400] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-08-16 13:29:16.4403] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-08-16 13:29:16.4406] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-08-16 13:29:16.4409] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-08-16 13:29:16.4412] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-08-16 13:29:16.4415] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-08-16 13:29:16.4418] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-08-16 13:29:16.4421] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-08-16 13:29:16.4424] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-08-16 13:29:16.4427] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-08-16 13:29:16.4430] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-08-16 13:29:16.4433] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-08-16 13:29:16.4436] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-08-16 13:29:16.4439] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-08-16 13:29:16.4442] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-08-16 13:29:16.4445] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-08-16 13:29:16.4449] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-08-16 13:29:16.4452] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-08-16 13:29:16.4455] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-08-16 13:29:16.4458] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-08-16 13:29:16.4461] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-08-16 13:29:16.4464] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-08-16 13:29:16.4467] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-08-16 13:29:16.4804] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-08-16 13:29:16.4809] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-08-16 13:29:16.4812] Slim::Player::Source::streamingSongIndex (1494) Adding song index 2 to song queue
[08-08-16 13:29:16.4815] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 2,1
[08-08-16 13:29:16.4819] Slim::Player::Source::skipahead (962) **skipahead: restarting
[08-08-16 13:29:16.4822] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from playout-play
[08-08-16 13:29:16.4827] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-16 13:29:16.4875] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.18935057.mp3
[08-08-16 13:29:16.4881] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.18935057.mp3]
[08-08-16 13:29:16.4917] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-08-16 13:29:16.4941] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.18935057.mp3 as http://llnw-915.listen.com/1/5/0/3/3/93833051.rad, let's do it.
[08-08-16 13:29:16.5244] Slim::Player::Squeezebox::stream (1055) setting up direct stream (3496975633:80) autostart: 3.
[08-08-16 13:29:16.5248] Slim::Player::Squeezebox::stream (1056) request string: GET /1/5/0/3/3/93833051.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-915.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2/22679
Icy-Metadata: 1

[08-08-16 13:29:16.5328] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-08-16 13:29:16.5979] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache/2.0.58
Accept-Ranges: bytes
Content-Length: 5018972
Content-Type: text/plain
Age: 4283
Date: Sat, 16 Aug 2008 18:29:18 GMT
Last-Modified: Thu, 28 Feb 2008 21:32:21 GMT
Connection: close

[08-08-16 13:29:16.5985] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers
[08-08-16 13:29:16.5989] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Server: Apache/2.0.58
[08-08-16 13:29:16.5992] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-16 13:29:16.5995] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Content-Length: 5018972
[08-08-16 13:29:16.6231] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-16 13:29:16.6236] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-16 13:29:16.7430] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (1266) Logging returned: true
[08-08-16 13:29:26.0557] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-16 13:29:26.0561] Slim::Player::Source::trackStartEvent (1612) Song 1 had already started, so it's not longer in the queue
[08-08-16 13:29:26.0564] Slim::Player::Source::trackStartEvent (1621) Song 2 has now started playing
[08-08-16 13:29:26.0580] Slim::Player::Source::trackStartEvent (1649) Song queue is now 2
[08-08-16 13:29:26.4299] Slim::Player::Source::outputUnderrun (800) 00:04:20:06:be:04: Output buffer underrun (decoder: 2560457 / output: 0)
[08-08-16 13:29:26.4632] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pause from playout-play
[08-08-16 13:29:26.4644] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pause
[08-08-16 13:29:26.5115] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pause
[08-08-16 13:29:27.5538] Slim::Player::Source::rebuffer (900) Rebuffering: 2707885 / 120000
[08-08-16 13:29:27.5542] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-08-16 13:29:27.5557] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-08-16 13:29:27.5570] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: playout-play
[08-08-16 13:29:27.5601] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-08-16 13:29:35.8310] Slim::Player::Source::outputUnderrun (800) 00:04:20:06:be:04: Output buffer underrun (decoder: 3142673 / output: 0)
[08-08-16 13:29:35.8314] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode pause from playout-play
[08-08-16 13:29:35.8326] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: pause
[08-08-16 13:29:35.8340] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: pause
[08-08-16 13:29:36.8451] Slim::Player::Source::rebuffer (900) Rebuffering: 3142765 / 120000
[08-08-16 13:29:36.8456] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from pause
[08-08-16 13:29:36.8466] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: resume
[08-08-16 13:29:36.8478] Slim::Player::Source::playmode (430) 00:04:20:06:be:04: Resume, resetting mode: playout-play
[08-08-16 13:29:36.8494] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: resume
[08-08-16 13:29:51.7195] Slim::Player::Source::decoderUnderrun (607) 00:04:20:06:be:04: Decoder underrun while this mode: playout-play
[08-08-16 13:29:51.7200] Slim::Player::Source::nextsong (1700) The next song is number 3, was 2
[08-08-16 13:29:51.7206] Slim::Player::Source::nextsong (1700) The next song is number 3, was 2
[08-08-16 13:29:51.7211] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (372) End of track, logging usage info (210 seconds)...
[08-08-16 13:29:51.7218] Slim::Plugin::RhapsodyDirect::ProtocolHandler::sendLogging (1275) Logging track playback: 210 seconds, trackId: Tra.18935057, stationId: , user: money-shopping@jbdotat.fastmail.fm
[08-08-16 13:29:51.7256] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.18935058"
[08-08-16 13:29:51.7265] Slim::Player::Source::underrun (722) 00:04:20:06:be:04: Underrun while this mode: playout-play
[08-08-16 13:29:51.7271] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onUnderrun (619) Underrun, stopping, playmode: playout-play
[08-08-16 13:29:51.7275] Slim::Player::Source::nextsong (1700) The next song is number 3, was 2
[08-08-16 13:29:51.7279] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode stop from playout-stop
[08-08-16 13:29:51.7291] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: stop
[08-08-16 13:29:51.7295] Slim::Player::Source::playmode (461) 00:04:20:06:be:04: Stopping and clearing out old chunks
[08-08-16 13:29:51.7309] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-16 13:29:51.7313] Slim::Player::Source::resetSongQueue (1568) Resetting song queue
[08-08-16 13:29:51.7316] Slim::Player::Source::resetSongQueue (1578) Song queue is now 2
[08-08-16 13:29:51.7324] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: stop
[08-08-16 13:29:51.7327] Slim::Player::Source::streamingSongIndex (1494) Adding song index 0 to song queue
[08-08-16 13:29:51.7330] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-16 13:29:51.7333] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 0
[08-08-16 13:29:51.7365] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (653) Skipping to next track (3)
[08-08-16 13:29:51.7375] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (395) Handling command "jump", playmode: stop
[08-08-16 13:29:51.7437] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (486) Ending any previous playback session
[08-08-16 13:29:51.7453] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\6"
[08-08-16 13:29:51.7479] Slim::Plugin::RhapsodyDirect::ProtocolHandler::stopCallback (1037) Player stopped (stop ) but another track was already starting, ignoring
[08-08-16 13:29:52.4399] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (1266) Logging returned: true
[08-08-16 13:29:56.0289] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\xFC"
[08-08-16 13:29:56.0297] 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-16 13:29:58.0322] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (353) 00:04:20:06:be:04 Re-sending RPDS packet: "\6"
[08-08-16 13:29:58.0335] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\6"
[08-08-16 13:30:02.5541] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\xFC"
[08-08-16 13:30:02.5551] 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-16 13:30:03.3413] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x001http://llnw-964.listen.com/6/4/3/3/3/93833346.rad"
[08-08-16 13:30:03.3419] 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-16 13:30:04.5632] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (353) 00:04:20:06:be:04 Re-sending RPDS packet: "\6"
[08-08-16 13:30:04.5647] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\6"
[08-08-16 13:30:04.9107] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\6"
[08-08-16 13:30:04.9116] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (259) Found IP for secure-direct.rhapsody.com: 207.188.0.25
[08-08-16 13:30:04.9162] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\0\xCF\xBC\0\31\1\xBB"
[08-08-16 13:30:04.9166] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getPlaybackSession (279) 00:04:20:06:be:04 Requesting new playback session...
[08-08-16 13:30:04.9224] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: 2
[08-08-16 13:30:05.2116] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\2"
[08-08-16 13:30:05.2123] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (339) New playback session obtained, resetting sessionErrors count to 0
[08-08-16 13:30:05.2136] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.18935058"
[08-08-16 13:30:05.6721] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x001http://llnw-964.listen.com/6/4/3/3/3/93833346.rad"
[08-08-16 13:30:06.2630] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode stop from stop
[08-08-16 13:30:06.2634] Slim::Player::Source::playmode (316) Already in playmode stop : ignoring mode change
[08-08-16 13:30:06.2637] Slim::Player::Source::jumpto (1306) Jumping to 3
[08-08-16 13:30:06.2640] Slim::Player::Source::streamingSongIndex (1494) Adding song index 3 to song queue
[08-08-16 13:30:06.2643] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-16 13:30:06.2647] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 3
[08-08-16 13:30:06.2653] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from stop
[08-08-16 13:30:06.2659] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-16 13:30:06.2670] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.18935058.mp3
[08-08-16 13:30:06.2675] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.18935058.mp3]
[08-08-16 13:30:06.2713] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-08-16 13:30:06.2741] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.18935058.mp3 as http://llnw-964.listen.com/6/4/3/3/3/93833346.rad, let's do it.
[08-08-16 13:30:06.8752] Slim::Player::Squeezebox::stream (1055) setting up direct stream (3496975731:80) autostart: 3.
[08-08-16 13:30:06.8756] Slim::Player::Squeezebox::stream (1056) request string: GET /6/4/3/3/3/93833346.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-964.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2/22679
Icy-Metadata: 1

[08-08-16 13:30:06.8829] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-08-16 13:30:06.8930] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 73728
[08-08-16 13:30:06.9428] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache/2.0.58
Accept-Ranges: bytes
Content-Length: 4363924
Content-Type: text/plain
Age: 4120
Date: Sat, 16 Aug 2008 18:30:08 GMT
Last-Modified: Thu, 28 Feb 2008 22:01:00 GMT
Connection: close

[08-08-16 13:30:06.9433] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers
[08-08-16 13:30:06.9437] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Server: Apache/2.0.58
[08-08-16 13:30:06.9440] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-16 13:30:06.9444] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Content-Length: 4363924
[08-08-16 13:30:06.9676] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-16 13:30:06.9681] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-16 13:30:07.3121] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 73728
[08-08-16 13:30:07.5788] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-16 13:30:12.1250] Slim::Player::Source::trackStartEvent (1621) Song 3 has now started playing
[08-08-16 13:30:12.1265] Slim::Player::Source::trackStartEvent (1649) Song queue is now 3
[08-08-16 13:33:00.8036] Slim::Player::Source::decoderUnderrun (607) 00:04:20:06:be:04: Decoder underrun while this mode: playout-play
[08-08-16 13:33:00.8041] Slim::Player::Source::nextsong (1700) The next song is number 4, was 3
[08-08-16 13:33:00.8048] Slim::Player::Source::nextsong (1700) The next song is number 4, was 3
[08-08-16 13:33:00.8054] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (372) End of track, logging usage info (183 seconds)...
[08-08-16 13:33:00.8060] Slim::Plugin::RhapsodyDirect::ProtocolHandler::sendLogging (1275) Logging track playback: 183 seconds, trackId: Tra.18935058, stationId: , user: money-shopping@jbdotat.fastmail.fm
[08-08-16 13:33:00.8138] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:06:be:04 RPDS packet sent: "\3\fTra.18935059"
[08-08-16 13:33:01.1172] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:06:be:04 Got RPDS packet: \"\3\x001http://llnw-957.listen.com/5/7/2/3/3/93833275.rad"
[08-08-16 13:33:01.2327] Slim::Player::Source::skipahead (956) **skipahead: opening next song
[08-08-16 13:33:01.2331] Slim::Player::Source::gotoNext (1350) Opening next song...
[08-08-16 13:33:01.2335] Slim::Player::Source::nextsong (1700) The next song is number 4, was 3
[08-08-16 13:33:01.2350] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:06:be:04
[08-08-16 13:33:01.2354] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:06:be:04
[08-08-16 13:33:01.2357] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:06:be:04
[08-08-16 13:33:01.2360] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-08-16 13:33:01.2363] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-08-16 13:33:01.2366] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:06:be:04
[08-08-16 13:33:01.2370] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:06:be:04
[08-08-16 13:33:01.2372] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-08-16 13:33:01.2375] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-08-16 13:33:01.2378] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:06:be:04
[08-08-16 13:33:01.2382] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:06:be:04
[08-08-16 13:33:01.2385] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-08-16 13:33:01.2388] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-08-16 13:33:01.2391] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:06:be:04
[08-08-16 13:33:01.2394] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:06:be:04
[08-08-16 13:33:01.2397] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-08-16 13:33:01.2400] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-08-16 13:33:01.2403] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:06:be:04
[08-08-16 13:33:01.2406] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:06:be:04
[08-08-16 13:33:01.2409] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-08-16 13:33:01.2412] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-08-16 13:33:01.2415] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:06:be:04
[08-08-16 13:33:01.2418] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:06:be:04
[08-08-16 13:33:01.2421] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-08-16 13:33:01.2423] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-08-16 13:33:01.2427] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-08-16 13:33:01.2430] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-08-16 13:33:01.2433] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-08-16 13:33:01.2778] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-08-16 13:33:01.2782] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-08-16 13:33:01.2786] Slim::Player::Source::streamingSongIndex (1494) Adding song index 4 to song queue
[08-08-16 13:33:01.2789] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 4,3
[08-08-16 13:33:01.2792] Slim::Player::Source::skipahead (962) **skipahead: restarting
[08-08-16 13:33:01.2796] Slim::Player::Source::playmode (309) 00:04:20:06:be:04: Switching to mode play from playout-play
[08-08-16 13:33:01.2801] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-16 13:33:01.2843] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.18935059.mp3
[08-08-16 13:33:01.2849] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.18935059.mp3]
[08-08-16 13:33:01.2885] Slim::Player::Source::playmode (412) 00:04:20:06:be:04 New play mode: play
[08-08-16 13:33:01.2913] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.18935059.mp3 as http://llnw-957.listen.com/5/7/2/3/3/93833275.rad, let's do it.
[08-08-16 13:33:01.3234] Slim::Player::Squeezebox::stream (1055) setting up direct stream (1150175852:80) autostart: 3.
[08-08-16 13:33:01.3238] Slim::Player::Squeezebox::stream (1056) request string: GET /5/7/2/3/3/93833275.rad HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: */*
Host: llnw-957.listen.com
User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.2/22679
Icy-Metadata: 1

[08-08-16 13:33:01.3317] Slim::Player::Source::playmode (581) 00:04:20:06:be:04: Current playmode: play
[08-08-16 13:33:01.3752] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK
Server: Apache/2.0.58
Accept-Ranges: bytes
Content-Length: 4058484
Content-Type: text/plain
Age: 145670
Date: Sat, 16 Aug 2008 18:33:02 GMT
Last-Modified: Thu, 28 Feb 2008 22:00:59 GMT
Connection: close

[08-08-16 13:33:01.3757] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers
[08-08-16 13:33:01.3761] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Server: Apache/2.0.58
[08-08-16 13:33:01.3764] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-16 13:33:01.3767] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (66) RhapsodyDirect header: Content-Length: 4058484
[08-08-16 13:33:01.4003] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-16 13:33:01.4009] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-16 13:33:01.4650] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (1266) Logging returned: true
[08-08-16 13:33:13.1731] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-16 13:33:13.1735] Slim::Player::Source::trackStartEvent (1612) Song 3 had already started, so it's not longer in the queue
[08-08-16 13:33:13.1738] Slim::Player::Source::trackStartEvent (1621) Song 4 has now started playing
[08-08-16 13:33:13.1756] Slim::Player::Source::trackStartEvent (1649) Song queue is now 4