[08-08-15 09:15:13.3698] Slim::Player::Source::decoderUnderrun (607) 00:04:20:12:00:01: Decoder underrun while this mode: playout-play
[08-08-15 09:15:13.3703] Slim::Player::Source::nextsong (1700) The next song is number 8, was 7
[08-08-15 09:15:13.3708] Slim::Player::Source::nextsong (1700) The next song is number 8, was 7
[08-08-15 09:15:13.3713] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (380) End of track, logging usage info (241 seconds)...
[08-08-15 09:15:13.3727] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\4\x03241"
[08-08-15 09:15:14.5590] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\4"
[08-08-15 09:15:14.5626] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\3\fTra.12202871"
[08-08-15 09:15:15.4459] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\3\x001http://llnw-675.listen.com/7/5/2/1/7/64571257.rad"
[08-08-15 09:15:15.7632] Slim::Player::Source::skipahead (956) **skipahead: opening next song
[08-08-15 09:15:15.7636] Slim::Player::Source::gotoNext (1350) Opening next song...
[08-08-15 09:15:15.7640] Slim::Player::Source::nextsong (1700) The next song is number 8, was 7
[08-08-15 09:15:15.7821] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:12:00:01
[08-08-15 09:15:15.7825] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:12:00:01
[08-08-15 09:15:15.7829] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:12:00:01
[08-08-15 09:15:15.7832] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-08-15 09:15:15.7835] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-08-15 09:15:15.7838] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:12:00:01
[08-08-15 09:15:15.7996] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:12:00:01
[08-08-15 09:15:15.7999] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-08-15 09:15:15.8002] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-08-15 09:15:15.8005] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:12:00:01
[08-08-15 09:15:15.8008] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:12:00:01
[08-08-15 09:15:15.8011] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-08-15 09:15:15.8196] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-08-15 09:15:15.8200] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:12:00:01
[08-08-15 09:15:15.8203] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:12:00:01
[08-08-15 09:15:15.8206] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-08-15 09:15:15.8208] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-08-15 09:15:15.8211] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:12:00:01
[08-08-15 09:15:15.8497] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:12:00:01
[08-08-15 09:15:15.8501] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-08-15 09:15:15.8504] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-08-15 09:15:15.8507] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:12:00:01
[08-08-15 09:15:15.8511] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:12:00:01
[08-08-15 09:15:15.8514] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-08-15 09:15:15.8700] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-08-15 09:15:15.8703] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-08-15 09:15:15.8706] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-08-15 09:15:15.8709] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-08-15 09:15:16.4738] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-08-15 09:15:16.4742] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-08-15 09:15:16.4745] Slim::Player::Source::streamingSongIndex (1494) Adding song index 8 to song queue
[08-08-15 09:15:16.4748] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 8,7
[08-08-15 09:15:16.4752] Slim::Player::Source::skipahead (962) **skipahead: restarting
[08-08-15 09:15:16.4755] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from playout-play
[08-08-15 09:15:16.4760] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-15 09:15:16.4821] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.12202871.mp3
[08-08-15 09:15:16.4827] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.12202871.mp3]
[08-08-15 09:15:16.4873] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: play
[08-08-15 09:15:16.4895] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.12202871.mp3 as http://llnw-675.listen.com/7/5/2/1/7/64571257.rad, let's do it.
[08-08-15 09:15:16.8000] Slim::Player::Squeezebox::stream (1055) setting up direct stream (3496968531:80) autostart: 3.
[08-08-15 09:15:16.8004] Slim::Player::Squeezebox::stream (1056) request string: GET /7/5/2/1/7/64571257.rad HTTP/1.0

Cache-Control: no-cache

Connection: close

