[09-10-07 19:07:43.4353] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:07:47.0839] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.005s
[09-10-07 19:07:51.4350] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:07:52.0098] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s
[09-10-07 19:07:57.4760] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:02.0205] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s
[09-10-07 19:08:02.0213] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:08:05.4407] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:12.0100] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:17.0170] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s
[09-10-07 19:08:17.9655] Slim::Player::StreamingController::playerActive (1870) 00:04:20:16:7e:5b sync group now has: 00:04:20:16:7e:5b,00:04:20:1e:26:20,00:04:20:1e:ff:6a
[09-10-07 19:08:17.9660] Slim::Player::StreamingController::playerActive (1871) 00:04:20:16:7e:5b active players are: 00:04:20:16:7e:5b,00:04:20:1e:26:20
[09-10-07 19:08:17.9663] Slim::Player::StreamingController::playerActive (1875) 00:04:20:16:7e:5b restart play
[09-10-07 19:08:18.0213] Slim::Player::StreamingController::_Stop (600) Song queue is now 12
[09-10-07 19:08:18.0224] Slim::Player::StreamingController::_setPlayingState (2215) new playing state STOPPED
[09-10-07 19:08:18.0227] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state IDLE
[09-10-07 19:08:18.0233] Slim::Player::StreamingController::_Stream (1079) 00:04:20:16:7e:5b: got song from params, song index 12
[09-10-07 19:08:18.0237] Slim::Player::StreamingController::_Stream (1153) Song queue is now 12
[09-10-07 19:08:18.0240] Slim::Player::StreamingController::_Stream (1156) 00:04:20:16:7e:5b: preparing to stream song index 12
[09-10-07 19:08:18.0383] Slim::Player::Song::open (357) file:///E:/Public/Music/jukebox_flac/R/R.E.M/In%20Time%20%20The%20Best%20of%20R.E.M.%201988-2003/R.E.M.%20-%2013%20-%20Stand.flac
[09-10-07 19:08:18.0568] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: -
[09-10-07 19:08:18.0573] Slim::Player::Song::open (377) seek=true time=47.266 canSeek=1
[09-10-07 19:08:18.0582] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: -
[09-10-07 19:08:18.0586] Slim::Player::Song::open (398) Transcoder: streamMode=I, streamformat=flc
[09-10-07 19:08:18.0590] Slim::Player::Song::open (446) Opening stream (no direct streaming) using Slim::Player::Protocols::File [file:///E:/Public/Music/jukebox_flac/R/R.E.M/In%20Time%20%20The%20Best%20of%20R.E.M.%201988-2003/R.E.M.%20-%2013%20-%20Stand.flac]
[09-10-07 19:08:18.0602] Slim::Player::Protocols::File::open (78) duration: [192.173] size: [23835596] endian [] offset: [0] for file:///E:/Public/Music/jukebox_flac/R/R.E.M/In%20Time%20%20The%20Best%20of%20R.E.M.%201988-2003/R.E.M.%20-%2013%20-%20Stand.flac
[09-10-07 19:08:18.0606] Slim::Player::Protocols::File::open (95) Opening file E:\Public\Music\jukebox_flac\R\R.E.M\In Time  The Best of R.E.M. 1988-2003\R.E.M. - 13 - Stand.flac
[09-10-07 19:08:18.0712] Slim::Player::Protocols::File::_timeToOffset (284) seeking using Slim::Formats::FLAC findFrameBoundaries(5862495, 47.266)
[09-10-07 19:08:18.0982] Slim::Player::Protocols::File::_timeToOffset (296) 47.266 -> 5871186 (align: 1 size: 23835596 duration: 192.173)
[09-10-07 19:08:18.0987] Slim::Player::Protocols::File::open (173) Seeking in 5871186 into E:\Public\Music\jukebox_flac\R\R.E.M\In Time  The Best of R.E.M. 1988-2003\R.E.M. - 13 - Stand.flac
[09-10-07 19:08:18.0992] Slim::Player::Song::open (467) URL is a song (audio): file:///E:/Public/Music/jukebox_flac/R/R.E.M/In%20Time%20%20The%20Best%20of%20R.E.M.%201988-2003/R.E.M.%20-%2013%20-%20Stand.flac, type=flc
[09-10-07 19:08:18.1367] Slim::Player::StreamingController::_Stream (1207) 00:04:20:16:7e:5b: stream
[09-10-07 19:08:18.1390] Slim::Player::StreamingController::_Stream (1207) 00:04:20:1e:26:20: stream
[09-10-07 19:08:18.1533] Slim::Player::Boom::play (335) Setting LineIn to 0 for [file:///E:/Public/Music/jukebox_flac/R/R.E.M/In%20Time%20%20The%20Best%20of%20R.E.M.%201988-2003/R.E.M.%20-%2013%20-%20Stand.flac]
[09-10-07 19:08:18.1544] Slim::Player::Boom::setLineIn (427) Switching to line in 0
[09-10-07 19:08:18.1562] Slim::Player::StreamingController::_Stream (1238) Song queue is now 12
[09-10-07 19:08:18.1569] Slim::Player::StreamingController::_setPlayingState (2215) new playing state BUFFERING
[09-10-07 19:08:18.1572] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state STREAMING
[09-10-07 19:08:18.1620] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:18.1640] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:08:18.2269] Slim::Player::StreamingController::playerBufferReady (2116) 00:04:20:16:7e:5b
[09-10-07 19:08:18.2285] Slim::Player::StreamingController::_setPlayingState (2215) new playing state WAITING_TO_SYNC
[09-10-07 19:08:18.2419] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:08:18.2427] Slim::Player::StreamingController::playerBufferReady (2116) 00:04:20:1e:26:20
[09-10-07 19:08:18.2655] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:16:7e:5b startAt: 436390086
[09-10-07 19:08:18.2662] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:26:20 startAt: 125911989
[09-10-07 19:08:18.2671] Slim::Player::StreamingController::_setPlayingState (2215) new playing state PLAYING
[09-10-07 19:08:18.2675] Slim::Player::StreamingController::_Playing (361) Song 12 has now started playing
[09-10-07 19:08:18.2687] Slim::Player::StreamingController::_Playing (391) Song queue is now 12
[09-10-07 19:08:18.2771] Slim::Player::StreamingController::_CheckSync (495) 00:04:20:16:7e:5b bailing as no playPoint
[09-10-07 19:08:18.2774] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:18.5751] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: -
[09-10-07 19:08:21.1294] Slim::Player::StreamingController::playerTrackStarted (2027) 00:04:20:16:7e:5b
[09-10-07 19:08:21.4393] Slim::Player::StreamingController::_CheckSync (495) 00:04:20:1e:26:20 bailing as no playPoint
[09-10-07 19:08:22.4402] Slim::Player::StreamingController::_CheckSync (495) 00:04:20:1e:26:20 bailing as no playPoint
[09-10-07 19:08:23.4344] Slim::Player::StreamingController::_CheckSync (495) 00:04:20:1e:26:20 bailing as no playPoint
[09-10-07 19:08:23.4718] Slim::Player::StreamingController::playerTrackStarted (2027) 00:04:20:1e:26:20
[09-10-07 19:08:24.4404] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.78890943527222s
[09-10-07 19:08:25.4404] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.78893041610718s
[09-10-07 19:08:26.4394] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.78797650337219s
[09-10-07 19:08:27.0300] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s
[09-10-07 19:08:27.0307] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:27.0317] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:08:27.4405] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.47703456878662s
[09-10-07 19:08:28.4403] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.7838294506073s
[09-10-07 19:08:29.4400] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.78362846374512s
[09-10-07 19:08:30.4352] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.77877330780029s
[09-10-07 19:08:31.4404] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.78394341468811s
[09-10-07 19:08:32.4398] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.47721552848816s
[09-10-07 19:08:33.4340] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:33.4352] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.77847242355347s
[09-10-07 19:08:33.6941] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:08:34.4409] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.77930235862732s
[09-10-07 19:08:35.4426] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.78103923797607s
[09-10-07 19:08:36.5466] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.88502025604248s
[09-10-07 19:08:37.0591] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.005s
[09-10-07 19:08:38.4413] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.77985620498657s
[09-10-07 19:08:39.4421] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.78070139884949s
[09-10-07 19:08:41.1736] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:16:7e:5b bailing as playPoint too old: 3.41778469085693s
[09-10-07 19:08:41.4327] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:41.6999] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:08:42.0107] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s
[09-10-07 19:08:42.4343] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.45990228652954s
[09-10-07 19:08:43.4408] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.77437019348145s
[09-10-07 19:08:43.5748] Slim::Player::StreamingController::stop (1944) 00:04:20:16:7e:5b
[09-10-07 19:08:43.5765] Slim::Player::StreamingController::_Stop (600) Song queue is now 12
[09-10-07 19:08:43.5771] Slim::Player::StreamingController::_setPlayingState (2215) new playing state STOPPED
[09-10-07 19:08:43.5775] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state IDLE
[09-10-07 19:08:43.5868] Slim::Player::StreamingController::resetSongqueue (1626) Song queue is now 
[09-10-07 19:08:43.6549] Slim::Player::StreamingController::play (1947) 00:04:20:16:7e:5b
[09-10-07 19:08:43.6581] Slim::Player::StreamingController::_setPlayingState (2215) new playing state STOPPED
[09-10-07 19:08:43.6585] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state IDLE
[09-10-07 19:08:43.6736] Slim::Player::Song::new (107) index 1 -> file:///E:/Public/Music/jukebox_flac/M/Magtens%20Korridorer/Milan%20all%E9/02%20-%20Milan%20all%E9.flac
[09-10-07 19:08:43.6741] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state TRACKWAIT
[09-10-07 19:08:43.6751] Slim::Player::Song::getNextSong (228) file:///E:/Public/Music/jukebox_flac/M/Magtens%20Korridorer/Milan%20all%E9/02%20-%20Milan%20all%E9.flac
[09-10-07 19:08:43.6757] Slim::Player::StreamingController::_nextTrackReady (726) 00:04:20:16:7e:5b: nextTrack will be index 1
[09-10-07 19:08:43.6763] Slim::Player::StreamingController::_Stream (1153) Song queue is now 1
[09-10-07 19:08:43.6769] Slim::Player::StreamingController::_Stream (1156) 00:04:20:16:7e:5b: preparing to stream song index 1
[09-10-07 19:08:43.6774] Slim::Player::Song::open (357) file:///E:/Public/Music/jukebox_flac/M/Magtens%20Korridorer/Milan%20all%E9/02%20-%20Milan%20all%E9.flac
[09-10-07 19:08:43.6792] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: -
[09-10-07 19:08:43.6797] Slim::Player::Song::open (377) seek=false time=0 canSeek=1
[09-10-07 19:08:43.6806] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: -
[09-10-07 19:08:43.6810] Slim::Player::Song::open (398) Transcoder: streamMode=I, streamformat=flc
[09-10-07 19:08:43.6815] Slim::Player::Song::open (446) Opening stream (no direct streaming) using Slim::Player::Protocols::File [file:///E:/Public/Music/jukebox_flac/M/Magtens%20Korridorer/Milan%20all%E9/02%20-%20Milan%20all%E9.flac]
[09-10-07 19:08:43.6826] Slim::Player::Protocols::File::open (78) duration: [191.466] size: [23537717] endian [] offset: [0] for file:///E:/Public/Music/jukebox_flac/M/Magtens%20Korridorer/Milan%20all%E9/02%20-%20Milan%20all%E9.flac
[09-10-07 19:08:43.6830] Slim::Player::Protocols::File::open (95) Opening file E:\Public\Music\jukebox_flac\M\Magtens Korridorer\Milan allé\02 - Milan allé.flac
[09-10-07 19:08:43.6840] Slim::Player::Song::open (467) URL is a song (audio): file:///E:/Public/Music/jukebox_flac/M/Magtens%20Korridorer/Milan%20all%E9/02%20-%20Milan%20all%E9.flac, type=flc
[09-10-07 19:08:43.7240] Slim::Player::StreamingController::_Stream (1207) 00:04:20:16:7e:5b: stream
[09-10-07 19:08:43.7263] Slim::Player::StreamingController::_Stream (1207) 00:04:20:1e:26:20: stream
[09-10-07 19:08:43.7273] Slim::Player::Boom::play (335) Setting LineIn to 0 for [file:///E:/Public/Music/jukebox_flac/M/Magtens%20Korridorer/Milan%20all%E9/02%20-%20Milan%20all%E9.flac]
[09-10-07 19:08:43.7280] Slim::Player::Boom::setLineIn (427) Switching to line in 0
[09-10-07 19:08:43.7300] Slim::Player::StreamingController::_Stream (1238) Song queue is now 1
[09-10-07 19:08:43.7306] Slim::Player::StreamingController::_setPlayingState (2215) new playing state BUFFERING
[09-10-07 19:08:43.7309] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state STREAMING
[09-10-07 19:08:43.7564] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:43.7587] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:08:43.8132] Slim::Player::StreamingController::playerBufferReady (2116) 00:04:20:16:7e:5b
[09-10-07 19:08:43.8136] Slim::Player::StreamingController::_setPlayingState (2215) new playing state WAITING_TO_SYNC
[09-10-07 19:08:43.8205] Slim::Player::StreamingController::playerBufferReady (2116) 00:04:20:1e:26:20
[09-10-07 19:08:43.8210] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:16:7e:5b startAt: 436415639
[09-10-07 19:08:43.8215] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:26:20 startAt: 125937547
[09-10-07 19:08:43.8220] Slim::Player::StreamingController::_setPlayingState (2215) new playing state PLAYING
[09-10-07 19:08:43.8224] Slim::Player::StreamingController::_Playing (361) Song 1 has now started playing
[09-10-07 19:08:43.8238] Slim::Player::StreamingController::_Playing (391) Song queue is now 1
[09-10-07 19:08:44.1291] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: -
[09-10-07 19:08:46.6451] Slim::Player::StreamingController::playerTrackStarted (2027) 00:04:20:16:7e:5b
[09-10-07 19:08:47.0077] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:08:47.0105] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:47.0116] Slim::Player::StreamingController::_CheckSync (495) 00:04:20:1e:26:20 bailing as no playPoint
[09-10-07 19:08:48.4429] Slim::Player::StreamingController::_CheckSync (495) 00:04:20:1e:26:20 bailing as no playPoint
[09-10-07 19:08:49.0044] Slim::Player::StreamingController::playerTrackStarted (2027) 00:04:20:1e:26:20
[09-10-07 19:08:49.4417] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.4620189666748s
[09-10-07 19:08:50.4405] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.76401901245117s
[09-10-07 19:08:51.4405] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.76409292221069s
[09-10-07 19:08:52.0076] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s
[09-10-07 19:08:52.4423] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.45988488197327s
[09-10-07 19:08:53.4419] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:53.4427] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.766352891922s
[09-10-07 19:08:54.4407] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.76427888870239s
[09-10-07 19:08:55.4343] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.75782299041748s
[09-10-07 19:08:55.6939] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:08:56.4405] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.75911784172058s
[09-10-07 19:08:56.9572] Slim::Player::StreamingController::pause (1963) 00:04:20:16:7e:5b
[09-10-07 19:08:56.9625] Slim::Player::StreamingController::_setPlayingState (2215) new playing state PAUSED
[09-10-07 19:08:56.9983] Slim::Player::Source::playmode (93) 00:04:20:1e:26:20: Current playmode: pause
[09-10-07 19:08:57.1997] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:08:57.3266] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:09:07.0047] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s
[09-10-07 19:09:09.6114] Slim::Player::StreamingController::stop (1944) 00:04:20:16:7e:5b
[09-10-07 19:09:09.6135] Slim::Player::StreamingController::_Stop (600) Song queue is now 1
[09-10-07 19:09:09.6140] Slim::Player::StreamingController::_setPlayingState (2215) new playing state STOPPED
[09-10-07 19:09:09.6144] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state IDLE
[09-10-07 19:09:09.6151] Slim::Player::StreamingController::resetSongqueue (1626) Song queue is now 
[09-10-07 19:09:09.7199] Slim::Player::StreamingController::play (1947) 00:04:20:16:7e:5b
[09-10-07 19:09:09.7222] Slim::Player::StreamingController::_setPlayingState (2215) new playing state STOPPED
[09-10-07 19:09:09.7226] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state IDLE
[09-10-07 19:09:09.7235] Slim::Player::Song::new (107) index 0 -> rhapd://Tra.26589358.mp3
[09-10-07 19:09:10.2902] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state TRACKWAIT
[09-10-07 19:09:10.2906] Slim::Player::Song::getNextSong (228) rhapd://Tra.26589358.mp3
[09-10-07 19:09:10.2910] Slim::Player::Song::getNextSong (250) scanning URL rhapd://Tra.26589358.mp3
[09-10-07 19:09:10.3063] Slim::Player::Song::getNextSong (228) rhapd://Tra.26589358.mp3
[09-10-07 19:09:10.3528] Slim::Player::StreamingController::_playersMessage (770) Getting stream info...: rhapd://Tra.26589358.mp3
[09-10-07 19:09:10.3752] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:09:10.3783] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:09:13.0624] Slim::Player::StreamingController::_nextTrackReady (726) 00:04:20:16:7e:5b: nextTrack will be index 0
[09-10-07 19:09:13.0629] Slim::Player::StreamingController::_Stream (1153) Song queue is now 0
[09-10-07 19:09:13.0632] Slim::Player::StreamingController::_Stream (1156) 00:04:20:16:7e:5b: preparing to stream song index 0
[09-10-07 19:09:13.0636] Slim::Player::Song::open (357) rhapd://Tra.26589358.mp3
[09-10-07 19:09:13.0640] Slim::Player::Song::open (377) seek=false time=0 canSeek=1
[09-10-07 19:09:13.0648] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: mp3->mp3 via: -
[09-10-07 19:09:13.0652] Slim::Player::Song::open (398) Transcoder: streamMode=I, streamformat=mp3
[09-10-07 19:09:13.0753] Slim::Player::Song::open (446) Opening stream (no direct streaming) using Slim::Plugin::RhapsodyDirect::ProtocolHandler [rhapd://Tra.26589358.mp3]
[09-10-07 19:09:13.9545] Slim::Player::Song::open (467) URL is a song (audio): rhapd://Tra.26589358.mp3, type=mp3
[09-10-07 19:09:13.9557] Slim::Player::StreamingController::_Stream (1207) 00:04:20:16:7e:5b: stream
[09-10-07 19:09:13.9705] Slim::Player::StreamingController::_Stream (1207) 00:04:20:1e:26:20: stream
[09-10-07 19:09:13.9715] Slim::Player::Boom::play (335) Setting LineIn to 0 for [http://llnw-1427.listen.com/2/7/1/8/5/164858172.rad]
[09-10-07 19:09:13.9719] Slim::Player::Boom::setLineIn (427) Switching to line in 0
[09-10-07 19:09:13.9758] Slim::Player::StreamingController::_Stream (1238) Song queue is now 0
[09-10-07 19:09:13.9765] Slim::Player::StreamingController::_setPlayingState (2215) new playing state BUFFERING
[09-10-07 19:09:13.9770] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state STREAMING
[09-10-07 19:09:13.9960] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:09:14.0154] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:09:14.0863] Slim::Player::Player::_buffering (1135) Buffering... 0 / 73728
[09-10-07 19:09:14.1008] Slim::Player::Player::_buffering (1135) Buffering... 0 / 73728
[09-10-07 19:09:14.3874] Slim::Player::Player::_buffering (1135) Buffering... 2710 / 73728
[09-10-07 19:09:14.4036] Slim::Player::Player::_buffering (1135) Buffering... 7090 / 73728
[09-10-07 19:09:14.6921] Slim::Player::Player::_buffering (1135) Buffering... 20230 / 73728
[09-10-07 19:09:14.7118] Slim::Player::Player::_buffering (1135) Buffering... 20230 / 73728
[09-10-07 19:09:14.7684] Slim::Player::StreamingController::playerBufferReady (2116) 00:04:20:16:7e:5b
[09-10-07 19:09:14.7688] Slim::Player::StreamingController::_setPlayingState (2215) new playing state WAITING_TO_SYNC
[09-10-07 19:09:14.7937] Slim::Player::StreamingController::playerBufferReady (2116) 00:04:20:1e:26:20
[09-10-07 19:09:14.7942] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:16:7e:5b startAt: 436446587
[09-10-07 19:09:14.7948] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:26:20 startAt: 125968495
[09-10-07 19:09:14.7952] Slim::Player::StreamingController::_setPlayingState (2215) new playing state PLAYING
[09-10-07 19:09:14.7956] Slim::Player::StreamingController::_Playing (361) Song 0 has now started playing
[09-10-07 19:09:14.7972] Slim::Player::StreamingController::_Playing (391) Song queue is now 0
[09-10-07 19:09:16.8226] Slim::Player::StreamingController::_CheckSync (495) 00:04:20:16:7e:5b bailing as no playPoint
[09-10-07 19:09:16.8248] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:09:17.0345] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s
[09-10-07 19:09:17.0360] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:09:17.6604] Slim::Player::StreamingController::playerTrackStarted (2027) 00:04:20:16:7e:5b
[09-10-07 19:09:18.1288] Slim::Player::StreamingController::_CheckSync (495) 00:04:20:1e:26:20 bailing as no playPoint
[09-10-07 19:09:19.1269] Slim::Player::StreamingController::_CheckSync (495) 00:04:20:1e:26:20 bailing as no playPoint
[09-10-07 19:09:20.0172] Slim::Player::StreamingController::playerTrackStarted (2027) 00:04:20:1e:26:20
[09-10-07 19:09:20.1250] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 5.10953259468079s
[09-10-07 19:09:21.1273] Slim::Player::StreamingController::_CheckSync (507) 00:04:20:1e:26:20 bailing as playPoint too old: 6.00089931488037s
[09-10-07 19:09:21.4441] Slim::Player::StreamingController::playerInactive (1912) 00:04:20:16:7e:5b sync group now has: 00:04:20:16:7e:5b,00:04:20:1e:26:20,00:04:20:1e:ff:6a
[09-10-07 19:09:21.4445] Slim::Player::StreamingController::playerInactive (1913) 00:04:20:16:7e:5b active players are: 00:04:20:16:7e:5b
[09-10-07 19:09:22.0039] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:09:22.1241] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:09:30.2065] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:09:32.0047] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s
[09-10-07 19:09:37.0039] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:09:42.0049] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s
[09-10-07 19:09:44.2926] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:09:45.7891] Slim::Player::StreamingController::playerActive (1841) 00:04:20:1a:78:2b player already active: 00:04:20:1a:78:2b
[09-10-07 19:09:45.7896] Slim::Player::Squeezebox::reconnect (113) 00:04:20:1a:78:2b restaring play on pseudo-reconnect at 0
[09-10-07 19:09:45.7923] Slim::Player::StreamingController::playerReconnect (1919) 00:04:20:1a:78:2b
[09-10-07 19:09:45.7927] Slim::Player::Squeezebox::reconnect (121) 00:04:20:1a:78:2b forcing stop on pseudo-reconnect
[09-10-07 19:09:46.2648] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1a:78:2b adjust jiffies epoch +1254890049.540s
[09-10-07 19:09:47.0046] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:09:47.4676] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1a:78:2b adjust jiffies epoch -0.004s
[09-10-07 19:09:49.4148] Slim::Player::StreamingController::pause (1963) 00:04:20:16:7e:5b
[09-10-07 19:09:49.4154] Slim::Player::StreamingController::_setPlayingState (2215) new playing state PAUSED
[09-10-07 19:09:49.4170] Slim::Player::Source::playmode (93) 00:04:20:16:7e:5b: Current playmode: pause
[09-10-07 19:09:49.4279] Slim::Player::StreamingController::_CheckPaused (429) Stopping remote stream upon full buffer when paused
[09-10-07 19:09:49.4292] Slim::Player::StreamingController::_setStreamingState (2228) new streaming state IDLE
[09-10-07 19:09:49.4421] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:09:52.0073] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s
[09-10-07 19:09:52.1028] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1a:78:2b adjust jiffies epoch -0.064s
[09-10-07 19:09:57.0068] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s
[09-10-07 19:09:57.1142] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1a:78:2b adjust jiffies epoch -0.004s
[09-10-07 19:10:07.0066] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s
[09-10-07 19:10:12.0102] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s
[09-10-07 19:10:17.0044] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s