[09-10-16 18:54:19.5274] Slim::Player::StreamingController::playerActive (1877) 00:04:20:1e:26:20 sync group now has: 00:04:20:1e:ff:6a,00:04:20:16:7e:5b,00:04:20:1e:26:20 [09-10-16 18:54:19.5278] Slim::Player::StreamingController::playerActive (1878) 00:04:20:1e:26:20 active players are: 00:04:20:1e:26:20,00:04:20:16:7e:5b,00:04:20:1e:ff:6a [09-10-16 18:54:19.5281] Slim::Player::StreamingController::playerActive (1882) 00:04:20:1e:26:20 restart play [09-10-16 18:54:19.5305] Slim::Player::StreamingController::_Stop (607) Song queue is now 1 [09-10-16 18:54:19.5311] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-16 18:54:19.5314] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-16 18:54:19.5320] Slim::Player::StreamingController::_Stream (1086) 00:04:20:1e:26:20: got song from params, song index 1 [09-10-16 18:54:19.5323] Slim::Player::StreamingController::_Stream (1160) Song queue is now 1 [09-10-16 18:54:19.5327] Slim::Player::StreamingController::_Stream (1163) 00:04:20:1e:26:20: preparing to stream song index 1 [09-10-16 18:54:19.5331] Slim::Player::Song::open (362) file:///E:/Public/Music/jukebox_flac/M/Magtens%20Korridorer/Milan%20all%E9/02%20-%20Milan%20all%E9.flac [09-10-16 18:54:19.5348] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: - [09-10-16 18:54:19.5352] Slim::Player::Song::open (382) seek=true time=50.92 canSeek=1 [09-10-16 18:54:19.5362] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: - [09-10-16 18:54:19.5365] Slim::Player::Song::open (403) Transcoder: streamMode=I, streamformat=flc [09-10-16 18:54:19.5370] Slim::Player::Song::open (451) 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-16 18:54:19.5381] 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-16 18:54:19.5385] Slim::Player::Protocols::File::open (95) Opening file E:\Public\Music\jukebox_flac\M\Magtens Korridorer\Milan allé\02 - Milan allé.flac [09-10-16 18:54:19.5397] Slim::Player::Protocols::File::_timeToOffset (284) seeking using Slim::Formats::FLAC findFrameBoundaries(6259808, 50.92) [09-10-16 18:54:19.5404] Slim::Player::Protocols::File::_timeToOffset (296) 50.92 -> 6270284 (align: 1 size: 23537717 duration: 191.466) [09-10-16 18:54:19.5408] Slim::Player::Protocols::File::open (173) Seeking in 6270284 into E:\Public\Music\jukebox_flac\M\Magtens Korridorer\Milan allé\02 - Milan allé.flac [09-10-16 18:54:19.5423] Slim::Player::Song::open (472) 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-16 18:54:19.5759] Slim::Player::StreamingController::_Stream (1214) 00:04:20:1e:26:20: stream [09-10-16 18:54:19.5768] 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-16 18:54:19.5772] Slim::Player::Boom::setLineIn (427) Switching to line in 0 [09-10-16 18:54:19.5790] Slim::Player::StreamingController::_Stream (1214) 00:04:20:16:7e:5b: stream [09-10-16 18:54:19.5842] Slim::Player::StreamingController::_Stream (1214) 00:04:20:1e:ff:6a: stream [09-10-16 18:54:19.5852] 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-16 18:54:19.5857] Slim::Player::Boom::setLineIn (427) Switching to line in 0 [09-10-16 18:54:19.5880] Slim::Player::StreamingController::_Stream (1245) Song queue is now 1 [09-10-16 18:54:19.5887] Slim::Player::StreamingController::_setPlayingState (2222) new playing state BUFFERING [09-10-16 18:54:19.5891] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state STREAMING [09-10-16 18:54:19.6001] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s [09-10-16 18:54:19.6155] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:26:20 [09-10-16 18:54:19.6159] Slim::Player::StreamingController::_setPlayingState (2222) new playing state WAITING_TO_SYNC [09-10-16 18:54:19.6166] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.004s [09-10-16 18:54:19.6173] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:16:7e:5b [09-10-16 18:54:19.8102] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: - [09-10-16 18:54:19.9499] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.001s [09-10-16 18:54:19.9633] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.002s [09-10-16 18:54:19.9639] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:ff:6a [09-10-16 18:54:19.9643] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:26:20 startAt: 78680 [09-10-16 18:54:19.9649] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:16:7e:5b startAt: 174413 [09-10-16 18:54:19.9654] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:ff:6a startAt: 16705 [09-10-16 18:54:19.9659] Slim::Player::StreamingController::_setPlayingState (2222) new playing state PLAYING [09-10-16 18:54:19.9662] Slim::Player::StreamingController::_Playing (361) Song 1 has now started playing [09-10-16 18:54:19.9685] Slim::Player::StreamingController::_Playing (391) Song queue is now 1 [09-10-16 18:54:19.9732] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.001s [09-10-16 18:54:20.1388] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:1e:ff:6a [09-10-16 18:54:20.1440] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:1e:26:20 [09-10-16 18:54:20.1539] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:16:7e:5b [09-10-16 18:54:20.2760] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: - [09-10-16 18:54:20.4000] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.001s [09-10-16 18:54:20.4718] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +1, 00:04:20:1e:ff:6a: +0 [09-10-16 18:54:21.4698] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +1, 00:04:20:1e:ff:6a: +0 [09-10-16 18:54:21.7813] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.002s [09-10-16 18:54:22.0538] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.001s [09-10-16 18:54:22.4705] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +1, 00:04:20:1e:ff:6a: +4 [09-10-16 18:54:22.7540] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.007s [09-10-16 18:54:23.4601] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.001s [09-10-16 18:54:23.4722] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +1, 00:04:20:1e:ff:6a: -3 [09-10-16 18:54:24.4755] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +1, 00:04:20:1e:ff:6a: -3 [09-10-16 18:54:25.4694] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +1, 00:04:20:1e:ff:6a: -4 [09-10-16 18:54:26.1746] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.004s [09-10-16 18:54:26.4034] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-16 18:54:26.4698] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +2, 00:04:20:1e:ff:6a: +0 [09-10-16 18:54:27.4696] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +0 [09-10-16 18:54:28.4735] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +0 [09-10-16 18:54:29.4695] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +0 [09-10-16 18:54:30.4713] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +0 [09-10-16 18:54:31.0050] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.001s [09-10-16 18:54:31.0062] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-16 18:54:31.4695] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +2 [09-10-16 18:54:32.4695] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +2 [09-10-16 18:54:33.4697] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +2 [09-10-16 18:54:34.4714] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +2 [09-10-16 18:54:35.4697] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +2 [09-10-16 18:54:36.4711] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +2 [09-10-16 18:54:37.4711] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +2 [09-10-16 18:54:38.4697] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712060.136, 00:04:20:16:7e:5b: +3, 00:04:20:1e:ff:6a: +2 [09-10-16 18:54:39.1691] Slim::Player::StreamingController::stop (1951) 00:04:20:1e:26:20 [09-10-16 18:54:39.1716] Slim::Player::StreamingController::_Stop (607) Song queue is now 1 [09-10-16 18:54:39.1723] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-16 18:54:39.1726] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-16 18:54:39.1735] Slim::Player::StreamingController::resetSongqueue (1633) Song queue is now [09-10-16 18:54:39.1764] Slim::Player::StreamingController::play (1954) 00:04:20:1e:26:20 [09-10-16 18:54:39.2046] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-16 18:54:39.2050] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-16 18:54:39.2062] Slim::Player::Song::new (107) index 0 -> http://opml.radiotime.com/StationPlaylist.axd?stationId=24860&username=sampletime [09-10-16 18:54:39.2074] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state TRACKWAIT [09-10-16 18:54:39.2078] Slim::Player::Song::getNextSong (228) http://opml.radiotime.com/StationPlaylist.axd?stationId=24860&username=sampletime [09-10-16 18:54:39.2082] Slim::Player::Song::getNextSong (250) scanning URL http://opml.radiotime.com/StationPlaylist.axd?stationId=24860&username=sampletime [09-10-16 18:54:39.2199] Slim::Player::StreamingController::_playersMessage (777) Getting stream info...: http://opml.radiotime.com/StationPlaylist.axd?stationId=24860&username=sampletime [09-10-16 18:54:39.5509] Slim::Player::TranscodingHelper::getConvertCommand2 (430) Error: Didn't find any command matches for type: unk [09-10-16 18:54:41.0537] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.005s [09-10-16 18:54:45.6984] Slim::Player::Song::__ANON__ (296) Found a playlist [09-10-16 18:54:45.6989] Slim::Player::Song::getNextSong (228) http://opml.radiotime.com/StationPlaylist.axd?stationId=24860&username=sampletime [09-10-16 18:54:45.6998] Slim::Player::Song::_getNextPlaylistTrack (217) Got next URL from playlist; track is: mms://wmscr1.dr.dk/e02ch01m?wmcontentbitrate=300000 [09-10-16 18:54:45.7002] Slim::Player::StreamingController::_nextTrackReady (733) 00:04:20:1e:26:20: nextTrack will be index 0 [09-10-16 18:54:45.7007] Slim::Player::StreamingController::_Stream (1160) Song queue is now 0 [09-10-16 18:54:45.7011] Slim::Player::StreamingController::_Stream (1163) 00:04:20:1e:26:20: preparing to stream song index 0 [09-10-16 18:54:45.7021] Slim::Player::Song::open (362) mms://wmscr1.dr.dk/e02ch01m?wmcontentbitrate=300000 [09-10-16 18:54:45.7315] Slim::Utils::Misc::msg (1161) Warning: [18:54:45.7253] Use of uninitialized value in join or string at /Log/Log4perl/Appender.pm line 164. [09-10-16 18:54:45.7323] Slim::Utils::Misc::msg (1161) Warning: [18:54:45.7320] Use of uninitialized value in join or string at /Log/Log4perl/Appender.pm line 164. [09-10-16 18:54:45.7324] Slim::Player::Song::open (382) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTED [09-10-16 18:54:45.7333] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: wma->wma via: - [09-10-16 18:54:45.7337] Slim::Player::Song::open (403) Transcoder: streamMode=I, streamformat=wma [09-10-16 18:54:45.7341] Slim::Player::Song::open (451) Opening stream (no direct streaming) using Slim::Player::Protocols::MMS [mms://wmscr1.dr.dk/e02ch01m?wmcontentbitrate=300000] [09-10-16 18:54:45.8695] Slim::Player::Song::open (472) URL is a song (audio): mms://wmscr1.dr.dk/e02ch01m?wmcontentbitrate=300000, type=wma [09-10-16 18:54:45.8706] Slim::Player::StreamingController::_Stream (1214) 00:04:20:1e:26:20: stream [09-10-16 18:54:45.8715] Slim::Player::Boom::play (335) Setting LineIn to 0 for [mms://wmscr1.dr.dk/e02ch01m?wmcontentbitrate=300000] [09-10-16 18:54:45.8719] Slim::Player::Boom::setLineIn (427) Switching to line in 0 [09-10-16 18:54:45.9156] Slim::Player::StreamingController::_Stream (1214) 00:04:20:16:7e:5b: stream [09-10-16 18:54:45.9190] Slim::Player::StreamingController::_Stream (1214) 00:04:20:1e:ff:6a: stream [09-10-16 18:54:45.9200] Slim::Player::Boom::play (335) Setting LineIn to 0 for [mms://wmscr1.dr.dk/e02ch01m?wmcontentbitrate=300000] [09-10-16 18:54:45.9204] Slim::Player::Boom::setLineIn (427) Switching to line in 0 [09-10-16 18:54:45.9236] Slim::Player::StreamingController::_Stream (1245) Song queue is now 0 [09-10-16 18:54:45.9242] Slim::Player::StreamingController::_setPlayingState (2222) new playing state BUFFERING [09-10-16 18:54:45.9245] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state STREAMING [09-10-16 18:54:45.9436] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.002s [09-10-16 18:54:45.9542] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.002s [09-10-16 18:54:45.9601] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.002s [09-10-16 18:54:45.9617] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.001s [09-10-16 18:54:46.0019] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.002s [09-10-16 18:54:46.0033] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.001s [09-10-16 18:54:46.0373] Slim::Player::Player::_buffering (1135) Buffering... 35103 / 49152 [09-10-16 18:54:46.0415] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.002s [09-10-16 18:54:46.0448] Slim::Player::Player::_buffering (1135) Buffering... 27980 / 49152 [09-10-16 18:54:46.0466] Slim::Player::Player::_buffering (1135) Buffering... 35103 / 49152 [09-10-16 18:54:46.1373] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:ff:6a [09-10-16 18:54:46.1378] Slim::Player::StreamingController::_setPlayingState (2222) new playing state WAITING_TO_SYNC [09-10-16 18:54:46.1387] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:26:20 [09-10-16 18:54:46.1401] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:16:7e:5b [09-10-16 18:54:46.1406] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:26:20 startAt: 104862 [09-10-16 18:54:46.1412] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:16:7e:5b startAt: 200594 [09-10-16 18:54:46.1416] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:ff:6a startAt: 42885 [09-10-16 18:54:46.1421] Slim::Player::StreamingController::_setPlayingState (2222) new playing state PLAYING [09-10-16 18:54:46.1425] Slim::Player::StreamingController::_Playing (361) Song 0 has now started playing [09-10-16 18:54:46.1448] Slim::Player::StreamingController::_Playing (391) Song queue is now 0 [09-10-16 18:54:46.1501] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:1e:26:20 bailing as no playPoint [09-10-16 18:54:46.5187] Slim::Player::TranscodingHelper::checkBin (265) couldn't find binary for: lame [09-10-16 18:54:46.5191] Slim::Player::TranscodingHelper::getConvertCommand2 (430) Error: Didn't find any command matches for type: wma [09-10-16 18:54:46.9398] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:1e:ff:6a [09-10-16 18:54:46.9494] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:1e:26:20 [09-10-16 18:54:47.4665] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-16 18:54:47.8061] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:16:7e:5b [09-10-16 18:54:48.3963] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.001s [09-10-16 18:54:48.4640] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.935, 00:04:20:16:7e:5b: +17, 00:04:20:1e:ff:6a: -2 [09-10-16 18:54:49.4698] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.934, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -1 [09-10-16 18:54:50.4692] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.935, 00:04:20:16:7e:5b: +17, 00:04:20:1e:ff:6a: -2 [09-10-16 18:54:51.4699] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.934, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -1 [09-10-16 18:54:52.3949] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.001s [09-10-16 18:54:52.4634] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.934, 00:04:20:16:7e:5b: +17, 00:04:20:1e:ff:6a: -1 [09-10-16 18:54:53.4697] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.934, 00:04:20:16:7e:5b: +17, 00:04:20:1e:ff:6a: -1 [09-10-16 18:54:54.4691] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.934, 00:04:20:16:7e:5b: +17, 00:04:20:1e:ff:6a: -1 [09-10-16 18:54:55.4695] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.934, 00:04:20:16:7e:5b: +17, 00:04:20:1e:ff:6a: -1 [09-10-16 18:54:56.0046] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-16 18:54:56.4723] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.934, 00:04:20:16:7e:5b: +17, 00:04:20:1e:ff:6a: -1 [09-10-16 18:54:57.4712] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.934, 00:04:20:16:7e:5b: +17, 00:04:20:1e:ff:6a: -2 [09-10-16 18:54:58.4697] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.934, 00:04:20:16:7e:5b: +17, 00:04:20:1e:ff:6a: -1 [09-10-16 18:54:59.4697] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.934, 00:04:20:16:7e:5b: +17, 00:04:20:1e:ff:6a: -1 [09-10-16 18:55:00.4008] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s [09-10-16 18:55:00.4586] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s [09-10-16 18:55:00.4725] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s [09-10-16 18:55:00.4734] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.939, 00:04:20:16:7e:5b: +17, 00:04:20:1e:ff:6a: -1 [09-10-16 18:55:01.4555] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.003s [09-10-16 18:55:01.4665] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.001s [09-10-16 18:55:01.4674] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.937, 00:04:20:16:7e:5b: +19, 00:04:20:1e:ff:6a: -2 [09-10-16 18:55:02.4702] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.937, 00:04:20:16:7e:5b: +19, 00:04:20:1e:ff:6a: -2 [09-10-16 18:55:03.4717] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.937, 00:04:20:16:7e:5b: +19, 00:04:20:1e:ff:6a: -2 [09-10-16 18:55:04.3972] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.003s [09-10-16 18:55:04.4544] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.001s [09-10-16 18:55:04.4646] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.002s [09-10-16 18:55:04.4661] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.935, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -2 [09-10-16 18:55:05.4693] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.935, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -3 [09-10-16 18:55:06.4700] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.935, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -3 [09-10-16 18:55:07.4800] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.935, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -3 [09-10-16 18:55:08.4697] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.935, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -3 [09-10-16 18:55:09.4697] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.935, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -3 [09-10-16 18:55:10.4742] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.935, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -3 [09-10-16 18:55:11.0048] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.004s [09-10-16 18:55:11.0059] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.004s [09-10-16 18:55:11.0068] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.003s [09-10-16 18:55:11.3994] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.001s [09-10-16 18:55:11.4571] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.001s [09-10-16 18:55:11.4669] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.937, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -2 [09-10-16 18:55:12.4713] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.937, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -3 [09-10-16 18:55:13.5452] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712086.937, 00:04:20:16:7e:5b: +18, 00:04:20:1e:ff:6a: -3 [09-10-16 18:55:15.3567] Slim::Player::StreamingController::stop (1951) 00:04:20:1e:26:20 [09-10-16 18:55:15.3595] Slim::Player::StreamingController::_Stop (607) Song queue is now 0 [09-10-16 18:55:15.3602] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-16 18:55:15.3606] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-16 18:55:15.3624] Slim::Player::StreamingController::resetSongqueue (1633) Song queue is now [09-10-16 18:55:15.3660] Slim::Player::StreamingController::play (1954) 00:04:20:1e:26:20 [09-10-16 18:55:15.3686] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-16 18:55:15.3690] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-16 18:55:15.3699] Slim::Player::Song::new (107) index 0 -> rhapd://Tra.1942496.mp3 [09-10-16 18:55:15.3705] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state TRACKWAIT [09-10-16 18:55:15.3709] Slim::Player::Song::getNextSong (228) rhapd://Tra.1942496.mp3 [09-10-16 18:55:15.3713] Slim::Player::Song::getNextSong (250) scanning URL rhapd://Tra.1942496.mp3 [09-10-16 18:55:15.3717] Slim::Player::Song::getNextSong (228) rhapd://Tra.1942496.mp3 [09-10-16 18:55:15.4539] Slim::Player::StreamingController::_playersMessage (777) Getting stream info...: rhapd://Tra.1942496.mp3 [09-10-16 18:55:15.4828] Slim::Player::StreamingController::play (1954) 00:04:20:1e:26:20 [09-10-16 18:55:15.4857] Slim::Player::StreamingController::_Stop (607) Song queue is now 0 [09-10-16 18:55:15.4861] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-16 18:55:15.4864] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-16 18:55:15.4875] Slim::Player::Song::new (107) index 0 -> rhapd://Tra.1942496.mp3 [09-10-16 18:55:15.4881] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state TRACKWAIT [09-10-16 18:55:15.4886] Slim::Player::Song::getNextSong (228) rhapd://Tra.1942496.mp3 [09-10-16 18:55:15.4889] Slim::Player::Song::getNextSong (250) scanning URL rhapd://Tra.1942496.mp3 [09-10-16 18:55:15.4894] Slim::Player::Song::getNextSong (228) rhapd://Tra.1942496.mp3 [09-10-16 18:55:15.4935] Slim::Player::StreamingController::_playersMessage (777) Getting stream info...: rhapd://Tra.1942496.mp3 [09-10-16 18:55:15.9969] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.005s [09-10-16 18:55:15.9979] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.003s [09-10-16 18:55:16.3363] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-16 18:55:19.9746] Slim::Player::StreamingController::_nextTrackReady (726) 00:04:20:1e:26:20: discarding unexpected nextTrackCallbackId 41, expected 43 [09-10-16 18:55:19.9864] Slim::Player::StreamingController::_nextTrackReady (733) 00:04:20:1e:26:20: nextTrack will be index 0 [09-10-16 18:55:19.9868] Slim::Player::StreamingController::_Stream (1160) Song queue is now 0 [09-10-16 18:55:19.9872] Slim::Player::StreamingController::_Stream (1163) 00:04:20:1e:26:20: preparing to stream song index 0 [09-10-16 18:55:19.9875] Slim::Player::Song::open (362) rhapd://Tra.1942496.mp3 [09-10-16 18:55:19.9880] Slim::Player::Song::open (382) seek=false time=0 canSeek=1 [09-10-16 18:55:19.9892] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: mp3->mp3 via: - [09-10-16 18:55:19.9896] Slim::Player::Song::open (403) Transcoder: streamMode=I, streamformat=mp3 [09-10-16 18:55:19.9900] Slim::Player::Song::open (451) Opening stream (no direct streaming) using Slim::Plugin::RhapsodyDirect::ProtocolHandler [rhapd://Tra.1942496.mp3] [09-10-16 18:55:20.7744] Slim::Player::Song::open (472) URL is a song (audio): rhapd://Tra.1942496.mp3, type=mp3 [09-10-16 18:55:20.7756] Slim::Player::StreamingController::_Stream (1214) 00:04:20:1e:26:20: stream [09-10-16 18:55:20.7766] Slim::Player::Boom::play (335) Setting LineIn to 0 for [http://llnw-627.listen.com/2/7/0/6/5/64556072.rad] [09-10-16 18:55:20.7770] Slim::Player::Boom::setLineIn (427) Switching to line in 0 [09-10-16 18:55:20.7874] Slim::Player::StreamingController::_Stream (1214) 00:04:20:16:7e:5b: stream [09-10-16 18:55:20.7982] Slim::Player::StreamingController::_Stream (1214) 00:04:20:1e:ff:6a: stream [09-10-16 18:55:20.7994] Slim::Player::Boom::play (335) Setting LineIn to 0 for [http://llnw-627.listen.com/2/7/0/6/5/64556072.rad] [09-10-16 18:55:20.7999] Slim::Player::Boom::setLineIn (427) Switching to line in 0 [09-10-16 18:55:20.8080] Slim::Player::StreamingController::_Stream (1245) Song queue is now 0 [09-10-16 18:55:20.8086] Slim::Player::StreamingController::_setPlayingState (2222) new playing state BUFFERING [09-10-16 18:55:20.8090] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state STREAMING [09-10-16 18:55:20.9073] Slim::Player::Player::_buffering (1135) Buffering... 0 / 73728 [09-10-16 18:55:20.9123] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.002s [09-10-16 18:55:20.9135] Slim::Player::Player::_buffering (1135) Buffering... 0 / 73728 [09-10-16 18:55:20.9164] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.001s [09-10-16 18:55:20.9284] Slim::Player::Player::_buffering (1135) Buffering... 0 / 73728 [09-10-16 18:55:20.9391] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.004s [09-10-16 18:55:21.0050] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-16 18:55:21.2132] Slim::Player::Player::_buffering (1135) Buffering... 5828 / 73728 [09-10-16 18:55:21.2210] Slim::Player::Player::_buffering (1135) Buffering... 5828 / 73728 [09-10-16 18:55:21.2364] Slim::Player::Player::_buffering (1135) Buffering... 5828 / 73728 [09-10-16 18:55:21.5177] Slim::Player::Player::_buffering (1135) Buffering... 18968 / 73728 [09-10-16 18:55:21.5274] Slim::Player::Player::_buffering (1135) Buffering... 20428 / 73728 [09-10-16 18:55:21.5409] Slim::Player::Player::_buffering (1135) Buffering... 20428 / 73728 [09-10-16 18:55:21.6102] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:26:20 [09-10-16 18:55:21.6108] Slim::Player::StreamingController::_setPlayingState (2222) new playing state WAITING_TO_SYNC [09-10-16 18:55:21.8227] Slim::Player::Player::_buffering (1135) Buffering... 74448 / 73728 [09-10-16 18:55:21.8299] Slim::Player::Player::_buffering (1135) Buffering... 39408 / 73728 [09-10-16 18:55:21.8457] Slim::Player::Player::_buffering (1135) Buffering... 51088 / 73728 [09-10-16 18:55:22.1274] Slim::Player::Player::_buffering (1135) Buffering... 74448 / 73728 [09-10-16 18:55:22.1351] Slim::Player::Player::_buffering (1135) Buffering... 39408 / 73728 [09-10-16 18:55:22.1506] Slim::Player::Player::_buffering (1135) Buffering... 51088 / 73728 [09-10-16 18:55:22.2294] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:16:7e:5b [09-10-16 18:55:22.4318] Slim::Player::Player::_buffering (1135) Buffering... 74448 / 73728 [09-10-16 18:55:22.4421] Slim::Player::Player::_buffering (1135) Buffering... 74448 / 73728 [09-10-16 18:55:22.4555] Slim::Player::Player::_buffering (1135) Buffering... 51088 / 73728 [09-10-16 18:55:22.7373] Slim::Player::Player::_buffering (1135) Buffering... 74448 / 73728 [09-10-16 18:55:22.7444] Slim::Player::Player::_buffering (1135) Buffering... 74448 / 73728 [09-10-16 18:55:22.7603] Slim::Player::Player::_buffering (1135) Buffering... 51088 / 73728 [09-10-16 18:55:23.0184] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:ff:6a [09-10-16 18:55:23.0189] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:26:20 startAt: 141740 [09-10-16 18:55:23.0194] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:16:7e:5b startAt: 237471 [09-10-16 18:55:23.0199] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:ff:6a startAt: 79760 [09-10-16 18:55:23.0204] Slim::Player::StreamingController::_setPlayingState (2222) new playing state PLAYING [09-10-16 18:55:23.0207] Slim::Player::StreamingController::_Playing (361) Song 0 has now started playing [09-10-16 18:55:23.0220] Slim::Player::StreamingController::_Playing (391) Song queue is now 0 [09-10-16 18:55:23.2372] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.003s [09-10-16 18:55:23.2925] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.001s [09-10-16 18:55:23.6310] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:1e:26:20 bailing as no playPoint [09-10-16 18:55:23.6355] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.004s [09-10-16 18:55:23.9161] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.004s [09-10-16 18:55:23.9194] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:16:7e:5b [09-10-16 18:55:23.9870] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.003s [09-10-16 18:55:24.0154] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:1e:ff:6a [09-10-16 18:55:26.0032] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.002s [09-10-16 18:55:26.0038] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:1e:26:20 bailing as no playPoint [09-10-16 18:55:26.1904] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:1e:26:20 [09-10-16 18:55:27.9886] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712126.185, 00:04:20:16:7e:5b: -2267, 00:04:20:1e:ff:6a: -2172 [09-10-16 18:55:27.9893] Slim::Player::StreamingController::_CheckSync (563) 00:04:20:1e:26:20 resync: skipAhead 2267ms [09-10-16 18:55:27.9899] Slim::Player::StreamingController::_CheckSync (563) 00:04:20:1e:ff:6a resync: skipAhead 95ms [09-10-16 18:55:30.9913] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.001s [09-10-16 18:55:31.0069] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712126.184, 00:04:20:16:7e:5b: -2267, 00:04:20:1e:ff:6a: -2265 [09-10-16 18:55:31.0074] Slim::Player::StreamingController::_CheckSync (563) 00:04:20:1e:26:20 resync: skipAhead 2267ms [09-10-16 18:55:31.0666] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.005s [09-10-16 18:55:33.0882] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712123.917, 00:04:20:16:7e:5b: +0, 00:04:20:1e:ff:6a: +1 [09-10-16 18:55:34.0885] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712123.917, 00:04:20:16:7e:5b: +0, 00:04:20:1e:ff:6a: +1 [09-10-16 18:55:35.0900] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712123.917, 00:04:20:16:7e:5b: +0, 00:04:20:1e:ff:6a: +1 [09-10-16 18:55:36.0837] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712123.918, 00:04:20:16:7e:5b: -1, 00:04:20:1e:ff:6a: +0 [09-10-16 18:55:37.0887] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712123.918, 00:04:20:16:7e:5b: -1, 00:04:20:1e:ff:6a: +0 [09-10-16 18:55:38.0880] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712123.917, 00:04:20:16:7e:5b: +0, 00:04:20:1e:ff:6a: +1 [09-10-16 18:55:39.0883] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712123.917, 00:04:20:16:7e:5b: +0, 00:04:20:1e:ff:6a: +1 [09-10-16 18:55:40.0888] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712123.918, 00:04:20:16:7e:5b: -1, 00:04:20:1e:ff:6a: +0 [09-10-16 18:55:41.0887] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712123.917, 00:04:20:16:7e:5b: +0, 00:04:20:1e:ff:6a: +1 [09-10-16 18:55:42.0878] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:1e:26:20: 1255712123.917, 00:04:20:16:7e:5b: +0, 00:04:20:1e:ff:6a: +1 [09-10-16 18:55:42.8453] Slim::Player::StreamingController::_newMaster (2187) new master: 00:04:20:16:7e:5b [09-10-16 18:55:42.8466] Slim::Player::StreamingController::playerInactive (1919) 00:04:20:16:7e:5b sync group now has: 00:04:20:1e:ff:6a,00:04:20:16:7e:5b,00:04:20:1e:26:20 [09-10-16 18:55:42.8471] Slim::Player::StreamingController::playerInactive (1920) 00:04:20:16:7e:5b active players are: 00:04:20:16:7e:5b,00:04:20:1e:ff:6a [09-10-16 18:55:42.8572] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.004s [09-10-16 18:55:42.9212] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.001s [09-10-16 18:55:43.9213] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1255712123.918, 00:04:20:1e:ff:6a: +0 [09-10-16 18:55:43.9927] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.004s [09-10-16 18:55:44.9243] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1255712123.918, 00:04:20:1e:ff:6a: +4 [09-10-16 18:55:45.9217] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1255712123.918, 00:04:20:1e:ff:6a: +4 [09-10-16 18:55:46.0051] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-16 18:55:46.9211] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1255712123.918, 00:04:20:1e:ff:6a: +4 [09-10-16 18:55:47.9212] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1255712123.918, 00:04:20:1e:ff:6a: +4 [09-10-16 18:55:48.8987] Slim::Player::StreamingController::playerInactive (1919) 00:04:20:16:7e:5b sync group now has: 00:04:20:1e:ff:6a,00:04:20:16:7e:5b,00:04:20:1e:26:20 [09-10-16 18:55:48.8991] Slim::Player::StreamingController::playerInactive (1920) 00:04:20:16:7e:5b active players are: 00:04:20:16:7e:5b [09-10-16 18:55:49.0134] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.001s [09-10-16 18:55:56.0021] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-16 18:56:02.9245] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s [09-10-16 18:56:06.0057] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.001s [09-10-16 18:56:11.0037] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-16 18:56:11.0056] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.003s [09-10-16 18:56:21.1973] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.005s [09-10-16 18:56:31.0046] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-16 18:56:31.0072] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.003s [09-10-16 18:56:36.0044] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-16 18:56:36.0054] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.002s [09-10-16 18:56:38.9217] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.002s [09-10-16 18:56:41.9196] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.002s [09-10-16 18:56:49.9250] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-16 18:56:54.9188] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.003s [09-10-16 18:57:01.0032] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-16 18:57:01.0050] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s [09-10-16 18:57:01.9201] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.004s [09-10-16 18:57:09.9247] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.003s [09-10-16 18:57:11.1142] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.005s [09-10-16 18:57:19.3220] Slim::Player::Source::_readNextChunk (330) end of file or error on socket, song pos: 9740624 [09-10-16 18:57:19.3224] Slim::Player::Source::_readNextChunk (344) 00:04:20:16:7e:5b mark end of stream [09-10-16 18:57:19.3233] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state STREAMOUT [09-10-16 18:57:20.9250] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.001s [09-10-16 18:57:22.1151] Slim::Player::StreamingController::playerEndOfStream (2131) 00:04:20:16:7e:5b [09-10-16 18:57:22.1156] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state STREAMOUT [09-10-16 18:57:22.9232] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.001s [09-10-16 18:57:23.9182] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.005s [09-10-16 18:57:26.0055] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-16 18:57:31.0036] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s [09-10-16 18:57:38.9212] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.002s [09-10-16 18:57:46.0034] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.001s [09-10-16 18:57:51.0018] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.001s [09-10-16 18:57:51.0039] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-16 18:57:57.9260] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-16 18:58:01.0880] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.005s [09-10-16 18:58:06.0127] Slim::Player::StreamingController::pause (1970) 00:04:20:16:7e:5b [09-10-16 18:58:06.0133] Slim::Player::StreamingController::_setPlayingState (2222) new playing state PAUSED [09-10-16 18:58:06.0146] Slim::Player::Source::playmode (93) 00:04:20:16:7e:5b: Current playmode: pause [09-10-16 18:58:06.3704] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s