Accept: */*

Host: llnw-675.listen.com

User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.2/22618

Icy-Metadata: 1



[08-08-15 09:15:16.8834] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: play
[08-08-15 09:15:17.2722] 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: 4297988

Content-Type: text/plain

Age: 226367

Date: Fri, 15 Aug 2008 15:15:17 GMT

Last-Modified: Sun, 18 Mar 2007 14:33:35 GMT

Connection: close



[08-08-15 09:15:17.2727] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers
[08-08-15 09:15:17.2730] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Server: Apache/2.0.58
[08-08-15 09:15:17.2733] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-15 09:15:17.2736] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Content-Length: 4297988
[08-08-15 09:15:17.3356] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-15 09:15:17.3361] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-15 09:15:23.3401] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-15 09:15:23.3405] Slim::Player::Source::trackStartEvent (1612) Song 7 had already started, so it's not longer in the queue
[08-08-15 09:15:23.3408] Slim::Player::Source::trackStartEvent (1621) Song 8 has now started playing
[08-08-15 09:15:23.3421] Slim::Player::Source::trackStartEvent (1649) Song queue is now 8
[08-08-15 09:18:13.7567] Slim::Player::Source::decoderUnderrun (607) 00:04:20:12:00:01: Decoder underrun while this mode: playout-play
[08-08-15 09:18:13.7578] Slim::Player::Source::nextsong (1700) The next song is number 9, was 8
[08-08-15 09:18:13.7591] Slim::Player::Source::nextsong (1700) The next song is number 9, was 8
[08-08-15 09:18:13.7605] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (380) End of track, logging usage info (180 seconds)...
[08-08-15 09:18:13.7635] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\4\x03180"
[08-08-15 09:18:14.9355] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\4"
[08-08-15 09:18:14.9387] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\3\13Tra.7396570"
[08-08-15 09:18:15.8232] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\3\x001http://llnw-654.listen.com/5/4/9/5/6/64665945.rad"
[08-08-15 09:18:16.0941] Slim::Player::Source::skipahead (956) **skipahead: opening next song
[08-08-15 09:18:16.0952] Slim::Player::Source::gotoNext (1350) Opening next song...
[08-08-15 09:18:16.0962] Slim::Player::Source::nextsong (1700) The next song is number 9, was 8
[08-08-15 09:18:16.0980] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:12:00:01
[08-08-15 09:18:16.0991] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:12:00:01
[08-08-15 09:18:16.0999] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:12:00:01
[08-08-15 09:18:16.1007] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-08-15 09:18:16.1015] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-08-15 09:18:16.1023] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:12:00:01
[08-08-15 09:18:16.1031] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:12:00:01
[08-08-15 09:18:16.1039] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-08-15 09:18:16.1047] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-08-15 09:18:16.1055] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:12:00:01
[08-08-15 09:18:16.1063] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:12:00:01
[08-08-15 09:18:16.1070] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-08-15 09:18:16.1078] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-08-15 09:18:16.1086] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:12:00:01
[08-08-15 09:18:16.1097] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:12:00:01
[08-08-15 09:18:16.1105] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-08-15 09:18:16.1113] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-08-15 09:18:16.1121] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:12:00:01
[08-08-15 09:18:16.1129] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:12:00:01
[08-08-15 09:18:16.1136] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-08-15 09:18:16.1144] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-08-15 09:18:16.1152] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:12:00:01
[08-08-15 09:18:16.1160] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:12:00:01
[08-08-15 09:18:16.1168] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-08-15 09:18:16.1176] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-08-15 09:18:16.1184] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-08-15 09:18:16.1194] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-08-15 09:18:16.1202] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-08-15 09:18:16.4105] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-08-15 09:18:16.4109] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-08-15 09:18:16.4112] Slim::Player::Source::streamingSongIndex (1494) Adding song index 9 to song queue
[08-08-15 09:18:16.4115] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 9,8
[08-08-15 09:18:16.4118] Slim::Player::Source::skipahead (962) **skipahead: restarting
[08-08-15 09:18:16.4121] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from playout-play
[08-08-15 09:18:16.4126] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-15 09:18:16.4184] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.7396570.mp3
[08-08-15 09:18:16.4189] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.7396570.mp3]
[08-08-15 09:18:16.4237] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: play
[08-08-15 09:18:16.4255] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.7396570.mp3 as http://llnw-654.listen.com/5/4/9/5/6/64665945.rad, let's do it.
[08-08-15 09:18:16.7247] Slim::Player::Squeezebox::stream (1055) setting up direct stream (1159502554:80) autostart: 3.
[08-08-15 09:18:16.7252] Slim::Player::Squeezebox::stream (1056) request string: GET /5/4/9/5/6/64665945.rad HTTP/1.0

Cache-Control: no-cache

Connection: close

Accept: */*

Host: llnw-654.listen.com

User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.2/22618

Icy-Metadata: 1



[08-08-15 09:18:16.7561] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: play
[08-08-15 09:18:17.2467] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK

Date: Fri, 15 Aug 2008 15:18:17 GMT

Server: Apache/2.0.58

Last-Modified: Tue, 20 Mar 2007 04:08:23 GMT

Accept-Ranges: bytes

Content-Length: 5828300

Content-Type: text/plain

Connection: close



