[09-09-29 21:39:38.8965] Slim::Player::StreamingController::stop (1944) 00:04:20:05:ab:fa
[09-09-29 21:39:38.8971] Slim::Player::StreamingController::resetSongqueue (1626) Song queue is now 
[09-09-29 21:39:38.8990] Slim::Player::StreamingController::play (1947) 00:04:20:05:ab:fa
[09-09-29 21:39:38.8998] Slim::Player::StreamingController::_setPlayingState (2215) new playing state STOPPED
[09-09-29 21:39:38.9001] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state IDLE
[09-09-29 21:39:38.9008] Slim::Player::Song::new (107) index 0 -> slacker://stations/1601610/446.mp3
[09-09-29 21:39:38.9013] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state TRACKWAIT
[09-09-29 21:39:38.9017] Slim::Player::Song::getNextSong (228) slacker://stations/1601610/446.mp3
[09-09-29 21:39:38.9021] Slim::Player::Song::getNextSong (250) scanning URL slacker://stations/1601610/446.mp3
[09-09-29 21:39:38.9026] Slim::Player::Song::getNextSong (228) slacker://stations/1601610/446.mp3
[09-09-29 21:39:38.9032] Slim::Plugin::Slacker::ProtocolHandler::_getNextTrack (149) 00:04:20:05:ab:fa Getting next track from SqueezeNetwork
[09-09-29 21:39:38.9053] Slim::Player::StreamingController::_playersMessage (770) Getting stream info...: slacker://stations/1601610/446.mp3
[09-09-29 21:39:39.1498] Slim::Player::StreamingController::play (1947) 00:04:20:05:ab:fa
[09-09-29 21:39:39.1507] Slim::Player::StreamingController::_Stop (600) Song queue is now 0
[09-09-29 21:39:39.1511] Slim::Player::StreamingController::_setPlayingState (2215) new playing state STOPPED
[09-09-29 21:39:39.1514] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state IDLE
[09-09-29 21:39:39.1521] Slim::Player::Song::new (107) index 0 -> slacker://stations/1601610/446.mp3
[09-09-29 21:39:39.1527] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state TRACKWAIT
[09-09-29 21:39:39.1531] Slim::Player::Song::getNextSong (228) slacker://stations/1601610/446.mp3
[09-09-29 21:39:39.1534] Slim::Player::Song::getNextSong (250) scanning URL slacker://stations/1601610/446.mp3
[09-09-29 21:39:39.1539] Slim::Player::Song::getNextSong (228) slacker://stations/1601610/446.mp3
[09-09-29 21:39:39.1545] Slim::Plugin::Slacker::ProtocolHandler::_getNextTrack (149) 00:04:20:05:ab:fa Getting next track from SqueezeNetwork
[09-09-29 21:39:39.1565] Slim::Player::StreamingController::_playersMessage (770) Getting stream info...: slacker://stations/1601610/446.mp3
[09-09-29 21:39:39.3401] Slim::Plugin::Slacker::ProtocolHandler::gotNextTrack (172) Got Slacker track: {
  URL              => "http://signal-4.slacker.com/c2/transcodings/rcd/001/54/79_1/mp3_128/TRACK_1_01.mp3?e=1254274799&h=73d0a5a40e852cafd1944b32714760bd",
  album            => "Deep and Wide and Tall: The Platinum Collection",
  albumid          => 178_446,
  arate            => 0,
  artist           => "Aztec Camera",
  artistid         => 2708,
  audiogain        => -1,
  container        => "NONE",
  copyright        => "",
  fid              => 7_080_463,
  licensed         => 0,
  pid              => 6_139_220,
  portablelicensed => 0,
  saved            => 0,
  sid              => "stations/1601610/446",
  skip             => "yes",
  skips            => 5,
  tid              => 7_172_826,
  title            => "Oblivious",
  tlen             => 192,
  trate            => 0,
  type             => "radio",
  upnext           => "Public Image Ltd.",
  upnext_albumid   => 14_192,
  year             => 2005,
}
[09-09-29 21:39:39.3411] Slim::Player::StreamingController::_nextTrackReady (719) 00:04:20:05:ab:fa: discarding unexpected nextTrackCallbackId 3, expected 5
[09-09-29 21:39:39.3419] Slim::Plugin::Slacker::ProtocolHandler::playlistCallback (458) Got playlist event: repeat
[09-09-29 21:39:39.5062] Slim::Plugin::Slacker::ProtocolHandler::gotNextTrack (172) Got Slacker track: {
  URL              => "http://signal-3.slacker.com/c2/transcodings/emi/inti_harddrive2/B/slacker_20070921123619_HDD/077778610557/mp3_128/077778610557_01_002_wm9_lossless_full.mp3?e=1254274799&h=5f30a6a20f3109b5f74b41db33861112",
  album            => 9,
  albumid          => 14_192,
  arate            => 0,
  artist           => "Public Image Ltd.",
  artistid         => 87_883,
  audiogain        => 0,
  container        => "NONE",
  copyright        => "",
  fid              => 4_628_802,
  licensed         => 1,
  pid              => 5_971_212,
  portablelicensed => 1,
  saved            => 0,
  sid              => "stations/1601610/446",
  skip             => "yes",
  skips            => 4,
  tid              => 6_369_220,
  title            => "Disappointed",
  tlen             => 333,
  trate            => 0,
  type             => "radio",
  upnext           => "Billy Idol",
  upnext_albumid   => 194_358,
  year             => 1989,
}
[09-09-29 21:39:39.5072] Slim::Player::StreamingController::_nextTrackReady (726) 00:04:20:05:ab:fa: nextTrack will be index 0
[09-09-29 21:39:39.5077] Slim::Player::StreamingController::_Stream (1153) Song queue is now 0
[09-09-29 21:39:39.5081] Slim::Player::StreamingController::_Stream (1156) 00:04:20:05:ab:fa: preparing to stream song index 0
[09-09-29 21:39:39.5086] Slim::Player::Song::open (357) slacker://stations/1601610/446.mp3
[09-09-29 21:39:39.5106] Slim::Player::TranscodingHelper::getConvertCommand2 (430) Error: Didn't find any command matches for type: mp3
[09-09-29 21:39:39.5111] Slim::Player::Song::open (377) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDSlacker
[09-09-29 21:39:39.5117] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: mp3->mp3 via: -
[09-09-29 21:39:39.5122] Slim::Player::Song::open (398) Transcoder: streamMode=I, streamformat=mp3
[09-09-29 21:39:39.5127] Slim::Player::Song::open (436) URL supports direct streaming [slacker://stations/1601610/446.mp3->http://signal-3.slacker.com/c2/transcodings/emi/inti_harddrive2/B/slacker_20070921123619_HDD/077778610557/mp3_128/077778610557_01_002_wm9_lossless_full.mp3?e=1254274799&h=5f30a6a20f3109b5f74b41db33861112]
[09-09-29 21:39:39.5146] Slim::Player::StreamingController::_Stream (1207) 00:04:20:05:ab:fa: stream
[09-09-29 21:39:39.5151] Slim::Plugin::Slacker::ProtocolHandler::trackGain (557) Using replaygain value of 0 for Slacker track
[09-09-29 21:39:39.6229] Slim::Player::StreamingController::_Stream (1238) Song queue is now 0
[09-09-29 21:39:39.6235] Slim::Player::StreamingController::_setPlayingState (2215) new playing state BUFFERING
[09-09-29 21:39:39.6239] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state STREAMING
[09-09-29 21:39:39.6249] Slim::Plugin::Slacker::ProtocolHandler::playlistCallback (458) Got playlist event: repeat
[09-09-29 21:39:39.6412] Slim::Player::Player::_buffering (1135) Buffering... 0 / 20480
[09-09-29 21:39:39.9431] Slim::Player::Player::_buffering (1135) Buffering... 0 / 20480
[09-09-29 21:39:40.2442] Slim::Player::Player::_buffering (1135) Buffering... 1043 / 20480
[09-09-29 21:39:40.3868] Slim::Player::StreamingController::playerTrackStarted (2027) 00:04:20:05:ab:fa
[09-09-29 21:39:40.3873] Slim::Player::StreamingController::_setPlayingState (2215) new playing state PLAYING
[09-09-29 21:39:40.3876] Slim::Player::StreamingController::_Playing (361) Song 0 has now started playing
[09-09-29 21:39:40.3882] Slim::Player::StreamingController::_Playing (391) Song queue is now 0
[09-09-29 21:39:40.3892] Slim::Plugin::Slacker::ProtocolHandler::playlistCallback (458) Got playlist event: newsong