[08-03-29 12:11:42.7676] Slim::Player::Source::decoderUnderrun (582) 00:04:20:07:6e:1a: Decoder underrun while this mode: playout-play [08-03-29 12:11:42.7690] Slim::Player::Source::nextsong (1556) The next song is number 42, was 41 [08-03-29 12:11:42.7705] Slim::Player::Source::nextsong (1556) The next song is number 42, was 41 [08-03-29 12:11:42.7719] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (192 seconds)... [08-03-29 12:11:42.7750] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 12:11:44.2011] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\4" [08-03-29 12:11:44.2029] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 12:11:45.4279] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw-783.listen.com/8/3/4/5/9/69695438.rad" [08-03-29 12:11:45.7087] Slim::Player::Source::skipahead (873) **skipahead: opening next song [08-03-29 12:11:45.7095] Slim::Player::Source::gotoNext (1206) Opening next song... [08-03-29 12:11:45.7100] Slim::Player::Source::nextsong (1556) The next song is number 42, was 41 [08-03-29 12:11:45.7115] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:07:6e:1a [08-03-29 12:11:45.7118] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:07:6e:1a [08-03-29 12:11:45.7122] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:07:6e:1a [08-03-29 12:11:45.7125] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [08-03-29 12:11:45.7137] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [08-03-29 12:11:45.7140] Slim::Player::TranscodingHelper::checkBin (146) enabled [08-03-29 12:11:45.7143] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [08-03-29 12:11:45.7148] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [08-03-29 12:11:45.7152] Slim::Player::Source::gotoNext (1293) opening next song (old format: wma, new: wma) current playmode: playout-play [08-03-29 12:11:45.7155] Slim::Player::Source::streamingSongIndex (1350) Adding song index 42 to song queue [08-03-29 12:11:45.7158] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 42,41 [08-03-29 12:11:45.7162] Slim::Player::Source::skipahead (879) **skipahead: restarting [08-03-29 12:11:45.7165] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from playout-play [08-03-29 12:11:45.7170] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 12:11:45.7227] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.10506160.wma [08-03-29 12:11:45.7913] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 12:11:45.8023] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.10506160.wma as http://llnw-783.listen.com/8/3/4/5/9/69695438.rad, let's do it. [08-03-29 12:11:47.1257] Slim::Player::Squeezebox::stream (1044) setting up direct stream (1150180169:80) autostart: 3. [08-03-29 12:11:47.1269] Slim::Player::Squeezebox::stream (1045) request string: GET /8/3/4/5/9/69695438.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw-783.listen.com User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.0/17759 Icy-Metadata: 1 [08-03-29 12:11:47.1320] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 12:11:47.4664] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/2.2.3 Accept-Ranges: bytes Content-Length: 3871124 Content-Type: text/plain Age: 2862 Date: Sat, 29 Mar 2008 09:11:24 GMT Last-Modified: Sat, 26 May 2007 00:57:34 GMT Connection: close [08-03-29 12:11:47.4676] Slim::Player::Squeezebox2::directHeaders (343) Processing 8 headers [08-03-29 12:11:47.5694] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/2.2.3 [08-03-29 12:11:47.5704] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 12:11:47.6359] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3871124 [08-03-29 12:11:47.7981] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 12:11:47.7986] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 12:11:49.7968] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [08-03-29 12:11:49.8624] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 264) [08-03-29 12:11:49.8669] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 132) [08-03-29 12:11:49.8897] Slim::Player::Protocols::MMS::setMetadata (271) Setting bitrate to 128 from WMA bitrate properties object [08-03-29 12:11:52.7248] Slim::Player::Source::trackStartEvent (1461) Got a track starting event [08-03-29 12:11:52.7258] Slim::Player::Source::trackStartEvent (1468) Song 41 had already started, so it's not longer in the queue [08-03-29 12:11:52.7267] Slim::Player::Source::trackStartEvent (1477) Song 42 has now started playing [08-03-29 12:11:52.7335] Slim::Player::Source::trackStartEvent (1505) Song queue is now 42 [08-03-29 12:15:44.8495] Slim::Player::Source::decoderUnderrun (582) 00:04:20:07:6e:1a: Decoder underrun while this mode: playout-play [08-03-29 12:15:44.8506] Slim::Player::Source::nextsong (1556) The next song is number 43, was 42 [08-03-29 12:15:44.8520] Slim::Player::Source::nextsong (1556) The next song is number 43, was 42 [08-03-29 12:15:44.8534] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (242 seconds)... [08-03-29 12:15:44.8561] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 12:15:46.2239] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\4" [08-03-29 12:15:46.2271] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 12:15:47.1784] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw2.listen.com/u2/7/0/1/8/8/15688107.rad" [08-03-29 12:15:47.4593] Slim::Player::Source::skipahead (873) **skipahead: opening next song [08-03-29 12:15:47.4603] Slim::Player::Source::gotoNext (1206) Opening next song... [08-03-29 12:15:47.4619] Slim::Player::Source::nextsong (1556) The next song is number 43, was 42 [08-03-29 12:15:47.4652] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:07:6e:1a [08-03-29 12:15:47.4663] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:07:6e:1a [08-03-29 12:15:47.4672] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:07:6e:1a [08-03-29 12:15:47.4681] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [08-03-29 12:15:47.4690] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [08-03-29 12:15:47.4699] Slim::Player::TranscodingHelper::checkBin (146) enabled [08-03-29 12:15:47.4708] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [08-03-29 12:15:47.4721] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [08-03-29 12:15:47.4730] Slim::Player::Source::gotoNext (1293) opening next song (old format: wma, new: wma) current playmode: playout-play [08-03-29 12:15:47.4740] Slim::Player::Source::streamingSongIndex (1350) Adding song index 43 to song queue [08-03-29 12:15:47.4749] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 43,42 [08-03-29 12:15:47.4758] Slim::Player::Source::skipahead (879) **skipahead: restarting [08-03-29 12:15:47.4768] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from playout-play [08-03-29 12:15:47.4780] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 12:15:47.4892] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.1935212.wma [08-03-29 12:15:47.5514] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 12:15:47.5674] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.1935212.wma as http://llnw2.listen.com/u2/7/0/1/8/8/15688107.rad, let's do it. [08-03-29 12:15:47.8983] Slim::Player::Squeezebox::stream (1044) setting up direct stream (1159502586:80) autostart: 3. [08-03-29 12:15:47.9003] Slim::Player::Squeezebox::stream (1045) request string: GET /u2/7/0/1/8/8/15688107.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw2.listen.com User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.0/17759 Icy-Metadata: 1 [08-03-29 12:15:47.9054] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 12:15:48.3413] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Date: Sat, 29 Mar 2008 09:15:25 GMT Server: Apache/1.3.31 (Unix) Last-Modified: Mon, 20 Oct 2003 18:36:45 GMT ETag: "5e60c0-374674-3f942b3d" Accept-Ranges: bytes Content-Length: 3622516 Content-Type: application/octet-stream Connection: close [08-03-29 12:15:48.3426] Slim::Player::Squeezebox2::directHeaders (343) Processing 8 headers [08-03-29 12:15:48.3436] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Date: Sat, 29 Mar 2008 09:15:25 GMT [08-03-29 12:15:48.3445] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [08-03-29 12:15:48.3454] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Last-Modified: Mon, 20 Oct 2003 18:36:45 GMT [08-03-29 12:15:48.3463] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "5e60c0-374674-3f942b3d" [08-03-29 12:15:48.3473] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 12:15:48.3482] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3622516 [08-03-29 12:15:48.4797] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 12:15:48.4809] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 12:15:50.6858] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [08-03-29 12:15:50.6999] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 268) [08-03-29 12:15:50.9067] Slim::Player::Source::decoderUnderrun (582) 00:04:20:07:6e:1a: Decoder underrun while this mode: playout-play [08-03-29 12:15:50.9071] Slim::Player::Source::nextsong (1556) The next song is number 44, was 43 [08-03-29 12:15:50.9077] Slim::Player::Source::decoderUnderrun (595) Track failed before playback, marking as played [08-03-29 12:15:50.9081] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode playout-play from playout-play [08-03-29 12:15:50.9084] Slim::Player::Source::playmode (305) Already in playmode playout-play : ignoring mode change [08-03-29 12:15:50.9088] Slim::Player::Source::nextsong (1556) The next song is number 44, was 43 [08-03-29 12:15:50.9093] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (226 seconds)... [08-03-29 12:15:50.9105] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 12:15:50.9179] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\xFC" [08-03-29 12:15:50.9185] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (269) 00:04:20:07:6e:1a Received RPDS -4, SSL connection already in use, retrying later [08-03-29 12:15:52.9234] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (317) 00:04:20:07:6e:1a Re-sending RPDS packet: "\4\x03226" [08-03-29 12:15:52.9247] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 12:15:54.1909] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\4" [08-03-29 12:15:54.1929] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 12:15:54.8068] Slim::Player::Source::trackStartEvent (1461) Got a track starting event [08-03-29 12:15:54.8072] Slim::Player::Source::trackStartEvent (1477) Song 43 has now started playing [08-03-29 12:15:54.8084] Slim::Player::Source::trackStartEvent (1505) Song queue is now 43 [08-03-29 12:15:55.1501] Slim::Player::Source::underrun (681) 00:04:20:07:6e:1a: Underrun while this mode: playout-play [08-03-29 12:15:55.1522] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onUnderrun (594) Underrun, stopping, playmode: playout-play [08-03-29 12:15:55.1527] Slim::Player::Source::nextsong (1556) The next song is number 44, was 43 [08-03-29 12:15:55.1532] Slim::Player::Source::skipahead (873) **skipahead: opening next song [08-03-29 12:15:55.1535] Slim::Player::Source::gotoNext (1206) Opening next song... [08-03-29 12:15:55.1539] Slim::Player::Source::nextsong (1556) The next song is number 44, was 43 [08-03-29 12:15:55.1631] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:07:6e:1a [08-03-29 12:15:55.1635] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:07:6e:1a [08-03-29 12:15:55.1852] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:07:6e:1a [08-03-29 12:15:55.1857] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [08-03-29 12:15:55.1860] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [08-03-29 12:15:55.2020] Slim::Player::TranscodingHelper::checkBin (146) enabled [08-03-29 12:15:55.2024] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [08-03-29 12:15:55.2030] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [08-03-29 12:15:55.2225] Slim::Player::Source::gotoNext (1293) opening next song (old format: wma, new: wma) current playmode: playout-play [08-03-29 12:15:55.2230] Slim::Player::Source::streamingSongIndex (1350) Adding song index 44 to song queue [08-03-29 12:15:55.2235] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 44,43 [08-03-29 12:15:55.2423] Slim::Player::Source::skipahead (879) **skipahead: restarting [08-03-29 12:15:55.2428] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from playout-play [08-03-29 12:15:55.2434] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 12:15:55.2626] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.2376244.wma [08-03-29 12:15:55.3123] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 12:15:55.3475] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.2376244.wma as http://llnw2.listen.com/u2/7/0/1/8/8/15688107.rad, let's do it. [08-03-29 12:15:55.3695] Slim::Player::Squeezebox::stream (1044) setting up direct stream (1159502586:80) autostart: 3. [08-03-29 12:15:55.3699] Slim::Player::Squeezebox::stream (1045) request string: GET /u2/7/0/1/8/8/15688107.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw2.listen.com User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.0/17759 Icy-Metadata: 1 [08-03-29 12:15:55.3875] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 12:15:55.3903] Slim::Player::Source::outputUnderrun (759) 00:04:20:07:6e:1a: Output buffer underrun (decoder: 106335 / output: 0) [08-03-29 12:15:55.3910] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode pause from playout-play [08-03-29 12:15:55.4077] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: pause [08-03-29 12:15:55.4090] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: pause [08-03-29 12:15:55.4162] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw3.listen.com/u4/2/4/6/8/7/19078642.rad" [08-03-29 12:15:55.5421] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (624) Skipping to next track (44) [08-03-29 12:15:55.5431] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (452) Handling command "jump", playmode: pause [08-03-29 12:15:55.5527] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 12:15:55.5634] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\xFC" [08-03-29 12:15:55.5641] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (269) 00:04:20:07:6e:1a Received RPDS -4, SSL connection already in use, retrying later [08-03-29 12:15:55.7108] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "5e60c0-374674-3f942b3d" Accept-Ranges: bytes Content-Length: 3622516 Content-Type: application/octet-stream Age: 8 Date: Sat, 29 Mar 2008 09:15:33 GMT Last-Modified: Mon, 20 Oct 2003 18:36:45 GMT Connection: close [08-03-29 12:15:55.7114] Slim::Player::Squeezebox2::directHeaders (343) Processing 9 headers [08-03-29 12:15:55.7184] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [08-03-29 12:15:55.7189] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "5e60c0-374674-3f942b3d" [08-03-29 12:15:55.7192] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 12:15:55.7383] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3622516 [08-03-29 12:15:55.8531] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 12:15:55.8537] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 12:15:55.8788] Slim::Player::Source::skipahead (873) **skipahead: opening next song [08-03-29 12:15:55.8927] Slim::Player::Source::gotoNext (1206) Opening next song... [08-03-29 12:15:55.8933] Slim::Player::Source::nextsong (1556) The next song is number 45, was 44 [08-03-29 12:15:55.9311] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:07:6e:1a [08-03-29 12:15:55.9317] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:07:6e:1a [08-03-29 12:15:55.9320] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:07:6e:1a [08-03-29 12:15:55.9323] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [08-03-29 12:15:55.9510] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [08-03-29 12:15:55.9516] Slim::Player::TranscodingHelper::checkBin (146) enabled [08-03-29 12:15:55.9519] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [08-03-29 12:15:55.9525] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [08-03-29 12:15:55.9529] Slim::Player::Source::gotoNext (1293) opening next song (old format: wma, new: wma) current playmode: pause [08-03-29 12:15:55.9532] Slim::Player::Source::streamingSongIndex (1350) Adding song index 45 to song queue [08-03-29 12:15:55.9711] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 45,44,43 [08-03-29 12:15:55.9719] Slim::Player::Source::skipahead (879) **skipahead: restarting [08-03-29 12:15:55.9723] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from pause [08-03-29 12:15:55.9910] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: resume [08-03-29 12:15:55.9929] Slim::Player::Source::playmode (419) 00:04:20:07:6e:1a: Resume, resetting mode: playout-play [08-03-29 12:15:55.9958] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: resume [08-03-29 12:15:56.6277] Slim::Player::Source::decoderUnderrun (582) 00:04:20:07:6e:1a: Decoder underrun while this mode: playout-play [08-03-29 12:15:56.6282] Slim::Player::Source::nextsong (1556) The next song is number 46, was 45 [08-03-29 12:15:56.6287] Slim::Player::Source::decoderUnderrun (595) Track failed before playback, marking as played [08-03-29 12:15:56.6294] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode playout-play from playout-play [08-03-29 12:15:56.6298] Slim::Player::Source::playmode (305) Already in playmode playout-play : ignoring mode change [08-03-29 12:15:56.6301] Slim::Player::Source::decoderUnderrun (619) 00:04:20:07:6e:1a: Ignoring decoder underrun, player already has 2 tracks [08-03-29 12:15:57.5666] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (317) 00:04:20:07:6e:1a Re-sending RPDS packet: "\3\13Tra.2376244" [08-03-29 12:15:57.5697] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 12:15:58.7470] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw3.listen.com/u4/2/4/6/8/7/19078642.rad" [08-03-29 12:15:58.7486] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode stop from playout-play [08-03-29 12:15:58.7496] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: stop [08-03-29 12:15:58.7500] Slim::Player::Source::playmode (444) 00:04:20:07:6e:1a: Stopping and clearing out old chunks [08-03-29 12:15:58.7514] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 12:15:58.7518] Slim::Player::Source::resetSongQueue (1424) Resetting song queue [08-03-29 12:15:58.7776] Slim::Player::Source::resetSongQueue (1434) Song queue is now 44 [08-03-29 12:15:58.7786] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: stop [08-03-29 12:15:58.7789] Slim::Player::Source::jumpto (1162) Jumping to 44 [08-03-29 12:15:58.7974] Slim::Player::Source::streamingSongIndex (1350) Adding song index 44 to song queue [08-03-29 12:15:58.8172] Slim::Player::Source::streamingSongIndex (1354) Clearing out song queue first [08-03-29 12:15:58.8177] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 44 [08-03-29 12:15:58.8183] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from stop [08-03-29 12:15:58.8189] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 12:15:58.8248] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.2376244.wma [08-03-29 12:15:58.8845] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 12:15:58.8992] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.2376244.wma as http://llnw3.listen.com/u4/2/4/6/8/7/19078642.rad, let's do it. [08-03-29 12:16:00.2597] Slim::Player::Squeezebox::stream (1044) setting up direct stream (1150180247:80) autostart: 3. [08-03-29 12:16:00.2608] Slim::Player::Squeezebox::stream (1045) request string: GET /u4/2/4/6/8/7/19078642.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw3.listen.com User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.0/17759 Icy-Metadata: 1 [08-03-29 12:16:00.2670] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 12:16:00.2894] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 49152 [08-03-29 12:16:00.6048] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "777e3a-33a844-3f9be408" Accept-Ranges: bytes Content-Length: 3385412 Content-Type: application/octet-stream Age: 28726 Date: Sat, 29 Mar 2008 09:15:37 GMT Last-Modified: Sun, 26 Oct 2003 15:11:04 GMT Connection: close [08-03-29 12:16:00.6061] Slim::Player::Squeezebox2::directHeaders (343) Processing 9 headers [08-03-29 12:16:00.6071] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [08-03-29 12:16:00.6080] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "777e3a-33a844-3f9be408" [08-03-29 12:16:00.6089] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 12:16:00.6098] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3385412 [08-03-29 12:16:00.6466] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 12:16:00.6478] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 12:16:00.7032] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 49152 [08-03-29 12:16:01.1122] Slim::Player::Squeezebox::buffering (303) Buffering... 1171 / 49152 [08-03-29 12:16:01.5253] Slim::Player::Squeezebox::buffering (303) Buffering... 1171 / 49152 [08-03-29 12:16:01.9334] Slim::Player::Squeezebox::buffering (303) Buffering... 1171 / 49152 [08-03-29 12:16:02.3442] Slim::Player::Squeezebox::buffering (303) Buffering... 2623 / 49152 [08-03-29 12:16:02.9471] Slim::Player::Squeezebox::buffering (303) Buffering... 14238 / 49152 [08-03-29 12:16:03.2635] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [08-03-29 12:16:03.2739] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 268) [08-03-29 12:16:03.4015] Slim::Player::Squeezebox::buffering (303) Buffering... 37470 / 49152 [08-03-29 12:16:03.8721] Slim::Player::Squeezebox::buffering (303) Buffering... 53154 / 49152 [08-03-29 12:16:04.1147] Slim::Player::Source::trackStartEvent (1461) Got a track starting event [08-03-29 12:16:04.1152] Slim::Player::Source::trackStartEvent (1477) Song 44 has now started playing [08-03-29 12:16:04.1164] Slim::Player::Source::trackStartEvent (1505) Song queue is now 44 [08-03-29 12:16:04.1168] Slim::Player::Source::nextsong (1556) The next song is number 45, was 44 [08-03-29 12:16:04.1173] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (211 seconds)... [08-03-29 12:16:04.1187] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 12:16:04.1478] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\xFC" [08-03-29 12:16:04.1484] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (269) 00:04:20:07:6e:1a Received RPDS -4, SSL connection already in use, retrying later [08-03-29 12:16:06.1583] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (317) 00:04:20:07:6e:1a Re-sending RPDS packet: "\4\x03211" [08-03-29 12:16:06.1593] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 12:16:06.8797] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\xFC" [08-03-29 12:16:06.8813] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (269) 00:04:20:07:6e:1a Received RPDS -4, SSL connection already in use, retrying later [08-03-29 12:16:08.8830] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (317) 00:04:20:07:6e:1a Re-sending RPDS packet: "\4\x03211" [08-03-29 12:16:08.8861] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 12:16:10.3519] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\4" [08-03-29 12:16:10.3549] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 12:16:11.3226] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw-777.listen.com/7/7/6/7/7/73977677.rad" [08-03-29 12:16:11.6036] Slim::Player::Source::skipahead (873) **skipahead: opening next song [08-03-29 12:16:11.6045] Slim::Player::Source::gotoNext (1206) Opening next song... [08-03-29 12:16:11.6153] Slim::Player::Source::nextsong (1556) The next song is number 45, was 44 [08-03-29 12:16:11.6211] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:07:6e:1a [08-03-29 12:16:11.6222] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:07:6e:1a [08-03-29 12:16:11.6233] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:07:6e:1a [08-03-29 12:16:11.6242] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [08-03-29 12:16:11.6251] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [08-03-29 12:16:11.6261] Slim::Player::TranscodingHelper::checkBin (146) enabled [08-03-29 12:16:11.6270] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [08-03-29 12:16:11.6286] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [08-03-29 12:16:11.6296] Slim::Player::Source::gotoNext (1293) opening next song (old format: wma, new: wma) current playmode: playout-play [08-03-29 12:16:11.6305] Slim::Player::Source::streamingSongIndex (1350) Adding song index 45 to song queue [08-03-29 12:16:11.6317] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 45,44 [08-03-29 12:16:11.6326] Slim::Player::Source::skipahead (879) **skipahead: restarting [08-03-29 12:16:11.6336] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from playout-play [08-03-29 12:16:11.6348] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 12:16:11.6461] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.1884450.wma [08-03-29 12:16:11.7163] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 12:16:11.7334] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.1884450.wma as http://llnw-777.listen.com/7/7/6/7/7/73977677.rad, let's do it. [08-03-29 12:16:12.0762] Slim::Player::Squeezebox::stream (1044) setting up direct stream (3496968481:80) autostart: 3. [08-03-29 12:16:12.0773] Slim::Player::Squeezebox::stream (1045) request string: GET /7/7/6/7/7/73977677.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw-777.listen.com User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.0/17759 Icy-Metadata: 1 [08-03-29 12:16:12.0823] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 12:16:12.4384] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/2.2.3 Accept-Ranges: bytes Content-Length: 2619964 Content-Type: text/plain Age: 167718 Date: Sat, 29 Mar 2008 09:15:49 GMT Last-Modified: Sun, 22 Jul 2007 16:33:35 GMT Connection: close [08-03-29 12:16:12.4396] Slim::Player::Squeezebox2::directHeaders (343) Processing 8 headers [08-03-29 12:16:12.4406] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/2.2.3 [08-03-29 12:16:12.4418] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 12:16:12.4428] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 2619964 [08-03-29 12:16:12.6577] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 12:16:12.6584] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 12:16:15.7030] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [08-03-29 12:16:15.7206] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 264) [08-03-29 12:16:15.7723] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 132) [08-03-29 12:16:15.7851] Slim::Player::Protocols::MMS::setMetadata (271) Setting bitrate to 128 from WMA bitrate properties object [08-03-29 12:16:16.6179] Slim::Player::Source::trackStartEvent (1461) Got a track starting event [08-03-29 12:16:16.6189] Slim::Player::Source::trackStartEvent (1468) Song 44 had already started, so it's not longer in the queue [08-03-29 12:16:16.6198] Slim::Player::Source::trackStartEvent (1477) Song 45 has now started playing [08-03-29 12:16:16.6238] Slim::Player::Source::trackStartEvent (1505) Song queue is now 45 [08-03-29 12:18:50.2130] Slim::Player::Source::decoderUnderrun (582) 00:04:20:07:6e:1a: Decoder underrun while this mode: playout-play [08-03-29 12:18:50.2143] Slim::Player::Source::nextsong (1556) The next song is number 46, was 45 [08-03-29 12:18:50.2158] Slim::Player::Source::nextsong (1556) The next song is number 46, was 45 [08-03-29 12:18:50.2172] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (163 seconds)... [08-03-29 12:18:50.2200] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 12:18:51.5674] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\4" [08-03-29 12:18:51.5709] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 12:18:52.7243] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw-208.listen.com/0/8/4/9/2/41229480.rad" [08-03-29 12:18:53.0385] Slim::Player::Source::skipahead (873) **skipahead: opening next song [08-03-29 12:18:53.0395] Slim::Player::Source::gotoNext (1206) Opening next song... [08-03-29 12:18:53.0407] Slim::Player::Source::nextsong (1556) The next song is number 46, was 45 [08-03-29 12:18:53.0448] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:07:6e:1a [08-03-29 12:18:53.0459] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:07:6e:1a [08-03-29 12:18:53.0468] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:07:6e:1a [08-03-29 12:18:53.0478] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [08-03-29 12:18:53.0487] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [08-03-29 12:18:53.0497] Slim::Player::TranscodingHelper::checkBin (146) enabled [08-03-29 12:18:53.0506] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [08-03-29 12:18:53.0519] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [08-03-29 12:18:53.0537] Slim::Player::Source::gotoNext (1293) opening next song (old format: wma, new: wma) current playmode: playout-play [08-03-29 12:18:53.0550] Slim::Player::Source::streamingSongIndex (1350) Adding song index 46 to song queue [08-03-29 12:18:53.0560] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 46,45 [08-03-29 12:18:53.0570] Slim::Player::Source::skipahead (879) **skipahead: restarting [08-03-29 12:18:53.0580] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from playout-play [08-03-29 12:18:53.0593] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 12:18:53.0711] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.2650798.wma [08-03-29 12:18:53.1283] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 12:18:53.1483] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.2650798.wma as http://llnw-208.listen.com/0/8/4/9/2/41229480.rad, let's do it. [08-03-29 12:18:54.4903] Slim::Player::Squeezebox::stream (1044) setting up direct stream (1150180275:80) autostart: 3. [08-03-29 12:18:54.4916] Slim::Player::Squeezebox::stream (1045) request string: GET /0/8/4/9/2/41229480.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw-208.listen.com User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.0/17759 Icy-Metadata: 1 [08-03-29 12:18:54.4965] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 12:18:54.8412] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "7b755f-3645f4-43c31bf6" Accept-Ranges: bytes Content-Length: 3556852 Content-Type: application/octet-stream Age: 124244 Date: Sat, 29 Mar 2008 09:18:32 GMT Last-Modified: Tue, 10 Jan 2006 02:29:10 GMT Connection: close [08-03-29 12:18:54.8426] Slim::Player::Squeezebox2::directHeaders (343) Processing 9 headers [08-03-29 12:18:54.8436] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [08-03-29 12:18:54.8445] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "7b755f-3645f4-43c31bf6" [08-03-29 12:18:54.8454] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 12:18:54.8464] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3556852 [08-03-29 12:18:54.9904] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 12:18:54.9917] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 12:18:56.8644] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [08-03-29 12:18:56.9326] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 264) [08-03-29 12:18:56.9345] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 132) [08-03-29 12:18:56.9427] Slim::Player::Protocols::MMS::setMetadata (271) Setting bitrate to 128 from WMA bitrate properties object