[08-03-29 15:34:15.9823] Slim::Player::Source::decoderUnderrun (582) 00:04:20:07:6e:1a: Decoder underrun while this mode: playout-play [08-03-29 15:34:15.9840] Slim::Player::Source::nextsong (1556) The next song is number 97, was 96 [08-03-29 15:34:15.9863] Slim::Player::Source::nextsong (1556) The next song is number 97, was 96 [08-03-29 15:34:15.9878] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (330 seconds)... [08-03-29 15:34:15.9908] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 15:34:17.4171] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\4" [08-03-29 15:34:17.4205] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 15:34:18.5567] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw8.listen.com/u3/0/2/7/0/8/16780720.rad" [08-03-29 15:34:22.0836] Slim::Player::Source::skipahead (873) **skipahead: opening next song [08-03-29 15:34:22.0846] Slim::Player::Source::gotoNext (1206) Opening next song... [08-03-29 15:34:22.0857] Slim::Player::Source::nextsong (1556) The next song is number 97, was 96 [08-03-29 15:34:22.0891] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:07:6e:1a [08-03-29 15:34:22.0901] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:07:6e:1a [08-03-29 15:34:22.0910] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:07:6e:1a [08-03-29 15:34:22.0918] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [08-03-29 15:34:22.0927] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [08-03-29 15:34:22.0935] Slim::Player::TranscodingHelper::checkBin (146) enabled [08-03-29 15:34:22.0944] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [08-03-29 15:34:22.0956] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [08-03-29 15:34:22.0965] Slim::Player::Source::gotoNext (1293) opening next song (old format: wma, new: wma) current playmode: playout-play [08-03-29 15:34:22.0974] Slim::Player::Source::streamingSongIndex (1350) Adding song index 97 to song queue [08-03-29 15:34:22.0986] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 97,96 [08-03-29 15:34:22.0995] Slim::Player::Source::skipahead (879) **skipahead: restarting [08-03-29 15:34:22.1004] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from playout-play [08-03-29 15:34:22.1016] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 15:34:22.1131] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.348167.wma [08-03-29 15:34:22.1651] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 15:34:22.1805] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.348167.wma as http://llnw8.listen.com/u3/0/2/7/0/8/16780720.rad, let's do it. [08-03-29 15:34:22.7648] Slim::Player::Squeezebox::stream (1044) setting up direct stream (3496968516:80) autostart: 3. [08-03-29 15:34:22.7657] Slim::Player::Squeezebox::stream (1045) request string: GET /u3/0/2/7/0/8/16780720.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw8.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 15:34:22.7707] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 15:34:23.1082] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "6fb690-33a844-3f9239b9" Accept-Ranges: bytes Content-Length: 3385412 Content-Type: application/octet-stream Age: 135661 Date: Sat, 29 Mar 2008 12:34:00 GMT Last-Modified: Sun, 19 Oct 2003 07:14:01 GMT Connection: close [08-03-29 15:34:23.1095] Slim::Player::Squeezebox2::directHeaders (343) Processing 9 headers [08-03-29 15:34:23.1104] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [08-03-29 15:34:23.1115] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "6fb690-33a844-3f9239b9" [08-03-29 15:34:23.1124] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 15:34:23.1132] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3385412 [08-03-29 15:34:23.2653] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 15:34:23.2665] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 15:34:25.1430] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [08-03-29 15:34:25.1731] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 268) [08-03-29 15:34:25.9498] Slim::Player::Source::trackStartEvent (1461) Got a track starting event [08-03-29 15:34:25.9508] Slim::Player::Source::trackStartEvent (1468) Song 96 had already started, so it's not longer in the queue [08-03-29 15:34:25.9517] Slim::Player::Source::trackStartEvent (1477) Song 97 has now started playing [08-03-29 15:34:25.9665] Slim::Player::Source::trackStartEvent (1505) Song queue is now 97 [08-03-29 15:37:47.7003] Slim::Player::Source::decoderUnderrun (582) 00:04:20:07:6e:1a: Decoder underrun while this mode: playout-play [08-03-29 15:37:47.7014] Slim::Player::Source::nextsong (1556) The next song is number 98, was 97 [08-03-29 15:37:47.7030] Slim::Player::Source::nextsong (1556) The next song is number 98, was 97 [08-03-29 15:37:47.7043] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (211 seconds)... [08-03-29 15:37:47.7071] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 15:37:49.0277] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\4" [08-03-29 15:37:49.0311] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 15:37:50.1735] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw-278.listen.com/7/8/8/2/2/25422887.rad" [08-03-29 15:37:50.6535] Slim::Player::Source::skipahead (873) **skipahead: opening next song [08-03-29 15:37:50.6545] Slim::Player::Source::gotoNext (1206) Opening next song... [08-03-29 15:37:50.6564] Slim::Player::Source::nextsong (1556) The next song is number 98, was 97 [08-03-29 15:37:50.6596] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:07:6e:1a [08-03-29 15:37:50.6607] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:07:6e:1a [08-03-29 15:37:50.6615] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:07:6e:1a [08-03-29 15:37:50.6624] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [08-03-29 15:37:50.6632] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [08-03-29 15:37:50.6641] Slim::Player::TranscodingHelper::checkBin (146) enabled [08-03-29 15:37:50.6727] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [08-03-29 15:37:50.6741] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [08-03-29 15:37:50.6775] Slim::Player::Source::gotoNext (1293) opening next song (old format: wma, new: wma) current playmode: playout-play [08-03-29 15:37:50.6785] Slim::Player::Source::streamingSongIndex (1350) Adding song index 98 to song queue [08-03-29 15:37:50.6794] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 98,97 [08-03-29 15:37:50.6803] Slim::Player::Source::skipahead (879) **skipahead: restarting [08-03-29 15:37:50.6812] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from playout-play [08-03-29 15:37:50.6825] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 15:37:50.6932] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.2033975.wma [08-03-29 15:37:50.7614] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 15:37:50.7770] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.2033975.wma as http://llnw-278.listen.com/7/8/8/2/2/25422887.rad, let's do it. [08-03-29 15:37:51.1096] Slim::Player::Squeezebox::stream (1044) setting up direct stream (1150180330:80) autostart: 3. [08-03-29 15:37:51.1106] Slim::Player::Squeezebox::stream (1045) request string: GET /7/8/8/2/2/25422887.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw-278.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 15:37:51.1158] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 15:37:51.4614] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "c60a24-34101c-43271244" Accept-Ranges: bytes Content-Length: 3411996 Content-Type: application/octet-stream Age: 222494 Date: Sat, 29 Mar 2008 12:37:29 GMT Last-Modified: Tue, 13 Sep 2005 17:54:12 GMT Connection: close [08-03-29 15:37:51.4627] Slim::Player::Squeezebox2::directHeaders (343) Processing 9 headers [08-03-29 15:37:51.4636] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [08-03-29 15:37:51.4651] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "c60a24-34101c-43271244" [08-03-29 15:37:51.4660] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 15:37:51.4864] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3411996 [08-03-29 15:37:51.6274] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 15:37:51.6283] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 15:37:53.4966] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [08-03-29 15:37:53.5267] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 262) [08-03-29 15:37:53.5302] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 132) [08-03-29 15:37:53.5464] Slim::Player::Protocols::MMS::setMetadata (271) Setting bitrate to 128 from WMA bitrate properties object [08-03-29 15:37:57.6836] Slim::Player::Source::trackStartEvent (1461) Got a track starting event [08-03-29 15:37:57.6846] Slim::Player::Source::trackStartEvent (1468) Song 97 had already started, so it's not longer in the queue [08-03-29 15:37:57.6855] Slim::Player::Source::trackStartEvent (1477) Song 98 has now started playing [08-03-29 15:37:57.7019] Slim::Player::Source::trackStartEvent (1505) Song queue is now 98 [08-03-29 15:41:21.2773] Slim::Player::Source::decoderUnderrun (582) 00:04:20:07:6e:1a: Decoder underrun while this mode: playout-play [08-03-29 15:41:21.2784] Slim::Player::Source::nextsong (1556) The next song is number 99, was 98 [08-03-29 15:41:21.2798] Slim::Player::Source::nextsong (1556) The next song is number 99, was 98 [08-03-29 15:41:21.2821] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (213 seconds)... [08-03-29 15:41:21.2848] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 15:41:22.7559] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\4" [08-03-29 15:41:22.7621] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 15:41:31.2299] Slim::Player::Source::underrun (681) 00:04:20:07:6e:1a: Underrun while this mode: playout-play [08-03-29 15:41:31.2314] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onUnderrun (594) Underrun, stopping, playmode: playout-play [08-03-29 15:41:31.2334] Slim::Player::Source::nextsong (1556) The next song is number 99, was 98 [08-03-29 15:41:31.2345] Slim::Player::Source::skipahead (873) **skipahead: opening next song [08-03-29 15:41:31.2354] Slim::Player::Source::gotoNext (1206) Opening next song... [08-03-29 15:41:31.2364] Slim::Player::Source::nextsong (1556) The next song is number 99, was 98 [08-03-29 15:41:31.2396] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:07:6e:1a [08-03-29 15:41:31.2739] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:07:6e:1a [08-03-29 15:41:31.2749] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:07:6e:1a [08-03-29 15:41:31.2757] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [08-03-29 15:41:31.2766] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [08-03-29 15:41:31.2783] Slim::Player::TranscodingHelper::checkBin (146) enabled [08-03-29 15:41:31.2791] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [08-03-29 15:41:31.2804] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [08-03-29 15:41:31.2820] Slim::Player::Source::gotoNext (1293) opening next song (old format: wma, new: wma) current playmode: playout-play [08-03-29 15:41:31.2830] Slim::Player::Source::streamingSongIndex (1350) Adding song index 99 to song queue [08-03-29 15:41:31.2883] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 99,98 [08-03-29 15:41:31.2892] Slim::Player::Source::skipahead (879) **skipahead: restarting [08-03-29 15:41:31.2901] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from playout-play [08-03-29 15:41:31.2914] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 15:41:31.3043] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.1893479.wma [08-03-29 15:41:31.3835] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 15:41:31.3995] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.1893479.wma as http://llnw-278.listen.com/7/8/8/2/2/25422887.rad, let's do it. [08-03-29 15:41:31.4042] Slim::Player::Squeezebox::stream (1044) setting up direct stream (1150180330:80) autostart: 3. [08-03-29 15:41:31.4051] Slim::Player::Squeezebox::stream (1045) request string: GET /7/8/8/2/2/25422887.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw-278.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 15:41:31.4081] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 15:41:31.4120] Slim::Plugin::RhapsodyDirect::ProtocolHandler::__ANON__ (624) Skipping to next track (99) [08-03-29 15:41:31.4154] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (452) Handling command "jump", playmode: playout-play [08-03-29 15:41:31.4219] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (482) Track skip, logging usage info (213 seconds)... [08-03-29 15:41:31.4253] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 15:41:31.4595] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\xFC" [08-03-29 15:41:31.4611] 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 15:41:31.7423] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "c60a24-34101c-43271244" Accept-Ranges: bytes Content-Length: 3411996 Content-Type: application/octet-stream Age: 222714 Date: Sat, 29 Mar 2008 12:41:09 GMT Last-Modified: Tue, 13 Sep 2005 17:54:12 GMT Connection: close [08-03-29 15:41:31.7433] Slim::Player::Squeezebox2::directHeaders (343) Processing 9 headers [08-03-29 15:41:31.7441] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [08-03-29 15:41:31.7447] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "c60a24-34101c-43271244" [08-03-29 15:41:31.7526] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 15:41:31.7534] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3411996 [08-03-29 15:41:31.9431] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 15:41:31.9441] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 15:41:33.4642] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (317) 00:04:20:07:6e:1a Re-sending RPDS packet: "\4\x03213" [08-03-29 15:41:33.4657] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 15:41:33.4762] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\xFC" [08-03-29 15:41:33.4768] 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 15:41:33.9309] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw-256.listen.com/5/6/0/9/0/25409065.rad" [08-03-29 15:41:33.9315] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (290) 00:04:20:07:6e:1a Ignoring unrequested or old RPDS packet (got 3, expected N/A) [08-03-29 15:41:35.5044] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (317) 00:04:20:07:6e:1a Re-sending RPDS packet: "\4\x03213" [08-03-29 15:41:35.5065] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 15:41:36.9530] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\4" [08-03-29 15:41:36.9546] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 15:41:38.2586] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw-256.listen.com/5/6/0/9/0/25409065.rad" [08-03-29 15:41:38.7374] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode stop from playout-play [08-03-29 15:41:38.7386] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: stop [08-03-29 15:41:38.7390] Slim::Player::Source::playmode (444) 00:04:20:07:6e:1a: Stopping and clearing out old chunks [08-03-29 15:41:38.7402] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 15:41:38.7529] Slim::Player::Source::resetSongQueue (1424) Resetting song queue [08-03-29 15:41:38.7534] Slim::Player::Source::resetSongQueue (1434) Song queue is now 98 [08-03-29 15:41:38.7542] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: stop [08-03-29 15:41:38.7736] Slim::Player::Source::jumpto (1162) Jumping to 99 [08-03-29 15:41:38.7741] Slim::Player::Source::streamingSongIndex (1350) Adding song index 99 to song queue [08-03-29 15:41:38.7745] Slim::Player::Source::streamingSongIndex (1354) Clearing out song queue first [08-03-29 15:41:38.7918] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 99 [08-03-29 15:41:38.7927] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from stop [08-03-29 15:41:38.7933] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 15:41:38.8125] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.1893479.wma [08-03-29 15:41:38.8739] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 15:41:38.9132] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.1893479.wma as http://llnw-256.listen.com/5/6/0/9/0/25409065.rad, let's do it. [08-03-29 15:41:39.2820] Slim::Player::Squeezebox::stream (1044) setting up direct stream (3496968332:80) autostart: 3. [08-03-29 15:41:39.2824] Slim::Player::Squeezebox::stream (1045) request string: GET /5/6/0/9/0/25409065.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw-256.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 15:41:39.2841] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 15:41:39.3109] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 49152 [08-03-29 15:41:39.6322] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "c84264-394f8c-4327112b" Accept-Ranges: bytes Content-Length: 3755916 Content-Type: application/octet-stream Age: 154990 Date: Sat, 29 Mar 2008 12:41:17 GMT Last-Modified: Tue, 13 Sep 2005 17:49:31 GMT Connection: close [08-03-29 15:41:39.6327] Slim::Player::Squeezebox2::directHeaders (343) Processing 9 headers [08-03-29 15:41:39.6331] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [08-03-29 15:41:39.6334] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "c84264-394f8c-4327112b" [08-03-29 15:41:39.6337] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 15:41:39.6340] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3755916 [08-03-29 15:41:39.6435] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 15:41:39.6440] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 15:41:39.7176] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 49152 [08-03-29 15:41:40.1485] Slim::Player::Squeezebox::buffering (303) Buffering... 1170 / 49152 [08-03-29 15:41:40.6002] Slim::Player::Squeezebox::buffering (303) Buffering... 1170 / 49152 [08-03-29 15:41:41.1209] Slim::Player::Squeezebox::buffering (303) Buffering... 1170 / 49152 [08-03-29 15:41:41.5258] Slim::Player::Squeezebox::buffering (303) Buffering... 1170 / 49152 [08-03-29 15:41:41.9365] Slim::Player::Squeezebox::buffering (303) Buffering... 24402 / 49152 [08-03-29 15:41:42.2550] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [08-03-29 15:41:42.3051] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 262) [08-03-29 15:41:42.3661] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 132) [08-03-29 15:41:42.4063] Slim::Player::Protocols::MMS::setMetadata (271) Setting bitrate to 128 from WMA bitrate properties object [08-03-29 15:41:42.4081] Slim::Player::Squeezebox::buffering (303) Buffering... 37470 / 49152 [08-03-29 15:41:42.5624] Slim::Player::Source::decoderUnderrun (582) 00:04:20:07:6e:1a: Decoder underrun while this mode: playout-play [08-03-29 15:41:42.5629] Slim::Player::Source::nextsong (1556) The next song is number 100, was 99 [08-03-29 15:41:42.5634] Slim::Player::Source::decoderUnderrun (595) Track failed before playback, marking as played [08-03-29 15:41:42.5638] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode playout-play from playout-play [08-03-29 15:41:42.5641] Slim::Player::Source::playmode (305) Already in playmode playout-play : ignoring mode change [08-03-29 15:41:42.5669] Slim::Player::Source::nextsong (1556) The next song is number 100, was 99 [08-03-29 15:41:42.5676] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (235 seconds)... [08-03-29 15:41:42.5690] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 15:41:42.5775] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\xFC" [08-03-29 15:41:42.5781] 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 15:41:42.8192] Slim::Player::Squeezebox::buffering (303) Buffering... 55218 / 49152 [08-03-29 15:41:44.5806] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (317) 00:04:20:07:6e:1a Re-sending RPDS packet: "\4\x03235" [08-03-29 15:41:44.5830] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 4 [08-03-29 15:41:45.8264] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\4" [08-03-29 15:41:45.8297] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 15:41:45.8310] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2044931 from SqueezeNetwork [08-03-29 15:41:46.9699] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw-267.listen.com/6/7/0/3/2/25423076.rad" [08-03-29 15:41:47.3460] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.230594", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/9/7/6/2/392679_170x170.jpg", albumId => "Alb.230594", albumType => 0, displayName => "Now That I've Found You: A Collection", "displayableAlbumTypes" => [], name => "Now That I've Found You: A Collection", "primaryArtistDisplayName" => "Alison Krauss", primaryArtistId => "Art.5816", primaryStyle => "Folk", releaseYear => 1995, rightFlags => 62, shortcut => "nowthativefoundyouacollection", }, artistId => "Art.5816", blurbs => [""], discIndex => 1, displayAlbumName => "Now That I've Found You: A Collection", displayArtistName => "Alison Krauss", genreId => "g.446", name => "In The Palm Of Your Hand", playbackSeconds => 205, price => undef, "primaryArtistMetadata" => { artistId => "Art.5816", name => "Alison Krauss", rightFlags => 62, shortcut => "alisonkrauss", }, rightFlags => 62, trackId => "Tra.2044931", trackIndex => 11, } [08-03-29 15:41:47.4565] Slim::Player::Source::skipahead (873) **skipahead: opening next song [08-03-29 15:41:47.4574] Slim::Player::Source::gotoNext (1206) Opening next song... [08-03-29 15:41:47.4585] Slim::Player::Source::nextsong (1556) The next song is number 100, was 99 [08-03-29 15:41:47.4621] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:07:6e:1a [08-03-29 15:41:47.4631] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:07:6e:1a [08-03-29 15:41:47.4640] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:07:6e:1a [08-03-29 15:41:47.4649] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [08-03-29 15:41:47.4657] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [08-03-29 15:41:47.4666] Slim::Player::TranscodingHelper::checkBin (146) enabled [08-03-29 15:41:47.4674] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [08-03-29 15:41:47.4686] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [08-03-29 15:41:47.4696] Slim::Player::Source::gotoNext (1293) opening next song (old format: wma, new: wma) current playmode: playout-play [08-03-29 15:41:47.4704] Slim::Player::Source::streamingSongIndex (1350) Adding song index 100 to song queue [08-03-29 15:41:47.4716] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 100,99 [08-03-29 15:41:47.4725] Slim::Player::Source::skipahead (879) **skipahead: restarting [08-03-29 15:41:47.4734] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from playout-play [08-03-29 15:41:47.4746] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 15:41:47.4858] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.2044931.wma [08-03-29 15:41:47.5466] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 15:41:47.5636] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.2044931.wma as http://llnw-267.listen.com/6/7/0/3/2/25423076.rad, let's do it. [08-03-29 15:41:48.0048] Slim::Player::Squeezebox::stream (1044) setting up direct stream (1150180299:80) autostart: 3. [08-03-29 15:41:48.0058] Slim::Player::Squeezebox::stream (1045) request string: GET /6/7/0/3/2/25423076.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw-267.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 15:41:48.0111] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 15:41:48.3815] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "c61473-321284-432715fc" Accept-Ranges: bytes Content-Length: 3281540 Content-Type: application/octet-stream Age: 164785 Date: Sat, 29 Mar 2008 12:41:26 GMT Last-Modified: Tue, 13 Sep 2005 18:10:04 GMT Connection: close [08-03-29 15:41:48.3822] Slim::Player::Squeezebox2::directHeaders (343) Processing 9 headers [08-03-29 15:41:48.3825] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [08-03-29 15:41:48.4017] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "c61473-321284-432715fc" [08-03-29 15:41:48.4415] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 15:41:48.4420] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3281540 [08-03-29 15:41:48.5654] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 15:41:48.5659] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 15:41:50.6044] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [08-03-29 15:41:50.6386] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 262) [08-03-29 15:41:50.6419] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 132) [08-03-29 15:41:50.6591] Slim::Player::Protocols::MMS::setMetadata (271) Setting bitrate to 128 from WMA bitrate properties object [08-03-29 16:51:55.8853] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onJump (452) Handling command "jump", playmode: playout-play [08-03-29 16:51:55.9034] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:07:6e:1a RPDS packet sent: 3 [08-03-29 16:51:59.4408] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:07:6e:1a Got RPDS packet: \"\3\x001http://llnw-267.listen.com/6/7/0/3/2/25423076.rad" [08-03-29 16:51:59.7910] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode stop from playout-play [08-03-29 16:51:59.7932] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: stop [08-03-29 16:51:59.7942] Slim::Player::Source::playmode (444) 00:04:20:07:6e:1a: Stopping and clearing out old chunks [08-03-29 16:51:59.7995] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 16:51:59.8007] Slim::Player::Source::resetSongQueue (1424) Resetting song queue [08-03-29 16:51:59.8017] Slim::Player::Source::resetSongQueue (1434) Song queue is now 99 [08-03-29 16:51:59.8034] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: stop [08-03-29 16:51:59.8383] Slim::Player::Source::jumpto (1156) Jumping by +1 [08-03-29 16:51:59.8394] Slim::Player::Source::streamingSongIndex (1350) Adding song index 100 to song queue [08-03-29 16:51:59.8403] Slim::Player::Source::streamingSongIndex (1354) Clearing out song queue first [08-03-29 16:51:59.8413] Slim::Player::Source::streamingSongIndex (1371) Song queue is now 100 [08-03-29 16:51:59.8429] Slim::Player::Source::playmode (298) 00:04:20:07:6e:1a: Switching to mode play from stop [08-03-29 16:51:59.8443] Slim::Player::Source::resetSong (1595) Resetting song buffer. [08-03-29 16:51:59.8551] Slim::Player::Source::openSong (1690) Trying to open: rhapd://Tra.2044931.wma [08-03-29 16:51:59.8989] Slim::Player::Source::playmode (401) 00:04:20:07:6e:1a New play mode: play [08-03-29 16:51:59.9150] Slim::Player::Squeezebox::stream (1013) This player supports direct streaming for rhapd://Tra.2044931.wma as http://llnw-267.listen.com/6/7/0/3/2/25423076.rad, let's do it. [08-03-29 16:51:59.9218] Slim::Player::Squeezebox::stream (1044) setting up direct stream (1150180299:80) autostart: 3. [08-03-29 16:51:59.9231] Slim::Player::Squeezebox::stream (1045) request string: GET /6/7/0/3/2/25423076.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw-267.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 16:51:59.9292] Slim::Player::Source::playmode (556) 00:04:20:07:6e:1a: Current playmode: play [08-03-29 16:52:00.0406] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 49152 [08-03-29 16:52:00.2654] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "c61473-321284-432715fc" Accept-Ranges: bytes Content-Length: 3281540 Content-Type: application/octet-stream Age: 168997 Date: Sat, 29 Mar 2008 13:51:38 GMT Last-Modified: Tue, 13 Sep 2005 18:10:04 GMT Connection: close [08-03-29 16:52:00.2667] Slim::Player::Squeezebox2::directHeaders (343) Processing 9 headers [08-03-29 16:52:00.2678] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [08-03-29 16:52:00.2687] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "c61473-321284-432715fc" [08-03-29 16:52:00.2697] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [08-03-29 16:52:00.2706] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3281540 [08-03-29 16:52:00.2896] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [08-03-29 16:52:00.2909] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [08-03-29 16:52:00.4535] Slim::Player::Squeezebox::buffering (303) Buffering... 0 / 49152 [08-03-29 16:52:00.8646] Slim::Player::Squeezebox::buffering (303) Buffering... 1170 / 49152 [08-03-29 16:52:01.2743] Slim::Player::Squeezebox::buffering (303) Buffering... 1170 / 49152 [08-03-29 16:52:01.6849] Slim::Player::Squeezebox::buffering (303) Buffering... 8429 / 49152 [08-03-29 16:52:02.0950] Slim::Player::Squeezebox::buffering (303) Buffering... 18593 / 49152 [08-03-29 16:52:02.5075] Slim::Player::Squeezebox::buffering (303) Buffering... 31661 / 49152 [08-03-29 16:52:02.5501] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [08-03-29 16:52:02.5769] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 262) [08-03-29 16:52:02.5801] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 132) [08-03-29 16:52:02.6093] Slim::Player::Protocols::MMS::setMetadata (271) Setting bitrate to 128 from WMA bitrate properties object [08-03-29 16:52:02.9171] Slim::Player::Squeezebox::buffering (303) Buffering... 46181 / 49152 [08-03-29 16:52:03.3374] Slim::Player::Squeezebox::buffering (303) Buffering... 63065 / 49152 [08-03-29 16:52:03.4815] Slim::Player::Source::trackStartEvent (1461) Got a track starting event [08-03-29 16:52:03.4825] Slim::Player::Source::trackStartEvent (1477) Song 100 has now started playing [08-03-29 16:52:03.4853] Slim::Player::Source::trackStartEvent (1505) Song queue is now 100 [08-03-29 16:52:04.2574] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.13954756 from SqueezeNetwork [08-03-29 16:52:04.2912] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.714544 from SqueezeNetwork [08-03-29 16:52:04.3547] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.1917878 from SqueezeNetwork [08-03-29 16:52:04.3948] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.1999246 from SqueezeNetwork [08-03-29 16:52:04.4225] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2780929 from SqueezeNetwork [08-03-29 16:52:04.4854] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.7673338 from SqueezeNetwork [08-03-29 16:52:04.5170] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.11279557 from SqueezeNetwork [08-03-29 16:52:04.5453] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.3032819 from SqueezeNetwork [08-03-29 16:52:04.5829] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.8904357 from SqueezeNetwork [08-03-29 16:52:04.6411] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.13995230 from SqueezeNetwork [08-03-29 16:52:04.6767] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.14455411 from SqueezeNetwork [08-03-29 16:52:04.7083] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.6822505 from SqueezeNetwork [08-03-29 16:52:04.7436] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.10453159 from SqueezeNetwork [08-03-29 16:52:04.8044] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.10442994 from SqueezeNetwork [08-03-29 16:52:04.8390] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.12945409 from SqueezeNetwork [08-03-29 16:52:04.8964] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.10449758 from SqueezeNetwork [08-03-29 16:52:04.9550] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.3039229 from SqueezeNetwork [08-03-29 16:52:04.9873] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.7067742 from SqueezeNetwork [08-03-29 16:52:05.0787] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2242855 from SqueezeNetwork [08-03-29 16:52:05.1074] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.6644727 from SqueezeNetwork [08-03-29 16:52:05.1449] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.6644732 from SqueezeNetwork [08-03-29 16:52:05.1753] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.7310133 from SqueezeNetwork [08-03-29 16:52:05.2459] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.1782268 from SqueezeNetwork [08-03-29 16:52:05.3466] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.15970343 from SqueezeNetwork [08-03-29 16:52:05.4041] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.8904648 from SqueezeNetwork [08-03-29 16:52:05.4374] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.1900886 from SqueezeNetwork [08-03-29 16:52:05.4703] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.1891910 from SqueezeNetwork [08-03-29 16:52:05.5351] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.6185582 from SqueezeNetwork [08-03-29 16:52:05.5686] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.6185564 from SqueezeNetwork [08-03-29 16:52:05.6000] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.6185565 from SqueezeNetwork [08-03-29 16:52:05.6338] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.1880274 from SqueezeNetwork [08-03-29 16:52:05.6981] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.10348150 from SqueezeNetwork [08-03-29 16:52:05.7297] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.10348152 from SqueezeNetwork [08-03-29 16:52:05.7649] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.10348158 from SqueezeNetwork [08-03-29 16:52:05.7958] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.10348160 from SqueezeNetwork [08-03-29 16:52:05.8908] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.794816 from SqueezeNetwork [08-03-29 16:52:05.9919] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2024880 from SqueezeNetwork [08-03-29 16:52:44.5650] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.227633", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/4/2/9/2/392924_170x170.jpg", albumId => "Alb.227633", albumType => 0, displayName => "The First Million Miles", "displayableAlbumTypes" => [], name => "The First Million Miles", "primaryArtistDisplayName" => "Bill Staines", primaryArtistId => "Art.4686", primaryStyle => "New Folk", releaseYear => 1989, rightFlags => 62, shortcut => "thefirstmillionmiles", }, artistId => "Art.4686", blurbs => [""], discIndex => 1, displayAlbumName => "The First Million Miles", displayArtistName => "Bill Staines", genreId => "g.148", name => "Old Silver", playbackSeconds => 213, price => undef, "primaryArtistMetadata" => { artistId => "Art.4686", name => "Bill Staines", rightFlags => 62, shortcut => "billstaines", }, rightFlags => 62, trackId => "Tra.2024880", trackIndex => 6, } [08-03-29 16:52:45.0385] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.111386", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/4/7/4/454743_170x170.jpg", albumId => "Alb.111386", albumType => 0, displayName => "Diamonds In The Rough", "displayableAlbumTypes" => [], name => "Diamonds In The Rough", "primaryArtistDisplayName" => "Bill & Bonnie Hearne", primaryArtistId => "Art.63287", primaryStyle => "Country-Folk/Bluegrass", releaseYear => 2003, rightFlags => 62, shortcut => "diamondsintherough", }, artistId => "Art.63287", blurbs => [""], discIndex => 1, displayAlbumName => "Diamonds In The Rough", displayArtistName => "Bill & Bonnie Hearne", genreId => "g.132", name => "Wild Geese", playbackSeconds => 269, price => undef, "primaryArtistMetadata" => { artistId => "Art.63287", name => "Bill & Bonnie Hearne", rightFlags => 62, shortcut => "billbonniehearne", }, rightFlags => 62, trackId => "Tra.794816", trackIndex => 5, } [08-03-29 16:52:45.0432] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.10346527", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/0/3/7/837303_170x170.jpg", albumId => "Alb.10346527", albumType => 0, displayName => "West Of The West", "displayableAlbumTypes" => [], name => "West Of The West", "primaryArtistDisplayName" => "Dave Alvin", primaryArtistId => "Art.2569", primaryStyle => "Americana", releaseYear => 2006, rightFlags => 62, shortcut => "westofthewest", }, artistId => "Art.2569", blurbs => [""], discIndex => 1, displayAlbumName => "West Of The West", displayArtistName => "Dave Alvin", genreId => "g.350", name => "Surfer Girl", playbackSeconds => 181, price => undef, "primaryArtistMetadata" => { artistId => "Art.2569", name => "Dave Alvin", rightFlags => 62, shortcut => "davealvin", }, rightFlags => 62, trackId => "Tra.10348160", trackIndex => 13, } [08-03-29 16:52:45.0475] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.10346527", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/0/3/7/837303_170x170.jpg", albumId => "Alb.10346527", albumType => 0, displayName => "West Of The West", "displayableAlbumTypes" => [], name => "West Of The West", "primaryArtistDisplayName" => "Dave Alvin", primaryArtistId => "Art.2569", primaryStyle => "Americana", releaseYear => 2006, rightFlags => 62, shortcut => "westofthewest", }, artistId => "Art.2569", blurbs => [""], discIndex => 1, displayAlbumName => "West Of The West", displayArtistName => "Dave Alvin", genreId => "g.350", name => "Tramps And Hawkers", playbackSeconds => 275, price => undef, "primaryArtistMetadata" => { artistId => "Art.2569", name => "Dave Alvin", rightFlags => 62, shortcut => "davealvin", }, rightFlags => 62, trackId => "Tra.10348158", trackIndex => 11, } [08-03-29 16:52:45.0518] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.10346527", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/0/3/7/837303_170x170.jpg", albumId => "Alb.10346527", albumType => 0, displayName => "West Of The West", "displayableAlbumTypes" => [], name => "West Of The West", "primaryArtistDisplayName" => "Dave Alvin", primaryArtistId => "Art.2569", primaryStyle => "Americana", releaseYear => 2006, rightFlags => 62, shortcut => "westofthewest", }, artistId => "Art.2569", blurbs => [""], discIndex => 1, displayAlbumName => "West Of The West", displayArtistName => "Dave Alvin", genreId => "g.350", name => "Here In California", playbackSeconds => 275, price => undef, "primaryArtistMetadata" => {