[08-08-15 09:18:17.2478] Slim::Player::Squeezebox2::directHeaders (349) Processing 7 headers
[08-08-15 09:18:17.2487] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Date: Fri, 15 Aug 2008 15:18:17 GMT
[08-08-15 09:18:17.2498] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Server: Apache/2.0.58
[08-08-15 09:18:17.2506] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Last-Modified: Tue, 20 Mar 2007 04:08:23 GMT
[08-08-15 09:18:17.2514] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-15 09:18:17.2522] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Content-Length: 5828300
[08-08-15 09:18:17.3207] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-15 09:18:17.3218] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-15 09:18:23.7161] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-15 09:18:23.7173] Slim::Player::Source::trackStartEvent (1612) Song 8 had already started, so it's not longer in the queue
[08-08-15 09:18:23.7181] Slim::Player::Source::trackStartEvent (1621) Song 9 has now started playing
[08-08-15 09:18:23.7222] Slim::Player::Source::trackStartEvent (1649) Song queue is now 9
[08-08-15 09:22:18.4099] Slim::Player::Source::decoderUnderrun (607) 00:04:20:12:00:01: Decoder underrun while this mode: playout-play
[08-08-15 09:22:18.4107] Slim::Player::Source::nextsong (1700) The next song is number 10, was 9
[08-08-15 09:22:18.4118] Slim::Player::Source::nextsong (1700) The next song is number 10, was 9
[08-08-15 09:22:18.4130] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (380) End of track, logging usage info (244 seconds)...
[08-08-15 09:22:18.4153] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\4\x03244"
[08-08-15 09:22:19.4847] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\4"
[08-08-15 09:22:19.4861] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\3\fTra.10627855"
[08-08-15 09:22:20.3011] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\3\x001http://llnw-698.listen.com/9/8/8/7/5/64557889.rad"
[08-08-15 09:22:20.5680] Slim::Player::Source::skipahead (956) **skipahead: opening next song
[08-08-15 09:22:20.5908] Slim::Player::Source::gotoNext (1350) Opening next song...
[08-08-15 09:22:20.5920] Slim::Player::Source::nextsong (1700) The next song is number 10, was 9
[08-08-15 09:22:20.5940] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:12:00:01
[08-08-15 09:22:20.5950] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:12:00:01
[08-08-15 09:22:20.5958] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:12:00:01
[08-08-15 09:22:20.5965] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-08-15 09:22:20.5977] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-08-15 09:22:20.5985] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:12:00:01
[08-08-15 09:22:20.5993] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:12:00:01
[08-08-15 09:22:20.6160] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-08-15 09:22:20.6171] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-08-15 09:22:20.6179] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:12:00:01
[08-08-15 09:22:20.6188] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:12:00:01
[08-08-15 09:22:20.6196] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-08-15 09:22:20.6204] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-08-15 09:22:20.6212] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:12:00:01
[08-08-15 09:22:20.6220] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:12:00:01
[08-08-15 09:22:20.6228] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-08-15 09:22:20.6236] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-08-15 09:22:20.6244] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:12:00:01
[08-08-15 09:22:20.6252] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:12:00:01
[08-08-15 09:22:20.6260] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-08-15 09:22:20.6268] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-08-15 09:22:20.6276] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:12:00:01
[08-08-15 09:22:20.6284] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:12:00:01
[08-08-15 09:22:20.6292] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-08-15 09:22:20.6300] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-08-15 09:22:20.6308] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-08-15 09:22:20.6316] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-08-15 09:22:20.6324] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-08-15 09:22:20.9573] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-08-15 09:22:20.9577] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-08-15 09:22:20.9583] Slim::Player::Source::streamingSongIndex (1494) Adding song index 10 to song queue
[08-08-15 09:22:20.9586] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 10,9
[08-08-15 09:22:20.9589] Slim::Player::Source::skipahead (962) **skipahead: restarting
[08-08-15 09:22:20.9592] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from playout-play
[08-08-15 09:22:20.9597] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-15 09:22:20.9657] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.10627855.mp3
[08-08-15 09:22:20.9662] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.10627855.mp3]
[08-08-15 09:22:20.9708] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: play
[08-08-15 09:22:20.9731] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.10627855.mp3 as http://llnw-698.listen.com/9/8/8/7/5/64557889.rad, let's do it.
[08-08-15 09:22:21.4894] Slim::Player::Squeezebox::stream (1055) setting up direct stream (3496968228:80) autostart: 3.
[08-08-15 09:22:21.4903] Slim::Player::Squeezebox::stream (1056) request string: GET /9/8/8/7/5/64557889.rad HTTP/1.0

Cache-Control: no-cache

Connection: close

