[ [14:08:29.5948] Slim::Player::Source::playmode (298) 00:04:20:06:1a:f7: Switching to mode stop from stop [14:08:29.5953] Slim::Player::Source::playmode (305) Already in playmode stop : ignoring mode change [14:08:29.5960] Slim::Player::Source::streamingSongIndex (1380) Adding song index 0 to song queue [14:08:29.5964] Slim::Player::Source::streamingSongIndex (1384) Clearing out song queue first [14:08:29.5969] Slim::Player::Source::streamingSongIndex (1401) Song queue is now 0 [14:08:29.6627] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onCommand (173) RhapsodyDirect: Handling command 'play' [14:08:29.6634] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onCommand (207) Getting Rhapsody account from SqueezeNetwork [14:08:29.9847] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotAccount (294) Got Rhapsody account info from SN [14:08:29.9852] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onCommand (173) RhapsodyDirect: Handling command 'play' [14:08:29.9857] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onCommand (214) Ending any previous playback session [14:08:29.9880] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:06:1a:f7 RPDS packet sent: 6 [14:08:29.9897] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:06:1a:f7 Got RPDS packet: \"\xFE" [14:08:29.9906] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getPlaybackSession (250) 00:04:20:06:1a:f7 Requesting new playback session... [14:08:29.9951] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:06:1a:f7 RPDS packet sent: 2 [14:08:32.4306] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:06:1a:f7 Got RPDS packet: \"\2" [14:08:32.4315] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (303) New playback session obtained, resetting sessionErrors count to 0 [14:08:32.4320] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotPlaybackSession (346) New playback session started [14:08:32.4337] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:06:1a:f7 RPDS packet sent: 3 [14:08:32.4346] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088013 from SqueezeNetwork [14:08:32.7555] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "Death On Two Legs (Dedicated To.....", playbackSeconds => 223, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088013", trackIndex => 1, } [14:08:32.8544] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:06:1a:f7 Got RPDS packet: \"\3\x001http://llnw3.listen.com/u3/3/3/0/7/9/28297033.rad" [14:08:32.8825] Slim::Player::Source::playmode (298) 00:04:20:06:1a:f7: Switching to mode stop from stop [14:08:32.8830] Slim::Player::Source::playmode (305) Already in playmode stop : ignoring mode change [14:08:32.8835] Slim::Player::Source::jumpto (1192) Jumping to 0 [14:08:32.8839] Slim::Player::Source::streamingSongIndex (1380) Adding song index 0 to song queue [14:08:32.8844] Slim::Player::Source::streamingSongIndex (1384) Clearing out song queue first [14:08:32.8848] Slim::Player::Source::streamingSongIndex (1401) Song queue is now 0 [14:08:32.8857] Slim::Player::Source::playmode (298) 00:04:20:06:1a:f7: Switching to mode play from stop [14:08:32.8865] Slim::Player::Source::resetSong (1625) Resetting song buffer. [14:08:32.8881] Slim::Player::Source::openSong (1720) Trying to open: rhapd://Tra.2088013.wma [14:08:32.9243] Slim::Player::Source::playmode (401) 00:04:20:06:1a:f7 New play mode: play [14:08:32.9364] Slim::Player::Squeezebox::stream (996) This player supports direct streaming for rhapd://Tra.2088013.wma as http://llnw3.listen.com/u3/3/3/0/7/9/28297033.rad, let's do it. [14:08:33.0344] Slim::Player::Squeezebox::stream (1027) setting up direct stream (3496976738:80) autostart: 3. [14:08:33.0349] Slim::Player::Squeezebox::stream (1028) request string: GET /u3/3/3/0/7/9/28297033.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; cp1252) SqueezeCenter/7.0.1/17981 Icy-Metadata: 1 [14:08:33.0367] Slim::Player::Source::playmode (556) 00:04:20:06:1a:f7: Current playmode: play [14:08:33.1350] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "700e36-36c6fc-4181716c" Accept-Ranges: bytes Content-Length: 3589884 Content-Type: application/octet-stream Age: 84445 Date: Fri, 28 Mar 2008 18:06:24 GMT Last-Modified: Thu, 28 Oct 2004 22:23:40 GMT Connection: close [14:08:33.1357] Slim::Player::Squeezebox2::directHeaders (343) Processing 9 headers [14:08:33.1364] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [14:08:33.1369] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "700e36-36c6fc-4181716c" [14:08:33.1374] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [14:08:33.1378] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 3589884 [14:08:33.1786] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [14:08:33.1794] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [14:08:33.1926] Slim::Player::Squeezebox::buffering (286) Buffering... 0 / 49152 [14:08:33.6257] Slim::Player::Squeezebox::buffering (286) Buffering... 1179 / 49152 [14:08:33.8293] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [14:08:33.9042] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 262) [14:08:33.9059] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 132) [14:08:33.9149] Slim::Player::Protocols::MMS::setMetadata (271) Setting bitrate to 128 from WMA bitrate properties object [14:08:33.9663] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088014 from SqueezeNetwork [14:08:34.1084] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088017 from SqueezeNetwork [14:08:34.2246] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088018 from SqueezeNetwork [14:08:34.3462] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088019 from SqueezeNetwork [14:08:34.4655] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088020 from SqueezeNetwork [14:08:34.5950] Slim::Player::Source::trackStartEvent (1491) Got a track starting event [14:08:34.5955] Slim::Player::Source::trackStartEvent (1507) Song 0 has now started playing [14:08:34.6073] Slim::Player::Source::trackStartEvent (1535) Song queue is now 0 [14:08:34.6081] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088021 from SqueezeNetwork [14:08:34.7244] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088022 from SqueezeNetwork [14:08:34.8444] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088023 from SqueezeNetwork [14:08:34.9636] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088024 from SqueezeNetwork [14:08:35.2101] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088027 from SqueezeNetwork [14:08:35.3931] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "Seaside Rendezvous", playbackSeconds => 134, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088020", trackIndex => 7, } [14:08:35.4045] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "Sweet Lady", playbackSeconds => 242, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088019", trackIndex => 6, } [14:08:35.4207] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "Love Of My Life", playbackSeconds => 217, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088022", trackIndex => 9, } [14:08:35.4262] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "Bohemian Rhapsody", playbackSeconds => 354, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088024", trackIndex => 11, } [14:08:35.4408] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "Good Company", playbackSeconds => 203, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088023", trackIndex => 10, } [14:08:35.4501] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "The Prophet's Song", playbackSeconds => 501, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088021", trackIndex => 8, } [14:08:35.4651] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "You're My Best Friend", playbackSeconds => 170, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088017", trackIndex => 4, } [14:08:35.4717] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "'39", playbackSeconds => 210, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088018", trackIndex => 5, } [14:08:35.4769] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "Lazing On A Sunday Afternoon", playbackSeconds => 67, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088014", trackIndex => 2, } [14:08:35.5230] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "You're My Best Friend (1991 Bonus Remix)", playbackSeconds => 172, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088027", trackIndex => 14, } [14:08:43.8669] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088015 from SqueezeNetwork [14:08:44.1157] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088025 from SqueezeNetwork [14:08:44.2565] Slim::Plugin::RhapsodyDirect::ProtocolHandler::getTrackMetadata (657) Getting track metadata for Tra.2088026 from SqueezeNetwork [14:08:44.6170] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "I'm In Love With My Car", playbackSeconds => 185, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088015", trackIndex => 3, } [14:08:44.6276] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "God Save The Queen", playbackSeconds => 73, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088025", trackIndex => 12, } [14:08:44.6381] Slim::Plugin::RhapsodyDirect::ProtocolHandler::gotTrackMetadata (673) Got track metadata: { albumId => "Alb.316712", albumMetadata => { albumArt162x162Url => "http://image.listen.com/img/170x170/3/5/0/2/1182053_170x170.jpg", albumId => "Alb.316712", albumType => 0, displayName => "A Night At The Opera", "displayableAlbumTypes" => [], name => "A Night At The Opera", "primaryArtistDisplayName" => "Queen", primaryArtistId => "Art.69088", primaryStyle => "Hard Rock", releaseYear => 1994, rightFlags => 62, shortcut => "anightattheopera", }, artistId => "Art.69088", blurbs => [""], discIndex => 1, displayAlbumName => "A Night At The Opera", displayArtistName => "Queen", genreId => "g.112", name => "I'm In Love With My Car (1991 Bonus Remix)", playbackSeconds => 207, price => undef, "primaryArtistMetadata" => { artistId => "Art.69088", name => "Queen", rightFlags => 62, shortcut => "queen", }, rightFlags => 62, trackId => "Tra.2088026", trackIndex => 13, } [14:12:08.5140] Slim::Player::Source::decoderUnderrun (582) 00:04:20:06:1a:f7: Decoder underrun while this mode: playout-play [14:12:08.5146] Slim::Player::Source::nextsong (1586) The next song is number 1, was 0 [14:12:08.5155] Slim::Player::Source::nextsong (1586) The next song is number 1, was 0 [14:12:08.5164] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (224 seconds)... [14:12:08.5181] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:06:1a:f7 RPDS packet sent: 4 [14:12:09.0439] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:06:1a:f7 Got RPDS packet: \"\4" [14:12:09.0459] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:06:1a:f7 RPDS packet sent: 3 [14:12:09.4986] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:06:1a:f7 Got RPDS packet: \"\3\x001http://llnw3.listen.com/u3/8/2/0/7/9/28297028.rad" [14:12:09.5001] Slim::Player::Source::skipahead (903) **skipahead: opening next song [14:12:09.5008] Slim::Player::Source::gotoNext (1236) Opening next song... [14:12:09.5016] Slim::Player::Source::nextsong (1586) The next song is number 1, was 0 [14:12:09.5028] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:06:1a:f7 [14:12:09.5033] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:06:1a:f7 [14:12:09.5038] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:06:1a:f7 [14:12:09.5042] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [14:12:09.5046] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [14:12:09.5051] Slim::Player::TranscodingHelper::enabledFormat (113) There are 11 disabled formats... [14:12:09.5056] Slim::Player::TranscodingHelper::enabledFormat (119) Testing aif-mp3-*-* vs wma-wma-squeezebox2-* [14:12:09.5060] Slim::Player::TranscodingHelper::enabledFormat (119) Testing alc-mp3-*-* vs wma-wma-squeezebox2-* [14:12:09.5064] Slim::Player::TranscodingHelper::enabledFormat (119) Testing ape-mp3-*-* vs wma-wma-squeezebox2-* [14:12:09.5069] Slim::Player::TranscodingHelper::enabledFormat (119) Testing flc-flc-*-* vs wma-wma-squeezebox2-* [14:12:09.5073] Slim::Player::TranscodingHelper::enabledFormat (119) Testing flc-mp3-*-* vs wma-wma-squeezebox2-* [14:12:09.5077] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mov-mp3-*-* vs wma-wma-squeezebox2-* [14:12:09.5082] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs wma-wma-squeezebox2-* [14:12:09.5086] Slim::Player::TranscodingHelper::enabledFormat (119) Testing ogg-mp3-*-* vs wma-wma-squeezebox2-* [14:12:09.5090] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wav-mp3-*-* vs wma-wma-squeezebox2-* [14:12:09.5095] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs wma-wma-squeezebox2-* [14:12:09.5099] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wvp-mp3-*-* vs wma-wma-squeezebox2-* [14:12:09.5105] Slim::Player::TranscodingHelper::checkBin (146) enabled [14:12:09.5110] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [14:12:09.5117] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [14:12:09.5123] Slim::Player::Source::gotoNext (1323) opening next song (old format: wma, new: wma) current playmode: playout-play [14:12:09.5127] Slim::Player::Source::streamingSongIndex (1380) Adding song index 1 to song queue [14:12:09.5132] Slim::Player::Source::streamingSongIndex (1401) Song queue is now 1,0 [14:12:09.5137] Slim::Player::Source::skipahead (909) **skipahead: restarting [14:12:09.5142] Slim::Player::Source::playmode (298) 00:04:20:06:1a:f7: Switching to mode play from playout-play [14:12:09.5149] Slim::Player::Source::resetSong (1625) Resetting song buffer. [14:12:09.5204] Slim::Player::Source::openSong (1720) Trying to open: rhapd://Tra.2088014.wma [14:12:09.5476] Slim::Player::Source::playmode (401) 00:04:20:06:1a:f7 New play mode: play [14:12:09.5555] Slim::Player::Squeezebox::stream (996) This player supports direct streaming for rhapd://Tra.2088014.wma as http://llnw3.listen.com/u3/8/2/0/7/9/28297028.rad, let's do it. [14:12:09.5809] Slim::Player::Squeezebox::stream (1027) setting up direct stream (3496976543:80) autostart: 3. [14:12:09.5815] Slim::Player::Squeezebox::stream (1028) request string: GET /u3/8/2/0/7/9/28297028.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; cp1252) SqueezeCenter/7.0.1/17981 Icy-Metadata: 1 [14:12:09.5832] Slim::Player::Source::playmode (556) 00:04:20:06:1a:f7: Current playmode: play [14:12:09.6708] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "58913a-10c69c-41817bf5" Accept-Ranges: bytes Content-Length: 1099420 Content-Type: application/octet-stream Age: 182449 Date: Fri, 28 Mar 2008 18:10:00 GMT Last-Modified: Thu, 28 Oct 2004 23:08:37 GMT Connection: close [14:12:09.6715] Slim::Player::Squeezebox2::directHeaders (343) Processing 9 headers [14:12:09.6720] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [14:12:09.6724] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "58913a-10c69c-41817bf5" [14:12:09.6729] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [14:12:09.6733] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 1099420 [14:12:09.6825] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [14:12:09.6833] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [14:12:11.8286] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [14:12:11.8387] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 262) [14:12:11.8981] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 132) [14:12:11.9071] Slim::Player::Protocols::MMS::setMetadata (271) Setting bitrate to 128 from WMA bitrate properties object [14:12:18.5130] Slim::Player::Source::trackStartEvent (1491) Got a track starting event [14:12:18.5135] Slim::Player::Source::trackStartEvent (1498) Song 0 had already started, so it's not longer in the queue [14:12:18.5139] Slim::Player::Source::trackStartEvent (1507) Song 1 has now started playing [14:12:18.5262] Slim::Player::Source::trackStartEvent (1535) Song queue is now 1 [14:13:15.8759] Slim::Player::Source::decoderUnderrun (582) 00:04:20:06:1a:f7: Decoder underrun while this mode: playout-play [14:13:15.8765] Slim::Player::Source::nextsong (1586) The next song is number 2, was 1 [14:13:15.8776] Slim::Player::Source::nextsong (1586) The next song is number 2, was 1 [14:13:15.8785] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (67 seconds)... [14:13:15.8803] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:06:1a:f7 RPDS packet sent: 4 [14:13:16.4873] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:06:1a:f7 Got RPDS packet: \"\4" [14:13:16.4893] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:06:1a:f7 RPDS packet sent: 3 [14:13:17.2363] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:06:1a:f7 Got RPDS packet: \"\3\x001http://llnw-498.listen.com/9/8/2/8/3/50738289.rad" [14:13:17.3533] Slim::Player::Source::skipahead (903) **skipahead: opening next song [14:13:17.3537] Slim::Player::Source::gotoNext (1236) Opening next song... [14:13:17.3544] Slim::Player::Source::nextsong (1586) The next song is number 2, was 1 [14:13:17.3556] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:06:1a:f7 [14:13:17.3562] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:06:1a:f7 [14:13:17.3567] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:06:1a:f7 [14:13:17.3571] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [14:13:17.3575] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [14:13:17.3580] Slim::Player::TranscodingHelper::enabledFormat (113) There are 11 disabled formats... [14:13:17.3584] Slim::Player::TranscodingHelper::enabledFormat (119) Testing aif-mp3-*-* vs wma-wma-squeezebox2-* [14:13:17.3589] Slim::Player::TranscodingHelper::enabledFormat (119) Testing alc-mp3-*-* vs wma-wma-squeezebox2-* [14:13:17.3594] Slim::Player::TranscodingHelper::enabledFormat (119) Testing ape-mp3-*-* vs wma-wma-squeezebox2-* [14:13:17.3599] Slim::Player::TranscodingHelper::enabledFormat (119) Testing flc-flc-*-* vs wma-wma-squeezebox2-* [14:13:17.3603] Slim::Player::TranscodingHelper::enabledFormat (119) Testing flc-mp3-*-* vs wma-wma-squeezebox2-* [14:13:17.3609] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mov-mp3-*-* vs wma-wma-squeezebox2-* [14:13:17.3614] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs wma-wma-squeezebox2-* [14:13:17.3618] Slim::Player::TranscodingHelper::enabledFormat (119) Testing ogg-mp3-*-* vs wma-wma-squeezebox2-* [14:13:17.3622] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wav-mp3-*-* vs wma-wma-squeezebox2-* [14:13:17.3627] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs wma-wma-squeezebox2-* [14:13:17.3632] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wvp-mp3-*-* vs wma-wma-squeezebox2-* [14:13:17.3637] Slim::Player::TranscodingHelper::checkBin (146) enabled [14:13:17.3641] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [14:13:17.3649] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [14:13:17.3654] Slim::Player::Source::gotoNext (1323) opening next song (old format: wma, new: wma) current playmode: playout-play [14:13:17.3659] Slim::Player::Source::streamingSongIndex (1380) Adding song index 2 to song queue [14:13:17.3663] Slim::Player::Source::streamingSongIndex (1401) Song queue is now 2,1 [14:13:17.3668] Slim::Player::Source::skipahead (909) **skipahead: restarting [14:13:17.3673] Slim::Player::Source::playmode (298) 00:04:20:06:1a:f7: Switching to mode play from playout-play [14:13:17.3680] Slim::Player::Source::resetSong (1625) Resetting song buffer. [14:13:17.3733] Slim::Player::Source::openSong (1720) Trying to open: rhapd://Tra.2088015.wma [14:13:17.4073] Slim::Player::Source::playmode (401) 00:04:20:06:1a:f7 New play mode: play [14:13:17.4152] Slim::Player::Squeezebox::stream (996) This player supports direct streaming for rhapd://Tra.2088015.wma as http://llnw-498.listen.com/9/8/2/8/3/50738289.rad, let's do it. [14:13:17.5158] Slim::Player::Squeezebox::stream (1027) setting up direct stream (3496976473:80) autostart: 3. [14:13:17.5163] Slim::Player::Squeezebox::stream (1028) request string: GET /9/8/2/8/3/50738289.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw-498.listen.com User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.0.1/17981 Icy-Metadata: 1 [14:13:17.5181] Slim::Player::Source::playmode (556) 00:04:20:06:1a:f7: Current playmode: play [14:13:17.5956] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/2.0.58 Accept-Ranges: bytes Content-Length: 2975748 Content-Type: text/plain Age: 228393 Date: Fri, 28 Mar 2008 18:11:08 GMT Last-Modified: Mon, 21 Aug 2006 17:20:35 GMT Connection: close [14:13:17.5962] Slim::Player::Squeezebox2::directHeaders (343) Processing 8 headers [14:13:17.5967] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/2.0.58 [14:13:17.5972] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [14:13:17.5977] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 2975748 [14:13:17.6070] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [14:13:17.6077] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [14:13:18.6970] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [14:13:18.7612] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 264) [14:13:18.7637] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 132) [14:13:18.7720] Slim::Player::Protocols::MMS::setMetadata (271) Setting bitrate to 128 from WMA bitrate properties object [14:13:18.8939] Slim::Player::Source::decoderUnderrun (582) 00:04:20:06:1a:f7: Decoder underrun while this mode: playout-play [14:13:18.8945] Slim::Player::Source::nextsong (1586) The next song is number 3, was 2 [14:13:18.8953] Slim::Player::Source::decoderUnderrun (595) Track failed before playback, marking as played [14:13:18.8959] Slim::Player::Source::playmode (298) 00:04:20:06:1a:f7: Switching to mode playout-play from playout-play [14:13:18.8963] Slim::Player::Source::playmode (305) Already in playmode playout-play : ignoring mode change [14:13:18.8970] Slim::Player::Source::nextsong (1586) The next song is number 3, was 2 [14:13:18.8979] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (400) End of track, logging usage info (185 seconds)... [14:13:18.8995] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:06:1a:f7 RPDS packet sent: 4 [14:13:18.9064] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:06:1a:f7 Got RPDS packet: \"\xFC" [14:13:18.9073] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (269) 00:04:20:06:1a:f7 Received RPDS -4, SSL connection already in use, retrying later [14:13:20.9226] Slim::Plugin::RhapsodyDirect::RPDS::rpds_resend (317) 00:04:20:06:1a:f7 Re-sending RPDS packet: "\4\x03185" [14:13:20.9239] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:06:1a:f7 RPDS packet sent: 4 [14:13:21.5252] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:06:1a:f7 Got RPDS packet: \"\4" [14:13:21.5273] Slim::Plugin::RhapsodyDirect::RPDS::rpds (72) 00:04:20:06:1a:f7 RPDS packet sent: 3 [14:13:22.0471] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (126) 00:04:20:06:1a:f7 Got RPDS packet: \"\3\x001http://llnw6.listen.com/u4/9/6/5/4/1/28514569.rad" [14:13:22.0751] Slim::Player::Source::skipahead (903) **skipahead: opening next song [14:13:22.0756] Slim::Player::Source::gotoNext (1236) Opening next song... [14:13:22.0763] Slim::Player::Source::nextsong (1586) The next song is number 3, was 2 [14:13:22.0775] Slim::Player::TranscodingHelper::getConvertCommand (201) undermax = 1, type = wma, squeezebox2 = 00:04:20:06:1a:f7 [14:13:22.0780] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-00:04:20:06:1a:f7 [14:13:22.0785] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-*-00:04:20:06:1a:f7 [14:13:22.0789] Slim::Player::TranscodingHelper::checkBin (138) Checking formats for: wma-wma-squeezebox2-* [14:13:22.0793] Slim::Player::TranscodingHelper::enabledFormat (105) Checking to see if wma-wma-squeezebox2-* is enabled [14:13:22.0798] Slim::Player::TranscodingHelper::enabledFormat (113) There are 11 disabled formats... [14:13:22.0802] Slim::Player::TranscodingHelper::enabledFormat (119) Testing aif-mp3-*-* vs wma-wma-squeezebox2-* [14:13:22.0808] Slim::Player::TranscodingHelper::enabledFormat (119) Testing alc-mp3-*-* vs wma-wma-squeezebox2-* [14:13:22.0813] Slim::Player::TranscodingHelper::enabledFormat (119) Testing ape-mp3-*-* vs wma-wma-squeezebox2-* [14:13:22.0817] Slim::Player::TranscodingHelper::enabledFormat (119) Testing flc-flc-*-* vs wma-wma-squeezebox2-* [14:13:22.0822] Slim::Player::TranscodingHelper::enabledFormat (119) Testing flc-mp3-*-* vs wma-wma-squeezebox2-* [14:13:22.0826] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mov-mp3-*-* vs wma-wma-squeezebox2-* [14:13:22.0831] Slim::Player::TranscodingHelper::enabledFormat (119) Testing mpc-mp3-*-* vs wma-wma-squeezebox2-* [14:13:22.0835] Slim::Player::TranscodingHelper::enabledFormat (119) Testing ogg-mp3-*-* vs wma-wma-squeezebox2-* [14:13:22.0839] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wav-mp3-*-* vs wma-wma-squeezebox2-* [14:13:22.0844] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wma-mp3-*-* vs wma-wma-squeezebox2-* [14:13:22.0848] Slim::Player::TranscodingHelper::enabledFormat (119) Testing wvp-mp3-*-* vs wma-wma-squeezebox2-* [14:13:22.0853] Slim::Player::TranscodingHelper::checkBin (146) enabled [14:13:22.0857] Slim::Player::TranscodingHelper::checkBin (148) Found command: - [14:13:22.0865] Slim::Player::TranscodingHelper::getConvertCommand (302) Matched Format: wma Type: wma Command: - [14:13:22.0870] Slim::Player::Source::gotoNext (1323) opening next song (old format: wma, new: wma) current playmode: playout-play [14:13:22.0875] Slim::Player::Source::streamingSongIndex (1380) Adding song index 3 to song queue [14:13:22.0879] Slim::Player::Source::streamingSongIndex (1401) Song queue is now 3,2 [14:13:22.0884] Slim::Player::Source::skipahead (909) **skipahead: restarting [14:13:22.0889] Slim::Player::Source::playmode (298) 00:04:20:06:1a:f7: Switching to mode play from playout-play [14:13:22.0896] Slim::Player::Source::resetSong (1625) Resetting song buffer. [14:13:22.0951] Slim::Player::Source::openSong (1720) Trying to open: rhapd://Tra.2088017.wma [14:13:22.1195] Slim::Player::Source::playmode (401) 00:04:20:06:1a:f7 New play mode: play [14:13:22.1275] Slim::Player::Squeezebox::stream (996) This player supports direct streaming for rhapd://Tra.2088017.wma as http://llnw6.listen.com/u4/9/6/5/4/1/28514569.rad, let's do it. [14:13:22.1469] Slim::Player::Squeezebox::stream (1027) setting up direct stream (3496976691:80) autostart: 3. [14:13:22.1474] Slim::Player::Squeezebox::stream (1028) request string: GET /u4/9/6/5/4/1/28514569.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw6.listen.com User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1252) SqueezeCenter/7.0.1/17981 Icy-Metadata: 1 [14:13:22.1492] Slim::Player::Source::playmode (556) 00:04:20:06:1a:f7: Current playmode: play [14:13:22.4283] Slim::Player::Squeezebox2::directHeaders (284) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/1.3.31 (Unix) ETag: "359c5-2a335c-41dc707b" Accept-Ranges: bytes Content-Length: 2765660 Content-Type: application/octet-stream Date: Fri, 28 Mar 2008 18:11:13 GMT Last-Modified: Wed, 05 Jan 2005 22:55:55 GMT Connection: close [14:13:22.4309] Slim::Player::Squeezebox2::directHeaders (343) Processing 8 headers [14:13:22.4315] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Server: Apache/1.3.31 (Unix) [14:13:22.4319] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: ETag: "359c5-2a335c-41dc707b" [14:13:22.4324] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Accept-Ranges: bytes [14:13:22.4328] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (52) RhapsodyDirect header: Content-Length: 2765660 [14:13:22.4420] Slim::Player::Squeezebox2::directHeaders (396) Got a stream type: wma bitrate: 128000 title: [14:13:22.4427] Slim::Player::Squeezebox2::directHeaders (455) Beginning direct stream! [14:13:23.8512] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 204) [14:13:23.8901] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 262) [14:13:23.8920] Slim::Networking::Slimproto::_http_metadata_handler (808) metadata (len: 132) [14:13:23.9003] Slim::Player::Protocols::MMS::setMetadata (271) Setting bitrate to 128 from WMA bitrate properties object [14:13:25.8860] Slim::Player::Source::trackStartEvent (1491) Got a track starting event [14:13:25.8865] Slim::Player::Source::trackStartEvent (1498) Song 2 had already started, so it's not longer in the queue [14:13:25.8871] Slim::Player::Source::trackStartEvent (1507) Song 3 has now started playing [14:13:25.8908] Slim::Player::Source::trackStartEvent (1535) Song queue is now 3 [14:13:26.4540] Slim::Player::Source::trackStartEvent (1491) Got a track starting event [14:13:26.4545] Slim::Player::Source::trackStartEvent (1507) Song 3 has now started playing [14:13:26.4563] Slim::Player::Source::trackStartEvent (1535) Song queue is now 3 [14:13:50.0666] Slim::Player::Source::playmode (298) 00:04:20:06:1a:f7: Switching to mode pause from playout-play [14:13:50.0680] Slim::Player::Source::playmode (401) 00:04:20:06:1a:f7 New play mode: pause [14:13:50.0696] Slim::Player::Source::playmode (556) 00:04:20:06:1a:f7: Current playmode: pause