[09-10-07 19:14:58.3029] Slim::Schema::Storage::throw_exception (82) Error: DBI Connection failed: DBI connect('hostname=127.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Can't connect to MySQL server on '127.0.0.1' (10061) at /DBIx/Class/Storage/DBI.pm line 950 [09-10-07 19:14:58.3035] Slim::Schema::Storage::throw_exception (82) Backtrace: frame 0: Slim::Utils::Log::logBacktrace (/Slim/Schema/Storage.pm line 82) frame 1: Slim::Schema::Storage::throw_exception (/DBIx/Class/Storage/DBI.pm line 972) frame 2: DBIx::Class::Storage::DBI::_connect (/DBIx/Class/Storage/DBI.pm line 814) frame 3: DBIx::Class::Storage::DBI::_populate_dbh (/DBIx/Class/Storage/DBI.pm line 568) frame 4: DBIx::Class::Storage::DBI::dbh_do (/DBIx/Class/Storage/DBI.pm line 1227) frame 5: DBIx::Class::Storage::DBI::_execute (/DBIx/Class/Storage/DBI.pm line 1451) frame 6: DBIx::Class::Storage::DBI::_select (/DBIx/Class/Storage/DBI.pm line 1907) frame 7: DBIx::Class::Storage::DBI::select_single (/DBIx/Class/ResultSet.pm line 744) frame 8: DBIx::Class::ResultSet::single (/Slim/Schema.pm line 585) frame 9: Slim::Schema::single (/Slim/Music/Import.pm line 725) frame 10: Slim::Music::Import::stillScanning (slimserver.pl line 1058) frame 11: main::cleanup (slimserver.pl line 1026) frame 12: main::stopServer (slimserver.pl line 58) frame 13: PerlSvc::Startup (/PerlSvc.pm line 95) frame 14: PerlSvc::_startup (slimserver.pl line 0) frame 15: (eval) (slimserver.pl line 0) [09-10-07 19:16:23.5959] main::init (320) Starting Squeezebox Server (v7.4.1, r28791, Wed Oct 7 04:02:20 PDT 2009) perl 5.010000 [09-10-07 19:16:24.6076] Slim::Utils::Strings::parseStrings (346) Error: Parsing line 1: # Max Spicer, May 2007 [09-10-07 19:16:56.2129] Slim::Utils::Firmware::downloadAsyncError (557) Warning: Firmware: Failed to download http://update.mysqueezebox.com/update/firmware/7.4.1/jive_7.4.1_r7834.bin.sha (Validation of firmware C:\ProgramData\Squeezebox\Cache\updates\jive_7.4.1_r7834.bin failed, SHA1 checksum did not match), will try again in 10 minutes. [09-10-07 19:17:30.0080] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.002s [09-10-07 19:17:35.0054] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.004s [09-10-07 19:17:35.0093] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s [09-10-07 19:17:35.0309] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1a:78:2b adjust jiffies epoch -0.027s [09-10-07 19:17:40.0056] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.002s [09-10-07 19:17:55.0011] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:07:ef:be adjust jiffies epoch -0.003s [09-10-07 19:17:55.0171] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1a:78:2b adjust jiffies epoch -0.003s [09-10-07 19:18:00.0025] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.001s [09-10-07 19:18:25.0097] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.002s [09-10-07 19:18:25.0130] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-07 19:18:40.0109] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.001s [09-10-07 19:18:40.0118] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.002s [09-10-07 19:18:50.0100] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.004s [09-10-07 19:18:50.0117] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s [09-10-07 19:18:50.0290] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.002s [09-10-07 19:19:03.1766] Slim::Player::StreamingController::playerActive (1877) 00:04:20:16:7e:5b sync group now has: 00:04:20:1e:26:20,00:04:20:1e:ff:6a,00:04:20:16:7e:5b [09-10-07 19:19:03.1770] Slim::Player::StreamingController::playerActive (1878) 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:19:05.0071] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.002s [09-10-07 19:19:05.0086] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-07 19:19:15.0020] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.003s [09-10-07 19:19:15.0031] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:07:ef:be adjust jiffies epoch -0.004s [09-10-07 19:19:15.0042] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.002s [09-10-07 19:19:29.3872] Slim::Player::StreamingController::stop (1951) 00:04:20:16:7e:5b [09-10-07 19:19:29.3876] Slim::Player::StreamingController::resetSongqueue (1633) Song queue is now [09-10-07 19:19:29.4138] Slim::Player::StreamingController::play (1954) 00:04:20:16:7e:5b [09-10-07 19:19:29.4161] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-07 19:19:29.4165] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-07 19:19:29.4430] 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:19:29.4436] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state TRACKWAIT [09-10-07 19:19:29.4440] 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:19:29.4445] Slim::Player::StreamingController::_nextTrackReady (733) 00:04:20:16:7e:5b: nextTrack will be index 1 [09-10-07 19:19:29.4449] Slim::Player::StreamingController::_Stream (1160) Song queue is now 1 [09-10-07 19:19:29.4452] Slim::Player::StreamingController::_Stream (1163) 00:04:20:16:7e:5b: preparing to stream song index 1 [09-10-07 19:19:29.4470] 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-07 19:19:29.4611] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: - [09-10-07 19:19:29.4615] Slim::Player::Song::open (382) seek=false time=0 canSeek=1 [09-10-07 19:19:29.4624] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: - [09-10-07 19:19:29.4628] Slim::Player::Song::open (403) Transcoder: streamMode=I, streamformat=flc [09-10-07 19:19:29.4632] 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-07 19:19:29.4643] 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:19:29.4647] 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:19:29.4659] 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-07 19:19:29.4939] Slim::Player::StreamingController::_Stream (1214) 00:04:20:16:7e:5b: stream [09-10-07 19:19:29.4961] Slim::Player::StreamingController::_Stream (1214) 00:04:20:1e:26:20: stream [09-10-07 19:19:29.4971] 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:19:29.4975] Slim::Player::Boom::setLineIn (427) Switching to line in 0 [09-10-07 19:19:29.4997] Slim::Player::StreamingController::_Stream (1245) Song queue is now 1 [09-10-07 19:19:29.5002] Slim::Player::StreamingController::_setPlayingState (2222) new playing state BUFFERING [09-10-07 19:19:29.5006] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state STREAMING [09-10-07 19:19:29.5157] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.001s [09-10-07 19:19:29.5173] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.003s [09-10-07 19:19:29.5745] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:16:7e:5b [09-10-07 19:19:29.5749] Slim::Player::StreamingController::_setPlayingState (2222) new playing state WAITING_TO_SYNC [09-10-07 19:19:29.5759] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:26:20 [09-10-07 19:19:29.5766] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:16:7e:5b startAt: 437058586 [09-10-07 19:19:29.5772] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:26:20 startAt: 126578146 [09-10-07 19:19:29.5777] Slim::Player::StreamingController::_setPlayingState (2222) new playing state PLAYING [09-10-07 19:19:29.5780] Slim::Player::StreamingController::_Playing (361) Song 1 has now started playing [09-10-07 19:19:29.5810] Slim::Player::StreamingController::_Playing (391) Song queue is now 1 [09-10-07 19:19:29.5843] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-07 19:19:29.7381] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:16:7e:5b [09-10-07 19:19:29.7410] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:1e:26:20 [09-10-07 19:19:29.8925] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: - [09-10-07 19:19:30.0057] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.003s [09-10-07 19:19:30.4191] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.002s [09-10-07 19:19:31.5664] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.735, 00:04:20:1e:26:20: +3 [09-10-07 19:19:33.4287] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.735, 00:04:20:1e:26:20: +3 [09-10-07 19:19:34.4234] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.735, 00:04:20:1e:26:20: +3 [09-10-07 19:19:35.4287] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.735, 00:04:20:1e:26:20: +3 [09-10-07 19:19:36.4206] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.735, 00:04:20:1e:26:20: +3 [09-10-07 19:19:37.4214] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.735, 00:04:20:1e:26:20: +3 [09-10-07 19:19:38.4241] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.001s [09-10-07 19:19:38.4248] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +1 [09-10-07 19:19:39.4220] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +1 [09-10-07 19:19:40.0101] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.002s [09-10-07 19:19:40.1116] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.004s [09-10-07 19:19:40.4251] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:19:41.4188] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.001s [09-10-07 19:19:41.4196] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.734, 00:04:20:1e:26:20: +5 [09-10-07 19:19:42.4217] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.734, 00:04:20:1e:26:20: +5 [09-10-07 19:19:43.4253] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.734, 00:04:20:1e:26:20: +5 [09-10-07 19:19:44.4209] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.734, 00:04:20:1e:26:20: +5 [09-10-07 19:19:45.4252] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.734, 00:04:20:1e:26:20: +5 [09-10-07 19:19:46.4252] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.734, 00:04:20:1e:26:20: +5 [09-10-07 19:19:47.4252] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.734, 00:04:20:1e:26:20: +5 [09-10-07 19:19:48.4250] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.734, 00:04:20:1e:26:20: +5 [09-10-07 19:19:49.4211] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-07 19:19:49.4222] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:19:50.4251] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:19:51.4236] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:19:52.4218] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:19:53.4232] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:19:54.4252] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:19:55.0088] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.001s [09-10-07 19:19:55.0103] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.001s [09-10-07 19:19:55.4248] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:19:56.4240] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:19:57.4216] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:19:58.4227] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:19:59.4216] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:20:00.4258] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:20:01.4254] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:20:02.4223] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.736, 00:04:20:1e:26:20: +3 [09-10-07 19:20:03.2705] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.002s [09-10-07 19:20:03.4204] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.734, 00:04:20:1e:26:20: +2 [09-10-07 19:20:04.4219] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.735, 00:04:20:1e:26:20: +1 [09-10-07 19:20:05.4262] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.735, 00:04:20:1e:26:20: +1 [09-10-07 19:20:06.4254] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.734, 00:04:20:1e:26:20: +2 [09-10-07 19:20:07.4527] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254935969.734, 00:04:20:1e:26:20: +2 [09-10-07 19:20:07.4535] Slim::Player::StreamingController::stop (1951) 00:04:20:16:7e:5b [09-10-07 19:20:07.4552] Slim::Player::StreamingController::_Stop (607) Song queue is now 1 [09-10-07 19:20:07.4559] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-07 19:20:07.4562] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-07 19:20:07.4571] Slim::Player::StreamingController::resetSongqueue (1633) Song queue is now [09-10-07 19:20:07.4641] Slim::Player::StreamingController::play (1954) 00:04:20:16:7e:5b [09-10-07 19:20:07.4658] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-07 19:20:07.4662] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-07 19:20:07.4670] Slim::Player::Song::new (107) index 0 -> rhapd://Tra.26589358.mp3 [09-10-07 19:20:07.4677] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state TRACKWAIT [09-10-07 19:20:07.4681] Slim::Player::Song::getNextSong (228) rhapd://Tra.26589358.mp3 [09-10-07 19:20:07.4684] Slim::Player::Song::getNextSong (250) scanning URL rhapd://Tra.26589358.mp3 [09-10-07 19:20:07.4689] Slim::Player::Song::getNextSong (228) rhapd://Tra.26589358.mp3 [09-10-07 19:20:07.4758] Slim::Player::StreamingController::_playersMessage (777) Getting stream info...: rhapd://Tra.26589358.mp3 [09-10-07 19:20:07.4967] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.002s [09-10-07 19:20:07.4992] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-07 19:20:09.1987] Slim::Player::StreamingController::_nextTrackReady (733) 00:04:20:16:7e:5b: nextTrack will be index 0 [09-10-07 19:20:09.1992] Slim::Player::StreamingController::_Stream (1160) Song queue is now 0 [09-10-07 19:20:09.2009] Slim::Player::StreamingController::_Stream (1163) 00:04:20:16:7e:5b: preparing to stream song index 0 [09-10-07 19:20:09.2017] Slim::Player::Song::open (362) rhapd://Tra.26589358.mp3 [09-10-07 19:20:09.2034] Slim::Player::Song::open (382) seek=false time=0 canSeek=1 [09-10-07 19:20:09.2045] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: mp3->mp3 via: - [09-10-07 19:20:09.2049] Slim::Player::Song::open (403) Transcoder: streamMode=I, streamformat=mp3 [09-10-07 19:20:09.2053] Slim::Player::Song::open (451) Opening stream (no direct streaming) using Slim::Plugin::RhapsodyDirect::ProtocolHandler [rhapd://Tra.26589358.mp3] [09-10-07 19:20:09.5505] Slim::Player::Song::open (472) URL is a song (audio): rhapd://Tra.26589358.mp3, type=mp3 [09-10-07 19:20:09.5517] Slim::Player::StreamingController::_Stream (1214) 00:04:20:16:7e:5b: stream [09-10-07 19:20:09.5560] Slim::Player::StreamingController::_Stream (1214) 00:04:20:1e:26:20: stream [09-10-07 19:20:09.5571] 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:20:09.5575] Slim::Player::Boom::setLineIn (427) Switching to line in 0 [09-10-07 19:20:09.5613] Slim::Player::StreamingController::_Stream (1245) Song queue is now 0 [09-10-07 19:20:09.5618] Slim::Player::StreamingController::_setPlayingState (2222) new playing state BUFFERING [09-10-07 19:20:09.5622] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state STREAMING [09-10-07 19:20:09.6798] Slim::Player::Player::_buffering (1135) Buffering... 0 / 73728 [09-10-07 19:20:09.6873] Slim::Player::Player::_buffering (1135) Buffering... 0 / 73728 [09-10-07 19:20:09.6920] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.002s [09-10-07 19:20:09.9844] Slim::Player::Player::_buffering (1135) Buffering... 5620 / 73728 [09-10-07 19:20:09.9882] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s [09-10-07 19:20:09.9920] Slim::Player::Player::_buffering (1135) Buffering... 7080 / 73728 [09-10-07 19:20:10.2901] Slim::Player::Player::_buffering (1135) Buffering... 20220 / 73728 [09-10-07 19:20:10.2980] Slim::Player::Player::_buffering (1135) Buffering... 20220 / 73728 [09-10-07 19:20:10.3770] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:16:7e:5b [09-10-07 19:20:10.3774] Slim::Player::StreamingController::_setPlayingState (2222) new playing state WAITING_TO_SYNC [09-10-07 19:20:10.3961] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:26:20 [09-10-07 19:20:10.3966] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:16:7e:5b startAt: 437099401 [09-10-07 19:20:10.3973] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:26:20 startAt: 126618964 [09-10-07 19:20:10.3978] Slim::Player::StreamingController::_setPlayingState (2222) new playing state PLAYING [09-10-07 19:20:10.3982] Slim::Player::StreamingController::_Playing (361) Song 0 has now started playing [09-10-07 19:20:10.3996] Slim::Player::StreamingController::_Playing (391) Song queue is now 0 [09-10-07 19:20:12.4117] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-07 19:20:14.9743] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:16:7e:5b [09-10-07 19:20:15.0047] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:1e:26:20 bailing as no playPoint [09-10-07 19:20:15.0053] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.001s [09-10-07 19:20:15.1534] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.002s [09-10-07 19:20:15.1540] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:1e:26:20 [09-10-07 19:20:15.7081] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.002s [09-10-07 19:20:16.7120] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.972, 00:04:20:1e:26:20: +178 [09-10-07 19:20:16.7128] Slim::Player::StreamingController::_CheckSync (563) 00:04:20:1e:26:20 resync: skipAhead 178ms [09-10-07 19:20:18.7129] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.972, 00:04:20:1e:26:20: +0 [09-10-07 19:20:19.7130] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.972, 00:04:20:1e:26:20: +0 [09-10-07 19:20:20.0066] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-07 19:20:20.0089] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s [09-10-07 19:20:20.7132] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.972, 00:04:20:1e:26:20: +0 [09-10-07 19:20:21.7133] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.972, 00:04:20:1e:26:20: +0 [09-10-07 19:20:22.6995] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.003s [09-10-07 19:20:22.7133] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.972, 00:04:20:1e:26:20: +3 [09-10-07 19:20:23.7130] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-07 19:20:23.7138] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.973, 00:04:20:1e:26:20: +1 [09-10-07 19:20:24.7134] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.973, 00:04:20:1e:26:20: +1 [09-10-07 19:20:25.7135] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.973, 00:04:20:1e:26:20: +1 [09-10-07 19:20:26.7143] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.973, 00:04:20:1e:26:20: +1 [09-10-07 19:20:27.7136] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.973, 00:04:20:1e:26:20: +1 [09-10-07 19:20:28.7125] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.973, 00:04:20:1e:26:20: +1 [09-10-07 19:20:29.7135] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.973, 00:04:20:1e:26:20: +1 [09-10-07 19:20:30.0139] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.002s [09-10-07 19:20:30.0148] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-07 19:20:30.1174] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.005s [09-10-07 19:20:30.7094] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.003s [09-10-07 19:20:30.7103] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.973, 00:04:20:1e:26:20: +4 [09-10-07 19:20:30.7388] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.003s [09-10-07 19:20:31.8001] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936014.973, 00:04:20:1e:26:20: +2 [09-10-07 19:20:32.6275] Slim::Player::StreamingController::playerActive (1877) 00:04:20:16:7e:5b sync group now has: 00:04:20:1e:26:20,00:04:20:1e:ff:6a,00:04:20:16:7e:5b [09-10-07 19:20:32.6279] Slim::Player::StreamingController::playerActive (1878) 00:04:20:16:7e:5b active players are: 00:04:20:16:7e:5b,00:04:20:1e:26:20,00:04:20:1e:ff:6a [09-10-07 19:20:32.6282] Slim::Player::StreamingController::playerActive (1882) 00:04:20:16:7e:5b restart play [09-10-07 19:20:32.6372] Slim::Player::StreamingController::_Stop (607) Song queue is now 0 [09-10-07 19:20:32.6382] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-07 19:20:32.6389] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-07 19:20:32.6396] Slim::Player::StreamingController::_Stream (1086) 00:04:20:16:7e:5b: got song from params, song index 0 [09-10-07 19:20:32.6410] Slim::Player::StreamingController::_Stream (1160) Song queue is now 0 [09-10-07 19:20:32.6414] Slim::Player::StreamingController::_Stream (1163) 00:04:20:16:7e:5b: preparing to stream song index 0 [09-10-07 19:20:32.6418] Slim::Player::Song::open (362) rhapd://Tra.26589358.mp3 [09-10-07 19:20:32.6424] Slim::Player::Song::open (382) seek=true time=16.822 canSeek=1 [09-10-07 19:20:32.6436] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: mp3->mp3 via: - [09-10-07 19:20:32.6468] Slim::Player::Song::open (403) Transcoder: streamMode=I, streamformat=mp3 [09-10-07 19:20:32.6475] Slim::Player::Song::open (451) Opening stream (no direct streaming) using Slim::Plugin::RhapsodyDirect::ProtocolHandler [rhapd://Tra.26589358.mp3] [09-10-07 19:20:32.9293] Slim::Player::Song::open (472) URL is a song (audio): rhapd://Tra.26589358.mp3, type=mp3 [09-10-07 19:20:32.9305] Slim::Player::StreamingController::_Stream (1214) 00:04:20:16:7e:5b: stream [09-10-07 19:20:32.9369] Slim::Player::StreamingController::_Stream (1214) 00:04:20:1e:26:20: stream [09-10-07 19:20:32.9411] 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:20:32.9416] Slim::Player::Boom::setLineIn (427) Switching to line in 0 [09-10-07 19:20:32.9462] Slim::Player::StreamingController::_Stream (1214) 00:04:20:1e:ff:6a: stream [09-10-07 19:20:32.9473] 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:20:32.9478] Slim::Player::Boom::setLineIn (427) Switching to line in 0 [09-10-07 19:20:32.9518] Slim::Player::StreamingController::_Stream (1245) Song queue is now 0 [09-10-07 19:20:32.9525] Slim::Player::StreamingController::_setPlayingState (2222) new playing state BUFFERING [09-10-07 19:20:32.9528] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state STREAMING [09-10-07 19:20:32.9895] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s [09-10-07 19:20:32.9908] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.004s [09-10-07 19:20:32.9940] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.004s [09-10-07 19:20:33.0599] Slim::Player::Player::_buffering (1135) Buffering... 0 / 73728 [09-10-07 19:20:33.0679] Slim::Player::Player::_buffering (1135) Buffering... 0 / 73728 [09-10-07 19:20:33.0755] Slim::Player::Player::_buffering (1135) Buffering... 0 / 73728 [09-10-07 19:20:33.3568] Slim::Player::Player::_buffering (1135) Buffering... 1460 / 73728 [09-10-07 19:20:33.3746] Slim::Player::Player::_buffering (1135) Buffering... 1460 / 73728 [09-10-07 19:20:33.3767] Slim::Player::Player::_buffering (1135) Buffering... 5840 / 73728 [09-10-07 19:20:33.3806] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s [09-10-07 19:20:33.6657] Slim::Player::Player::_buffering (1135) Buffering... 20440 / 73728 [09-10-07 19:20:33.6813] Slim::Player::Player::_buffering (1135) Buffering... 20440 / 73728 [09-10-07 19:20:33.6830] Slim::Player::Player::_buffering (1135) Buffering... 20440 / 73728 [09-10-07 19:20:33.7732] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:16:7e:5b [09-10-07 19:20:33.7736] Slim::Player::StreamingController::_setPlayingState (2222) new playing state WAITING_TO_SYNC [09-10-07 19:20:33.7894] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:ff:6a [09-10-07 19:20:33.8731] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:26:20 [09-10-07 19:20:33.8736] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:16:7e:5b startAt: 437122874 [09-10-07 19:20:33.8745] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:26:20 startAt: 126642434 [09-10-07 19:20:33.8750] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:ff:6a startAt: 426374179 [09-10-07 19:20:33.8755] Slim::Player::StreamingController::_setPlayingState (2222) new playing state PLAYING [09-10-07 19:20:33.8758] Slim::Player::StreamingController::_Playing (361) Song 0 has now started playing [09-10-07 19:20:33.8792] Slim::Player::StreamingController::_Playing (391) Song queue is now 0 [09-10-07 19:20:33.8835] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s [09-10-07 19:20:33.8851] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-07 19:20:34.1038] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:1e:26:20 [09-10-07 19:20:34.9983] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.003s [09-10-07 19:20:34.9990] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-07 19:20:38.1942] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.001s [09-10-07 19:20:38.3145] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:1e:ff:6a [09-10-07 19:20:40.0023] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-07 19:20:44.9808] Slim::Player::StreamingController::playerOutputUnderrun (2073) 00:04:20:1e:26:20: decoder: 3145060 / output: 0 [09-10-07 19:20:44.9813] Slim::Player::StreamingController::_setPlayingState (2222) new playing state BUFFERING [09-10-07 19:20:45.0048] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-07 19:20:45.0260] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.002s [09-10-07 19:20:45.1114] Slim::Player::Player::_buffering (1135) Buffering... 3145727 / 120000 [09-10-07 19:20:45.1119] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:16:7e:5b [09-10-07 19:20:45.1122] Slim::Player::StreamingController::_setPlayingState (2222) new playing state WAITING_TO_SYNC [09-10-07 19:20:45.1195] Slim::Player::Player::_buffering (1135) Buffering... 3145060 / 120000 [09-10-07 19:20:45.1199] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:26:20 [09-10-07 19:20:45.1211] Slim::Player::Player::_buffering (1135) Buffering... 3142720 / 120000 [09-10-07 19:20:45.1215] Slim::Player::Player::_buffering (1136) +output... 1681912 / 1764000 [09-10-07 19:20:45.1218] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:ff:6a [09-10-07 19:20:45.1222] Slim::Player::StreamingController::_setPlayingState (2222) new playing state PLAYING [09-10-07 19:20:45.1303] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s [09-10-07 19:20:45.1324] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-07 19:20:45.1331] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-07 19:20:45.2065] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s [09-10-07 19:20:45.3892] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.001s [09-10-07 19:20:45.3972] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.001s [09-10-07 19:20:50.0071] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-07 19:20:53.2084] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.005s [09-10-07 19:20:53.8549] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.004s [09-10-07 19:20:55.0037] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.002s [09-10-07 19:20:55.0044] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-07 19:20:56.8503] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.003s [09-10-07 19:21:00.0051] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-07 19:21:00.0077] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.002s [09-10-07 19:21:02.3549] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.001s [09-10-07 19:21:02.4915] Slim::Player::StreamingController::playerInactive (1919) 00:04:20:16:7e:5b sync group now has: 00:04:20:1e:26:20,00:04:20:1e:ff:6a,00:04:20:16:7e:5b [09-10-07 19:21:02.4920] Slim::Player::StreamingController::playerInactive (1920) 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:21:04.8549] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.005s [09-10-07 19:21:05.0041] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-07 19:21:05.0059] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.001s [09-10-07 19:21:07.1787] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:16:7e:5b [09-10-07 19:21:07.8138] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.159, 00:04:20:1e:26:20: -31233 [09-10-07 19:21:08.8132] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.159, 00:04:20:1e:26:20: -31233 [09-10-07 19:21:09.8137] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.159, 00:04:20:1e:26:20: -31233 [09-10-07 19:21:10.0060] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s [09-10-07 19:21:10.0076] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-07 19:21:10.8135] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.164, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:11.8080] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.003s [09-10-07 19:21:11.8088] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.162, 00:04:20:1e:26:20: -31236 [09-10-07 19:21:11.8509] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.003s [09-10-07 19:21:12.8134] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.162, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:13.8134] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.162, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:14.8135] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.162, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:15.8084] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.162, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:16.8135] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.162, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:17.8134] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.162, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:18.8136] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.162, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:19.8136] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.161, 00:04:20:1e:26:20: -31238 [09-10-07 19:21:20.1526] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.005s [09-10-07 19:21:20.8137] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.162, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:21.8188] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.162, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:22.8135] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.161, 00:04:20:1e:26:20: -31238 [09-10-07 19:21:23.8122] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.004s [09-10-07 19:21:23.8130] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.165, 00:04:20:1e:26:20: -31242 [09-10-07 19:21:23.8551] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.004s [09-10-07 19:21:24.8141] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.166, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:25.8080] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.004s [09-10-07 19:21:25.8090] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.161, 00:04:20:1e:26:20: -31234 [09-10-07 19:21:25.8507] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.004s [09-10-07 19:21:26.2586] Slim::Player::Source::_readNextChunk (330) end of file or error on socket, song pos: 17417271 [09-10-07 19:21:26.2590] Slim::Player::Source::_readNextChunk (344) 00:04:20:16:7e:5b mark end of stream [09-10-07 19:21:26.2593] Slim::Player::Source::_readNextChunk (344) 00:04:20:1e:26:20 mark end of stream [09-10-07 19:21:26.2599] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state STREAMOUT [09-10-07 19:21:26.8134] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.161, 00:04:20:1e:26:20: -31238 [09-10-07 19:21:27.8137] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.161, 00:04:20:1e:26:20: -31238 [09-10-07 19:21:28.6687] Slim::Player::StreamingController::playerEndOfStream (2131) 00:04:20:1e:26:20 [09-10-07 19:21:28.8136] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.161, 00:04:20:1e:26:20: -31238 [09-10-07 19:21:29.8133] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.161, 00:04:20:1e:26:20: -31238 [09-10-07 19:21:30.8125] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.162, 00:04:20:1e:26:20: -31239 [09-10-07 19:21:31.8114] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.161, 00:04:20:1e:26:20: -31238 [09-10-07 19:21:32.8134] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.161, 00:04:20:1e:26:20: -31238 [09-10-07 19:21:33.8114] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.003s [09-10-07 19:21:33.8122] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.164, 00:04:20:1e:26:20: -31241 [09-10-07 19:21:33.8543] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.004s [09-10-07 19:21:34.8080] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.003s [09-10-07 19:21:34.8088] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936067.161, 00:04:20:1e:26:20: -31234 [09-10-07 19:21:34.8507] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.004s [09-10-07 19:21:35.0010] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.005s [09-10-07 19:21:35.0032] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.002s [09-10-07 19:21:35.7489] Slim::Player::StreamingController::stop (1951) 00:04:20:16:7e:5b [09-10-07 19:21:35.7533] Slim::Player::StreamingController::_Stop (607) Song queue is now 0 [09-10-07 19:21:35.7537] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-07 19:21:35.7540] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-07 19:21:35.7549] Slim::Player::StreamingController::resetSongqueue (1633) Song queue is now [09-10-07 19:21:35.7804] Slim::Player::StreamingController::play (1954) 00:04:20:16:7e:5b [09-10-07 19:21:35.7822] Slim::Player::StreamingController::_setPlayingState (2222) new playing state STOPPED [09-10-07 19:21:35.7826] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state IDLE [09-10-07 19:21:35.7851] 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:21:35.7859] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state TRACKWAIT [09-10-07 19:21:35.7864] 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:21:35.7868] Slim::Player::StreamingController::_nextTrackReady (733) 00:04:20:16:7e:5b: nextTrack will be index 1 [09-10-07 19:21:35.7873] Slim::Player::StreamingController::_Stream (1160) Song queue is now 1 [09-10-07 19:21:35.7876] Slim::Player::StreamingController::_Stream (1163) 00:04:20:16:7e:5b: preparing to stream song index 1 [09-10-07 19:21:35.7880] 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-07 19:21:35.7895] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: - [09-10-07 19:21:35.7899] Slim::Player::Song::open (382) seek=false time=0 canSeek=1 [09-10-07 19:21:35.7908] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: - [09-10-07 19:21:35.7911] Slim::Player::Song::open (403) Transcoder: streamMode=I, streamformat=flc [09-10-07 19:21:35.7915] 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-07 19:21:35.7928] 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:21:35.7945] 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:21:35.7956] 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-07 19:21:35.8362] Slim::Player::StreamingController::_Stream (1214) 00:04:20:16:7e:5b: stream [09-10-07 19:21:35.8382] Slim::Player::StreamingController::_Stream (1214) 00:04:20:1e:26:20: stream [09-10-07 19:21:35.8395] 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:21:35.8400] Slim::Player::Boom::setLineIn (427) Switching to line in 0 [09-10-07 19:21:35.8420] Slim::Player::StreamingController::_Stream (1245) Song queue is now 1 [09-10-07 19:21:35.8427] Slim::Player::StreamingController::_setPlayingState (2222) new playing state BUFFERING [09-10-07 19:21:35.8430] Slim::Player::StreamingController::_setStreamingState (2235) new streaming state STREAMING [09-10-07 19:21:35.8515] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.002s [09-10-07 19:21:35.8718] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.001s [09-10-07 19:21:35.9126] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:16:7e:5b [09-10-07 19:21:35.9130] Slim::Player::StreamingController::_setPlayingState (2222) new playing state WAITING_TO_SYNC [09-10-07 19:21:35.9158] Slim::Player::StreamingController::playerBufferReady (2123) 00:04:20:1e:26:20 [09-10-07 19:21:35.9169] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:16:7e:5b startAt: 437184906 [09-10-07 19:21:35.9176] Slim::Player::Squeezebox2::startAt (1038) 00:04:20:1e:26:20 startAt: 126704465 [09-10-07 19:21:35.9189] Slim::Player::StreamingController::_setPlayingState (2222) new playing state PLAYING [09-10-07 19:21:35.9193] Slim::Player::StreamingController::_Playing (361) Song 1 has now started playing [09-10-07 19:21:35.9207] Slim::Player::StreamingController::_Playing (391) Song queue is now 1 [09-10-07 19:21:35.9236] Slim::Player::StreamingController::_CheckSync (502) 00:04:20:16:7e:5b bailing as no playPoint [09-10-07 19:21:36.0754] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:1e:26:20 [09-10-07 19:21:36.0794] Slim::Player::StreamingController::playerTrackStarted (2034) 00:04:20:16:7e:5b [09-10-07 19:21:36.2306] Slim::Player::TranscodingHelper::getConvertCommand2 (433) Matched: flc->flc via: - [09-10-07 19:21:37.8167] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.072, 00:04:20:1e:26:20: -3 [09-10-07 19:21:37.8568] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.001s [09-10-07 19:21:38.8256] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.072, 00:04:20:1e:26:20: -2 [09-10-07 19:21:39.8137] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.003s [09-10-07 19:21:39.8145] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.075, 00:04:20:1e:26:20: -4 [09-10-07 19:21:40.8182] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.075, 00:04:20:1e:26:20: -5 [09-10-07 19:21:41.8134] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.075, 00:04:20:1e:26:20: -5 [09-10-07 19:21:42.8136] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.075, 00:04:20:1e:26:20: -4 [09-10-07 19:21:43.8148] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.075, 00:04:20:1e:26:20: -4 [09-10-07 19:21:44.8139] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.075, 00:04:20:1e:26:20: -5 [09-10-07 19:21:45.0083] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.002s [09-10-07 19:21:45.8080] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.004s [09-10-07 19:21:45.8088] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.071, 00:04:20:1e:26:20: +1 [09-10-07 19:21:45.8520] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.004s [09-10-07 19:21:46.8137] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.071, 00:04:20:1e:26:20: -2 [09-10-07 19:21:47.8148] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.071, 00:04:20:1e:26:20: -3 [09-10-07 19:21:48.8164] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.071, 00:04:20:1e:26:20: -2 [09-10-07 19:21:49.8137] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.071, 00:04:20:1e:26:20: -2 [09-10-07 19:21:50.8143] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.071, 00:04:20:1e:26:20: -3 [09-10-07 19:21:51.8137] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.071, 00:04:20:1e:26:20: -3 [09-10-07 19:21:52.8100] Slim::Player::StreamingController::_CheckSync (530) playPoints: 00:04:20:16:7e:5b: 1254936096.071, 00:04:20:1e:26:20: -2 [09-10-07 19:21:52.8468] Slim::Player::StreamingController::playerInactive (1919) 00:04:20:16:7e:5b sync group now has: 00:04:20:1e:26:20,00:04:20:1e:ff:6a,00:04:20:16:7e:5b [09-10-07 19:21:52.8472] Slim::Player::StreamingController::playerInactive (1920) 00:04:20:16:7e:5b active players are: 00:04:20:16:7e:5b [09-10-07 19:21:52.8536] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.004s [09-10-07 19:21:52.9512] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:26:20 adjust jiffies epoch -0.003s [09-10-07 19:22:00.0059] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.004s [09-10-07 19:22:00.0078] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:ff:6a adjust jiffies epoch +0.004s [09-10-07 19:22:00.0089] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.004s [09-10-07 19:22:06.8073] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:16:7e:5b adjust jiffies epoch -0.005s [09-10-07 19:22:10.0401] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.005s [09-10-07 19:22:10.4813] Slim::Player::StreamingController::pause (1970) 00:04:20:16:7e:5b [09-10-07 19:22:10.4822] Slim::Player::StreamingController::_setPlayingState (2222) new playing state PAUSED [09-10-07 19:22:10.4839] Slim::Player::Source::playmode (93) 00:04:20:16:7e:5b: Current playmode: pause [09-10-07 19:22:15.0081] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1e:26:20 adjust jiffies epoch +0.004s [09-10-07 19:22:35.0091] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.001s [09-10-07 19:23:00.0073] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:16:7e:5b adjust jiffies epoch +0.005s [09-10-07 19:23:00.0278] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:1a:78:2b adjust jiffies epoch +0.005s [09-10-07 19:23:05.0078] Slim::Player::Player::trackJiffiesEpoch (951) 00:04:20:07:ef:be adjust jiffies epoch +0.001s [09-10-07 19:23:30.0173] Slim::Player::Player::trackJiffiesEpoch (927) 00:04:20:1e:ff:6a adjust jiffies epoch -0.001s