Accept: */*

Host: llnw-698.listen.com

User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.2/22618

Icy-Metadata: 1



[08-08-15 09:22:21.6606] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: play
[08-08-15 09:22:22.0127] 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: 6436692

Content-Type: text/plain

Age: 226367

Date: Fri, 15 Aug 2008 15:22:22 GMT

Last-Modified: Sun, 18 Mar 2007 08:38:58 GMT

Connection: close



[08-08-15 09:22:22.0132] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers
[08-08-15 09:22:22.0135] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Server: Apache/2.0.58
[08-08-15 09:22:22.0138] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-15 09:22:22.0141] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Content-Length: 6436692
[08-08-15 09:22:22.0906] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-15 09:22:22.0911] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-15 09:22:28.4058] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-15 09:22:28.4068] Slim::Player::Source::trackStartEvent (1612) Song 9 had already started, so it's not longer in the queue
[08-08-15 09:22:28.4076] Slim::Player::Source::trackStartEvent (1621) Song 10 has now started playing
[08-08-15 09:22:28.4120] Slim::Player::Source::trackStartEvent (1649) Song queue is now 10
[08-08-15 09:22:28.5699] Slim::Player::Source::outputUnderrun (800) 00:04:20:12:00:01: Output buffer underrun (decoder: 7 / output: 0)
[08-08-15 09:22:28.5703] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode pause from playout-play
[08-08-15 09:22:28.5712] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: pause
[08-08-15 09:22:28.5725] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: pause
[08-08-15 09:22:29.5904] Slim::Player::Source::rebuffer (900) Rebuffering: 3 / 120000
[08-08-15 09:22:30.6056] Slim::Player::Source::rebuffer (900) Rebuffering: 7 / 120000
[08-08-15 09:22:31.6146] Slim::Player::Source::rebuffer (900) Rebuffering: 33403 / 120000
[08-08-15 09:22:32.6269] Slim::Player::Source::rebuffer (900) Rebuffering: 36307 / 120000
[08-08-15 09:22:33.6382] Slim::Player::Source::rebuffer (900) Rebuffering: 74059 / 120000
[08-08-15 09:22:34.6532] Slim::Player::Source::rebuffer (900) Rebuffering: 91483 / 120000
[08-08-15 09:22:35.6712] Slim::Player::Source::rebuffer (900) Rebuffering: 113263 / 120000
[08-08-15 09:22:36.6814] Slim::Player::Source::rebuffer (900) Rebuffering: 133591 / 120000
[08-08-15 09:22:36.6824] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from pause
[08-08-15 09:22:36.6844] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: resume
[08-08-15 09:22:36.6914] Slim::Player::Source::playmode (430) 00:04:20:12:00:01: Resume, resetting mode: playout-play
[08-08-15 09:22:36.7217] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: resume
[08-08-15 09:22:37.8159] Slim::Player::Source::outputUnderrun (800) 00:04:20:12:00:01: Output buffer underrun (decoder: 120031 / output: 0)
[08-08-15 09:22:37.8168] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode pause from playout-play
[08-08-15 09:22:37.8188] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: pause
[08-08-15 09:22:37.8214] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: pause
[08-08-15 09:22:38.8388] Slim::Player::Source::rebuffer (900) Rebuffering: 85247 / 120000
[08-08-15 09:22:39.8562] Slim::Player::Source::rebuffer (900) Rebuffering: 93543 / 120000
[08-08-15 09:22:40.8702] Slim::Player::Source::rebuffer (900) Rebuffering: 113871 / 120000
[08-08-15 09:22:41.8830] Slim::Player::Source::rebuffer (900) Rebuffering: 126939 / 120000
[08-08-15 09:22:41.8840] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from pause
[08-08-15 09:22:41.8860] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: resume
[08-08-15 09:22:41.9124] Slim::Player::Source::playmode (430) 00:04:20:12:00:01: Resume, resetting mode: playout-play
[08-08-15 09:22:41.9216] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: resume
[08-08-15 09:22:55.3498] Slim::Player::Source::outputUnderrun (800) 00:04:20:12:00:01: Output buffer underrun (decoder: 3251 / output: 0)
[08-08-15 09:22:55.3505] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode pause from playout-play
[08-08-15 09:22:55.3525] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: pause
[08-08-15 09:22:55.3545] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: pause
[08-08-15 09:22:56.3688] Slim::Player::Source::rebuffer (900) Rebuffering: 3 / 120000
[08-08-15 09:22:57.3752] Slim::Player::Source::rebuffer (900) Rebuffering: 34851 / 120000
[08-08-15 09:22:58.3875] Slim::Player::Source::rebuffer (900) Rebuffering: 119067 / 120000
[08-08-15 09:22:59.3955] Slim::Player::Source::rebuffer (900) Rebuffering: 136491 / 120000
[08-08-15 09:22:59.3959] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from pause
[08-08-15 09:22:59.4006] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: resume
[08-08-15 09:22:59.4019] Slim::Player::Source::playmode (430) 00:04:20:12:00:01: Resume, resetting mode: playout-play
[08-08-15 09:22:59.4052] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: resume
[08-08-15 09:23:44.5500] Slim::Player::Source::outputUnderrun (800) 00:04:20:12:00:01: Output buffer underrun (decoder: 7 / output: 0)
[08-08-15 09:23:44.5504] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode pause from playout-play
[08-08-15 09:23:44.5513] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: pause
[08-08-15 09:23:44.5523] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: pause
[08-08-15 09:23:45.5591] Slim::Player::Source::rebuffer (900) Rebuffering: 7 / 120000
[08-08-15 09:23:46.5734] Slim::Player::Source::rebuffer (900) Rebuffering: 7 / 120000
[08-08-15 09:23:47.5827] Slim::Player::Source::rebuffer (900) Rebuffering: 26143 / 120000
[08-08-15 09:23:48.5905] Slim::Player::Source::rebuffer (900) Rebuffering: 72607 / 120000
[08-08-15 09:23:49.5963] Slim::Player::Source::rebuffer (900) Rebuffering: 142303 / 120000
[08-08-15 09:23:49.5971] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from pause
[08-08-15 09:23:49.5988] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: resume
[08-08-15 09:23:49.6009] Slim::Player::Source::playmode (430) 00:04:20:12:00:01: Resume, resetting mode: playout-play
[08-08-15 09:23:49.6082] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: resume
[08-08-15 09:24:02.5403] Slim::Player::Source::outputUnderrun (800) 00:04:20:12:00:01: Output buffer underrun (decoder: 1411 / output: 0)
[08-08-15 09:24:02.5407] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode pause from playout-play
[08-08-15 09:24:02.5416] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: pause
[08-08-15 09:24:02.5429] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: pause
[08-08-15 09:24:03.5511] Slim::Player::Source::rebuffer (900) Rebuffering: 3 / 120000
[08-08-15 09:24:04.5608] Slim::Player::Source::rebuffer (900) Rebuffering: 34851 / 120000
[08-08-15 09:24:05.5703] Slim::Player::Source::rebuffer (900) Rebuffering: 58083 / 120000
[08-08-15 09:24:06.5776] Slim::Player::Source::rebuffer (900) Rebuffering: 87123 / 120000
[08-08-15 09:24:07.5831] Slim::Player::Source::rebuffer (900) Rebuffering: 104547 / 120000
[08-08-15 09:24:08.5922] Slim::Player::Source::rebuffer (900) Rebuffering: 120519 / 120000
[08-08-15 09:24:08.5944] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from pause
[08-08-15 09:24:08.5954] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: resume
[08-08-15 09:24:08.5965] Slim::Player::Source::playmode (430) 00:04:20:12:00:01: Resume, resetting mode: playout-play
[08-08-15 09:24:08.5995] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: resume
[08-08-15 09:24:15.3648] Slim::Player::Source::outputUnderrun (800) 00:04:20:12:00:01: Output buffer underrun (decoder: 435 / output: 0)
[08-08-15 09:24:15.3655] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode pause from playout-play
[08-08-15 09:24:15.4194] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: pause
[08-08-15 09:24:15.4212] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: pause
[08-08-15 09:24:16.4325] Slim::Player::Source::rebuffer (900) Rebuffering: 5851 / 120000
[08-08-15 09:24:17.4398] Slim::Player::Source::rebuffer (900) Rebuffering: 10207 / 120000
[08-08-15 09:24:18.4497] Slim::Player::Source::rebuffer (900) Rebuffering: 23275 / 120000
[08-08-15 09:24:19.4551] Slim::Player::Source::rebuffer (900) Rebuffering: 62479 / 120000
[08-08-15 09:24:20.4642] Slim::Player::Source::rebuffer (900) Rebuffering: 114751 / 120000
[08-08-15 09:24:21.4739] Slim::Player::Source::rebuffer (900) Rebuffering: 188803 / 120000
[08-08-15 09:24:21.4745] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from pause
[08-08-15 09:24:21.4755] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: resume
[08-08-15 09:24:21.4768] Slim::Player::Source::playmode (430) 00:04:20:12:00:01: Resume, resetting mode: playout-play
[08-08-15 09:24:21.4811] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: resume
[08-08-15 09:24:26.3674] Slim::Player::Source::outputUnderrun (800) 00:04:20:12:00:01: Output buffer underrun (decoder: 7 / output: 0)
[08-08-15 09:24:26.3684] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode pause from playout-play
[08-08-15 09:24:26.3705] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: pause
[08-08-15 09:24:26.3736] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: pause
[08-08-15 09:24:27.3828] Slim::Player::Source::rebuffer (900) Rebuffering: 3023 / 120000
[08-08-15 09:24:28.3885] Slim::Player::Source::rebuffer (900) Rebuffering: 32503 / 120000
[08-08-15 09:24:29.4040] Slim::Player::Source::rebuffer (900) Rebuffering: 110911 / 120000
[08-08-15 09:24:30.4127] Slim::Player::Source::rebuffer (900) Rebuffering: 131239 / 120000
[08-08-15 09:24:30.4157] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from pause
[08-08-15 09:24:30.4167] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: resume
[08-08-15 09:24:30.4178] Slim::Player::Source::playmode (430) 00:04:20:12:00:01: Resume, resetting mode: playout-play
[08-08-15 09:24:30.4193] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: resume
[08-08-15 09:25:24.3223] Slim::Player::Source::outputUnderrun (800) 00:04:20:12:00:01: Output buffer underrun (decoder: 7 / output: 0)
[08-08-15 09:25:24.3227] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode pause from playout-play
[08-08-15 09:25:24.3237] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: pause
[08-08-15 09:25:24.3249] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: pause
[08-08-15 09:25:25.3316] Slim::Player::Source::rebuffer (900) Rebuffering: 17431 / 120000
[08-08-15 09:25:26.3431] Slim::Player::Source::rebuffer (900) Rebuffering: 34855 / 120000
[08-08-15 09:25:26.9369] Slim::Player::Source::decoderUnderrun (607) 00:04:20:12:00:01: Decoder underrun while this mode: pause
[08-08-15 09:25:26.9380] Slim::Player::Source::gotoNext (1350) Opening next song...
[08-08-15 09:25:26.9391] Slim::Player::Source::nextsong (1700) The next song is number 11, was 10
[08-08-15 09:25:26.9409] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:12:00:01
[08-08-15 09:25:26.9418] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:12:00:01
[08-08-15 09:25:26.9426] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:12:00:01
[08-08-15 09:25:26.9435] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-08-15 09:25:26.9443] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-08-15 09:25:26.9451] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:12:00:01
[08-08-15 09:25:26.9460] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:12:00:01
[08-08-15 09:25:26.9467] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-08-15 09:25:26.9475] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-08-15 09:25:26.9484] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:12:00:01
[08-08-15 09:25:26.9492] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:12:00:01
[08-08-15 09:25:26.9499] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-08-15 09:25:26.9507] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-08-15 09:25:26.9516] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:12:00:01
[08-08-15 09:25:26.9524] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:12:00:01
[08-08-15 09:25:26.9532] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-08-15 09:25:26.9565] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-08-15 09:25:26.9573] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:12:00:01
[08-08-15 09:25:26.9585] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:12:00:01
[08-08-15 09:25:26.9593] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-08-15 09:25:26.9601] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-08-15 09:25:26.9609] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:12:00:01
[08-08-15 09:25:26.9618] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:12:00:01
[08-08-15 09:25:26.9626] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-08-15 09:25:26.9634] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-08-15 09:25:26.9648] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-08-15 09:25:26.9656] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-08-15 09:25:26.9664] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-08-15 09:25:27.3084] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-08-15 09:25:27.3089] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: pause
[08-08-15 09:25:27.3092] Slim::Player::Source::streamingSongIndex (1494) Adding song index 11 to song queue
[08-08-15 09:25:27.3095] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 11,10
[08-08-15 09:25:27.3098] Slim::Player::Source::decoderUnderrun (620) 00:04:20:12:00:01: forcing playout mode on unexpected decoder underrun: new mode pause
[08-08-15 09:25:27.3102] Slim::Player::Source::nextsong (1700) The next song is number 12, was 11
[08-08-15 09:25:27.3106] Slim::Player::Source::decoderUnderrun (633) Track failed before playback, marking as played
[08-08-15 09:25:27.3110] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode playout-play from pause
[08-08-15 09:25:27.3119] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: playout-play
[08-08-15 09:25:27.3125] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: playout-play
[08-08-15 09:25:27.3129] Slim::Player::Source::nextsong (1700) The next song is number 12, was 11
[08-08-15 09:25:27.3135] Slim::Utils::Misc::msg (1356) Warning: [18:25:27.3133] Use of uninitialized value in numeric gt (>) at C:/PROGRA~1/SQUEEZ~2/server/Slim/Plugin/RhapsodyDirect/ProtocolHandler.pm line 379.
[08-08-15 09:25:27.3152] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\3\fTra.10627862"
[08-08-15 09:25:27.3159] Slim::Player::Source::underrun (722) 00:04:20:12:00:01: Underrun while this mode: pause
[08-08-15 09:25:27.3164] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onUnderrun (667) Underrun, stopping, playmode: pause
[08-08-15 09:25:27.3168] Slim::Player::Source::nextsong (1700) The next song is number 12, was 11
[08-08-15 09:25:27.3171] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode stop from playout-stop
[08-08-15 09:25:27.3179] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: stop
[08-08-15 09:25:27.3182] Slim::Player::Source::playmode (461) 00:04:20:12:00:01: Stopping and clearing out old chunks
[08-08-15 09:25:27.3196] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-15 09:25:27.3199] Slim::Player::Source::resetSongQueue (1568) Resetting song queue
[08-08-15 09:25:27.3202] Slim::Player::Source::resetSongQueue (1578) Song queue is now 11
[08-08-15 09:25:27.3208] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: stop
[08-08-15 09:25:27.3211] Slim::Player::Source::streamingSongIndex (1494) Adding song index 0 to song queue
[08-08-15 09:25:27.3214] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-15 09:25:27.3217] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 0
[08-08-15 09:25:27.3322] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (701) Skipping to next track (12)
[08-08-15 09:25:27.3348] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (415) Handling command "jump", playmode: stop
[08-08-15 09:25:27.3433] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (498) Ending any previous playback session
[08-08-15 09:25:27.3447] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\6"
[08-08-15 09:25:27.3457] Slim::Utils::Misc::msg (1356) Warning: [18:25:27.3454] Use of uninitialized value in concatenation (.) or string at C:/PROGRA~1/SQUEEZ~2/server/Slim/Plugin/RhapsodyDirect/ProtocolHandler.pm line 1104.
[08-08-15 09:25:27.3461] Slim::Plugin::RhapsodyDirect::ProtocolHandler::stopCallback (1104) Player stopped (stop ) but another track was already starting, ignoring
[08-08-15 09:25:27.3641] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\xFC"
[08-08-15 09:25:27.3647] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (305) 00:04:20:12:00:01 Received RPDS -4, SSL connection already in use, retrying later
[08-08-15 09:25:28.1697] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\3\x001http://llnw-611.listen.com/1/1/8/0/1/64410811.rad"
[08-08-15 09:25:28.1711] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (326) 00:04:20:12:00:01 Ignoring unrequested or old RPDS packet (got 3, expected N/A)
[08-08-15 09:25:29.3662] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (353) 00:04:20:12:00:01 Re-sending RPDS packet: "\6"
[08-08-15 09:25:29.3693] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\6"
[08-08-15 09:25:30.1545] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\6"
[08-08-15 09:25:30.1564] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (254) Found IP for secure-direct.rhapsody.com: 207.188.0.25
[08-08-15 09:25:30.1686] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\0\xCF\xBC\0\31\1\xBB"
[08-08-15 09:25:30.1696] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getPlaybackSession (274) 00:04:20:12:00:01 Requesting new playback session...
[08-08-15 09:25:30.1746] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: 2
[08-08-15 09:25:30.9778] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\2"
[08-08-15 09:25:30.9785] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (339) New playback session obtained, resetting sessionErrors count to 0
[08-08-15 09:25:30.9796] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\3\fTra.10627862"
[08-08-15 09:25:31.7749] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\3\x001http://llnw-611.listen.com/1/1/8/0/1/64410811.rad"
[08-08-15 09:25:32.0459] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode stop from stop
[08-08-15 09:25:32.0484] Slim::Player::Source::playmode (316) Already in playmode stop : ignoring mode change
[08-08-15 09:25:32.0492] Slim::Player::Source::jumpto (1306) Jumping to 12
[08-08-15 09:25:32.0500] Slim::Player::Source::streamingSongIndex (1494) Adding song index 12 to song queue
[08-08-15 09:25:32.0509] Slim::Player::Source::streamingSongIndex (1498) Clearing out song queue first
[08-08-15 09:25:32.0518] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 12
[08-08-15 09:25:32.0536] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from stop
[08-08-15 09:25:32.0572] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-15 09:25:32.0592] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.10627862.mp3
[08-08-15 09:25:32.0604] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.10627862.mp3]
[08-08-15 09:25:32.0854] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: play
[08-08-15 09:25:32.0938] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.10627862.mp3 as http://llnw-611.listen.com/1/1/8/0/1/64410811.rad, let's do it.
[08-08-15 09:25:32.3958] Slim::Player::Squeezebox::stream (1055) setting up direct stream (3496968321:80) autostart: 3.
[08-08-15 09:25:32.3967] Slim::Player::Squeezebox::stream (1056) request string: GET /1/1/8/0/1/64410811.rad HTTP/1.0

Cache-Control: no-cache

Connection: close

Accept: */*

Host: llnw-611.listen.com

User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.2/22618

Icy-Metadata: 1



[08-08-15 09:25:32.4743] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: play
[08-08-15 09:25:32.6366] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 73728
[08-08-15 09:25:32.8241] 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: 6552396

Content-Type: text/plain

Age: 226087

Date: Fri, 15 Aug 2008 15:25:32 GMT

Last-Modified: Fri, 16 Mar 2007 14:33:52 GMT

Connection: close



[08-08-15 09:25:32.8246] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers
[08-08-15 09:25:32.8249] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Server: Apache/2.0.58
[08-08-15 09:25:32.8252] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-15 09:25:32.8255] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Content-Length: 6552396
[08-08-15 09:25:32.9163] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-15 09:25:32.9168] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-15 09:25:33.0440] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 73728
[08-08-15 09:25:33.4521] Slim::Player::Squeezebox::buffering (303) Buffering... 1223 / 73728
[08-08-15 09:25:33.8582] Slim::Player::Squeezebox::buffering (303) Buffering... 1223 / 73728
[08-08-15 09:25:34.2660] Slim::Player::Squeezebox::buffering (303) Buffering... 1223 / 73728
[08-08-15 09:25:34.6755] Slim::Player::Squeezebox::buffering (303) Buffering... 2675 / 73728
[08-08-15 09:25:35.0904] Slim::Player::Squeezebox::buffering (303) Buffering... 30262 / 73728
[08-08-15 09:25:35.4983] Slim::Player::Squeezebox::buffering (303) Buffering... 30262 / 73728
[08-08-15 09:25:35.9059] Slim::Player::Squeezebox::buffering (303) Buffering... 49138 / 73728
[08-08-15 09:25:36.0482] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-15 09:25:36.0492] Slim::Player::Source::trackStartEvent (1621) Song 12 has now started playing
[08-08-15 09:25:36.0516] Slim::Player::Source::trackStartEvent (1649) Song queue is now 12
[08-08-15 09:30:01.1226] Slim::Player::Source::decoderUnderrun (607) 00:04:20:12:00:01: Decoder underrun while this mode: playout-play
[08-08-15 09:30:01.1237] Slim::Player::Source::nextsong (1700) The next song is number 13, was 12
[08-08-15 09:30:01.1250] Slim::Player::Source::nextsong (1700) The next song is number 13, was 12
[08-08-15 09:30:01.1263] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (380) End of track, logging usage info (275 seconds)...
[08-08-15 09:30:01.1294] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\4\x03275"
[08-08-15 09:30:02.2381] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\4"
[08-08-15 09:30:02.2416] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:01 RPDS packet sent: "\3\fTra.12152481"
[08-08-15 09:30:03.4056] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:01 Got RPDS packet: \"\3\x001http://llnw-780.listen.com/8/0/8/1/9/73391808.rad"
[08-08-15 09:30:03.6700] Slim::Player::Source::skipahead (956) **skipahead: opening next song
[08-08-15 09:30:03.6708] Slim::Player::Source::gotoNext (1350) Opening next song...
[08-08-15 09:30:03.6719] Slim::Player::Source::nextsong (1700) The next song is number 13, was 12
[08-08-15 09:30:03.6737] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:12:00:01
[08-08-15 09:30:03.6758] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:12:00:01
[08-08-15 09:30:03.6766] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:12:00:01
[08-08-15 09:30:03.6774] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-*
[08-08-15 09:30:03.6782] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-*
[08-08-15 09:30:03.6790] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:12:00:01
[08-08-15 09:30:03.6798] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:12:00:01
[08-08-15 09:30:03.6846] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-*
[08-08-15 09:30:03.6857] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-*
[08-08-15 09:30:03.6865] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:12:00:01
[08-08-15 09:30:03.6874] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:12:00:01
[08-08-15 09:30:03.6882] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-*
[08-08-15 09:30:03.6889] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-*
[08-08-15 09:30:03.6897] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:12:00:01
[08-08-15 09:30:03.6905] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:12:00:01
[08-08-15 09:30:03.6913] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-*
[08-08-15 09:30:03.6921] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-*
[08-08-15 09:30:03.6929] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:12:00:01
[08-08-15 09:30:03.6937] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:12:00:01
[08-08-15 09:30:03.6948] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-*
[08-08-15 09:30:03.6956] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-*
[08-08-15 09:30:03.6964] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:12:00:01
[08-08-15 09:30:03.6972] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:12:00:01
[08-08-15 09:30:03.6980] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-*
[08-08-15 09:30:03.6988] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-*
[08-08-15 09:30:03.6995] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled
[08-08-15 09:30:03.7003] Slim::Player::TranscodingHelper::checkBin (160)    enabled
[08-08-15 09:30:03.7011] Slim::Player::TranscodingHelper::checkBin (162)   Found command: -
[08-08-15 09:30:03.9633] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: -
[08-08-15 09:30:03.9637] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: playout-play
[08-08-15 09:30:03.9640] Slim::Player::Source::streamingSongIndex (1494) Adding song index 13 to song queue
[08-08-15 09:30:03.9643] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 13,12
[08-08-15 09:30:03.9646] Slim::Player::Source::skipahead (962) **skipahead: restarting
[08-08-15 09:30:03.9652] Slim::Player::Source::playmode (309) 00:04:20:12:00:01: Switching to mode play from playout-play
[08-08-15 09:30:03.9657] Slim::Player::Source::resetSong (1739) Resetting song buffer.
[08-08-15 09:30:03.9716] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.12152481.mp3
[08-08-15 09:30:03.9721] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.12152481.mp3]
[08-08-15 09:30:03.9771] Slim::Player::Source::playmode (412) 00:04:20:12:00:01 New play mode: play
[08-08-15 09:30:03.9795] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.12152481.mp3 as http://llnw-780.listen.com/8/0/8/1/9/73391808.rad, let's do it.
[08-08-15 09:30:05.2909] Slim::Player::Squeezebox::stream (1055) setting up direct stream (1150180192:80) autostart: 3.
[08-08-15 09:30:05.2918] Slim::Player::Squeezebox::stream (1056) request string: GET /8/0/8/1/9/73391808.rad HTTP/1.0

Cache-Control: no-cache

Connection: close

Accept: */*

Host: llnw-780.listen.com

User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.2/22618

Icy-Metadata: 1



[08-08-15 09:30:05.3743] Slim::Player::Source::playmode (581) 00:04:20:12:00:01: Current playmode: play
[08-08-15 09:30:05.7361] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming:
HTTP/1.0 200 OK

Server: Apache/2.2.3

Accept-Ranges: bytes

Content-Length: 8370108

Content-Type: text/plain

Age: 226086

Date: Fri, 15 Aug 2008 15:30:05 GMT

Last-Modified: Sun, 15 Jul 2007 18:34:08 GMT

Connection: close



[08-08-15 09:30:05.7366] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers
[08-08-15 09:30:05.7370] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Server: Apache/2.2.3
[08-08-15 09:30:05.7373] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Accept-Ranges: bytes
[08-08-15 09:30:05.7376] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Content-Length: 8370108
[08-08-15 09:30:05.7753] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: 
[08-08-15 09:30:05.7758] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream!
[08-08-15 09:30:11.0990] Slim::Player::Source::trackStartEvent (1605) Got a track starting event
[08-08-15 09:30:11.0999] Slim::Player::Source::trackStartEvent (1612) Song 12 had already started, so it's not longer in the queue
[08-08-15 09:30:11.1013] Slim::Player::Source::trackStartEvent (1621) Song 13 has now started playing