[08-07-07 07:06:19.0080] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:06:19.0086] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:06:19.0091] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:06:19.0109] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:19.0118] Slim::Networking::Slimproto::_stat_handler (657) 00:04:20:12:36:52 latency=1, from (1.5, 3, 1, 1, 1.5, 1, 1.5, 2, 1, 1.5) [08-07-07 07:06:19.0126] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 30346061 fullness: 14697 (0%) bufferSize 3145728 fullness 14697 bytes_received 30346061 signal_strength: 65535 jiffies: 1283235 [08-07-07 07:06:19.0131] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 3520928 elapsed seconds: 241 [08-07-07 07:06:19.0136] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 241944 server timestamp: 4204801818 [08-07-07 07:06:19.0145] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:19.0153] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 30346061 fullness: 14697 (0%) bufferSize 3145728 fullness 14697 bytes_received 30346061 signal_strength: 65535 jiffies: 1283236 [08-07-07 07:06:19.0159] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 3520800 elapsed seconds: 241 [08-07-07 07:06:19.0164] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 241945 server timestamp: 0 [08-07-07 07:06:19.6608] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:19.6616] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMd bytes_rec_H 0 bytes_rec_L 30346061 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 30346061 signal_strength: 65535 jiffies: 1283893 [08-07-07 07:06:19.6619] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 3489664 elapsed seconds: 242 [08-07-07 07:06:19.6622] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 242602 server timestamp: 0 [08-07-07 07:06:19.6626] Slim::Player::Source::decoderUnderrun (582) 00:04:20:12:36:52: Decoder underrun while this mode: playout-play [08-07-07 07:06:19.6629] Slim::Player::Source::nextsong (1592) The next song is number 3, was 2 [08-07-07 07:06:19.6636] Slim::Player::Source::nextsong (1592) The next song is number 3, was 2 [08-07-07 07:06:19.6643] Slim::Player::Source::skipahead (909) **skipahead: opening next song [08-07-07 07:06:19.6647] Slim::Player::Source::gotoNext (1242) Opening next song... [08-07-07 07:06:19.6652] Slim::Player::Source::nextsong (1592) The next song is number 3, was 2 [08-07-07 07:06:19.6660] Slim::Player::TranscodingHelper::getConvertCommand (208) undermax = 1, type = flc, squeezebox2 = 00:04:20:12:36:52 [08-07-07 07:06:19.6664] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-wma-squeezebox2-00:04:20:12:36:52 [08-07-07 07:06:19.6668] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-wma-*-00:04:20:12:36:52 [08-07-07 07:06:19.6671] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-wma-squeezebox2-* [08-07-07 07:06:19.6674] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-wma-*-* [08-07-07 07:06:19.6677] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-ogg-squeezebox2-00:04:20:12:36:52 [08-07-07 07:06:19.6679] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-ogg-*-00:04:20:12:36:52 [08-07-07 07:06:19.6682] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-ogg-squeezebox2-* [08-07-07 07:06:19.6685] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-ogg-*-* [08-07-07 07:06:19.6688] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-flc-squeezebox2-00:04:20:12:36:52 [08-07-07 07:06:19.6692] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-flc-*-00:04:20:12:36:52 [08-07-07 07:06:19.6695] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-flc-squeezebox2-* [08-07-07 07:06:19.6698] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-flc-*-* [08-07-07 07:06:19.6701] Slim::Player::TranscodingHelper::enabledFormat (112) Checking to see if flc-flc-*-* is enabled [08-07-07 07:06:19.6704] Slim::Player::TranscodingHelper::checkBin (153) enabled [08-07-07 07:06:19.6707] Slim::Player::TranscodingHelper::checkBin (155) Found command: - [08-07-07 07:06:19.6711] Slim::Player::TranscodingHelper::getConvertCommand (309) Matched Format: flc Type: flc Command: - [08-07-07 07:06:19.6714] Slim::Player::Source::gotoNext (1329) opening next song (old format: flc, new: flc) current playmode: playout-play [08-07-07 07:06:19.6717] Slim::Player::Source::streamingSongIndex (1386) Adding song index 3 to song queue [08-07-07 07:06:19.6721] Slim::Player::Source::streamingSongIndex (1407) Song queue is now 3,2 [08-07-07 07:06:19.6727] Slim::Player::Source::skipahead (915) **skipahead: restarting [08-07-07 07:06:19.6730] Slim::Player::Source::playmode (298) 00:04:20:12:36:52: Switching to mode play from playout-play [08-07-07 07:06:19.6735] Slim::Player::Source::resetSong (1631) Resetting song buffer. [08-07-07 07:06:19.7153] Slim::Player::Source::openSong (1731) Trying to open: file:///F:/MUSIC/Dire%20Straits/Brothers%20in%20Arms/04%20Your%20Latest%20Trick.flac [08-07-07 07:06:19.7164] Slim::Player::Source::openSong (1909) duration: [393.867] size: [42738339] endian [] offset: [0] for file:///F:/MUSIC/Dire%20Straits/Brothers%20in%20Arms/04%20Your%20Latest%20Trick.flac [08-07-07 07:06:19.7170] Slim::Player::TranscodingHelper::getConvertCommand (208) undermax = 1, type = flc, squeezebox2 = 00:04:20:12:36:52 [08-07-07 07:06:19.7173] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-wma-squeezebox2-00:04:20:12:36:52 [08-07-07 07:06:19.7176] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-wma-*-00:04:20:12:36:52 [08-07-07 07:06:19.7178] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-wma-squeezebox2-* [08-07-07 07:06:19.7181] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-wma-*-* [08-07-07 07:06:19.7184] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-ogg-squeezebox2-00:04:20:12:36:52 [08-07-07 07:06:19.7186] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-ogg-*-00:04:20:12:36:52 [08-07-07 07:06:19.7189] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-ogg-squeezebox2-* [08-07-07 07:06:19.7192] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-ogg-*-* [08-07-07 07:06:19.7194] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-flc-squeezebox2-00:04:20:12:36:52 [08-07-07 07:06:19.7197] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-flc-*-00:04:20:12:36:52 [08-07-07 07:06:19.7200] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-flc-squeezebox2-* [08-07-07 07:06:19.7202] Slim::Player::TranscodingHelper::checkBin (145) Checking formats for: flc-flc-*-* [08-07-07 07:06:19.7205] Slim::Player::TranscodingHelper::enabledFormat (112) Checking to see if flc-flc-*-* is enabled [08-07-07 07:06:19.7208] Slim::Player::TranscodingHelper::checkBin (153) enabled [08-07-07 07:06:19.7210] Slim::Player::TranscodingHelper::checkBin (155) Found command: - [08-07-07 07:06:19.7213] Slim::Player::TranscodingHelper::getConvertCommand (309) Matched Format: flc Type: flc Command: - [08-07-07 07:06:19.7216] Slim::Player::Source::openSong (1938) This is an flc file: file:///F:/MUSIC/Dire%20Straits/Brothers%20in%20Arms/04%20Your%20Latest%20Trick.flac [08-07-07 07:06:19.7219] Slim::Player::Source::openSong (1939) file type: flc format: flc inrate: 867.976 maxRate: 0 [08-07-07 07:06:19.7221] Slim::Player::Source::openSong (1940) command: - [08-07-07 07:06:19.7226] Slim::Player::Source::openSong (1969) Opening file F:\MUSIC\Dire Straits\Brothers in Arms\04 Your Latest Trick.flac [08-07-07 07:06:19.7635] Slim::Player::Source::openSong (1973) Seeking in 0 into F:\MUSIC\Dire Straits\Brothers in Arms\04 Your Latest Trick.flac [08-07-07 07:06:19.7642] Slim::Player::Source::openSong (2108) Streaming with format: flc [08-07-07 07:06:19.8168] Slim::Player::Source::playmode (401) 00:04:20:12:36:52 New play mode: play [08-07-07 07:06:19.8173] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: audg, length: 18 [08-07-07 07:06:19.8178] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: audg, length: 18 [08-07-07 07:06:19.8184] Slim::Player::Squeezebox::stream (805) stream called: s paused: undef format: flc url: file:///F:/MUSIC/Dire%20Straits/Brothers%20in%20Arms/04%20Your%20Latest%20Trick.flac [08-07-07 07:06:19.8189] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox.pm line 202) frame 4: Slim::Player::Squeezebox::play (/Slim/Player/Source.pm line 484) frame 5: Slim::Player::Source::playmode (/Slim/Player/Source.pm line 917) frame 6: Slim::Player::Source::skipahead (/Slim/Player/Source.pm line 655) frame 7: Slim::Player::Source::__ANON__ (/Slim/Player/Source.pm line 679) frame 8: Slim::Player::Source::streamNextTrack (/Slim/Player/Source.pm line 643) frame 9: Slim::Player::Source::decoderUnderrun (/Slim/Networking/Slimproto.pm line 769) frame 10: Slim::Networking::Slimproto::_stat_handler (/Slim/Networking/Slimproto.pm line 381) frame 11: Slim::Networking::Slimproto::client_readable (/Slim/Networking/Select.pm line 243) frame 12: (eval) (/Slim/Networking/Select.pm line 243) frame 13: Slim::Networking::Select::select (slimserver.pl line 503) frame 14: main::idle (slimserver.pl line 453) frame 15: main::main (slimserver.pl line 88) frame 16: PerlSvc::Interactive (perlsvc line 855) frame 17: PerlSvc::_interactive (slimserver.pl line 0) frame 18: (eval) (slimserver.pl line 0) [08-07-07 07:06:19.8223] Slim::Player::Squeezebox::stream (1092) Starting with decoder with format: f autostart: 1 threshold: 255 samplesize: ? samplerate: ? endian: ? channels: ? [08-07-07 07:06:19.8229] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:06:19.8232] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 76 request string: [GET /stream.mp3?player=00:04:20:12:36:52 HTTP/1.0 ] [08-07-07 07:06:19.8235] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 76 [08-07-07 07:06:19.8240] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: audg, length: 18 [08-07-07 07:06:19.8246] Slim::Player::Source::playmode (556) 00:04:20:12:36:52: Current playmode: play [08-07-07 07:06:19.8263] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:19.8268] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: audg bytes_rec_H 0 bytes_rec_L 30346061 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 30346061 signal_strength: 65535 jiffies: 1284053 [08-07-07 07:06:19.8271] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 3433088 elapsed seconds: 242 [08-07-07 07:06:19.8274] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 242762 server timestamp: 0 [08-07-07 07:06:19.8277] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:19.8282] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: audg bytes_rec_H 0 bytes_rec_L 30346061 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 30346061 signal_strength: 65535 jiffies: 1284054 [08-07-07 07:06:19.8285] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 3432704 elapsed seconds: 242 [08-07-07 07:06:19.8288] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 242763 server timestamp: 0 [08-07-07 07:06:19.8291] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:19.8296] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMf bytes_rec_H 0 bytes_rec_L 30346061 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 30346061 signal_strength: 65535 jiffies: 1284059 [08-07-07 07:06:19.8299] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 3430912 elapsed seconds: 242 [08-07-07 07:06:19.8302] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 242768 server timestamp: 0 [08-07-07 07:06:19.8305] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:19.8310] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMc bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1284060 [08-07-07 07:06:19.8313] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 3430400 elapsed seconds: 242 [08-07-07 07:06:19.8316] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 242769 server timestamp: 0 [08-07-07 07:06:19.8323] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:19.8328] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1284061 [08-07-07 07:06:19.8331] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 3430272 elapsed seconds: 242 [08-07-07 07:06:19.8334] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 242770 server timestamp: 0 [08-07-07 07:06:19.8337] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:19.8341] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: audg bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1284062 [08-07-07 07:06:19.8344] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 3429760 elapsed seconds: 242 [08-07-07 07:06:19.8347] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 242771 server timestamp: 0 [08-07-07 07:06:19.9054] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: grfe, length: 1284 [08-07-07 07:06:19.9686] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:19.9692] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1284203 [08-07-07 07:06:19.9694] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 3380224 elapsed seconds: 242 [08-07-07 07:06:19.9697] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 242912 server timestamp: 0 [08-07-07 07:06:20.9640] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:20.9646] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1285203 [08-07-07 07:06:20.9649] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 3027456 elapsed seconds: 243 [08-07-07 07:06:20.9652] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 243912 server timestamp: 0 [08-07-07 07:06:21.9779] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:21.9785] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1286203 [08-07-07 07:06:21.9788] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 2674560 elapsed seconds: 244 [08-07-07 07:06:21.9791] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 244912 server timestamp: 0 [08-07-07 07:06:22.9752] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:22.9764] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1287203 [08-07-07 07:06:22.9770] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 2321792 elapsed seconds: 245 [08-07-07 07:06:22.9777] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 245912 server timestamp: 0 [08-07-07 07:06:23.9780] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:23.9788] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1288203 [08-07-07 07:06:23.9792] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 1969024 elapsed seconds: 246 [08-07-07 07:06:23.9796] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 246912 server timestamp: 0 [08-07-07 07:06:24.0135] Slim::Networking::Slimproto::check_all_clients (198) Checking if 00:04:20:12:36:52 is still alive [08-07-07 07:06:24.0139] Slim::Player::Squeezebox::stream (805) stream called: t paused: undef format: undef url: undef [08-07-07 07:06:24.0143] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox2.pm line 236) frame 4: Slim::Player::Squeezebox2::requestStatus (/Slim/Networking/Slimproto.pm line 220) frame 5: Slim::Networking::Slimproto::check_all_clients (/Slim/Utils/Timers.pm line 198) frame 6: (eval) (/Slim/Utils/Timers.pm line 198) frame 7: Slim::Utils::Timers::checkTimers (slimserver.pl line 506) frame 8: main::idle (slimserver.pl line 453) frame 9: main::main (slimserver.pl line 88) frame 10: PerlSvc::Interactive (perlsvc line 855) frame 11: PerlSvc::_interactive (slimserver.pl line 0) frame 12: (eval) (slimserver.pl line 0) [08-07-07 07:06:24.0146] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:06:24.0149] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:06:24.0152] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:06:24.0169] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:24.0174] Slim::Networking::Slimproto::_stat_handler (657) 00:04:20:12:36:52 latency=1, from (3, 1, 1, 1.5, 1, 1.5, 2, 1, 1.5, 1) [08-07-07 07:06:24.0180] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1288241 [08-07-07 07:06:24.0183] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 1955328 elapsed seconds: 246 [08-07-07 07:06:24.0186] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 246950 server timestamp: 4204806825 [08-07-07 07:06:24.0189] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:24.0193] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1288242 [08-07-07 07:06:24.0196] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 1955200 elapsed seconds: 246 [08-07-07 07:06:24.0199] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 246951 server timestamp: 0 [08-07-07 07:06:24.9779] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:24.9788] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1289203 [08-07-07 07:06:24.9791] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 1616256 elapsed seconds: 247 [08-07-07 07:06:24.9794] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 247912 server timestamp: 0 [08-07-07 07:06:25.9684] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:25.9691] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1290203 [08-07-07 07:06:25.9694] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 1263360 elapsed seconds: 248 [08-07-07 07:06:25.9696] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 248912 server timestamp: 0 [08-07-07 07:06:26.9779] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:26.9785] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1291203 [08-07-07 07:06:26.9788] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 910592 elapsed seconds: 249 [08-07-07 07:06:26.9791] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 249912 server timestamp: 0 [08-07-07 07:06:27.9778] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:27.9785] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1292203 [08-07-07 07:06:27.9788] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 557824 elapsed seconds: 250 [08-07-07 07:06:27.9790] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 250912 server timestamp: 0 [08-07-07 07:06:28.9778] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:28.9784] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1293203 [08-07-07 07:06:28.9787] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 205056 elapsed seconds: 251 [08-07-07 07:06:28.9790] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 251912 server timestamp: 0 [08-07-07 07:06:29.0250] Slim::Networking::Slimproto::check_all_clients (198) Checking if 00:04:20:12:36:52 is still alive [08-07-07 07:06:29.0255] Slim::Player::Squeezebox::stream (805) stream called: t paused: undef format: undef url: undef [08-07-07 07:06:29.0260] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox2.pm line 236) frame 4: Slim::Player::Squeezebox2::requestStatus (/Slim/Networking/Slimproto.pm line 220) frame 5: Slim::Networking::Slimproto::check_all_clients (/Slim/Utils/Timers.pm line 198) frame 6: (eval) (/Slim/Utils/Timers.pm line 198) frame 7: Slim::Utils::Timers::checkTimers (slimserver.pl line 506) frame 8: main::idle (slimserver.pl line 453) frame 9: main::main (slimserver.pl line 88) frame 10: PerlSvc::Interactive (perlsvc line 855) frame 11: PerlSvc::_interactive (slimserver.pl line 0) frame 12: (eval) (slimserver.pl line 0) [08-07-07 07:06:29.0264] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:06:29.0268] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:06:29.0272] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:06:29.0387] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:29.0395] Slim::Networking::Slimproto::_stat_handler (657) 00:04:20:12:36:52 latency=1, from (1, 1, 1.5, 1, 1.5, 2, 1, 1.5, 1, 6.5) [08-07-07 07:06:29.0402] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1293254 [08-07-07 07:06:29.0406] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 186944 elapsed seconds: 251 [08-07-07 07:06:29.0409] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 251963 server timestamp: 4204811836 [08-07-07 07:06:29.0414] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:29.0420] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1293254 [08-07-07 07:06:29.0423] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 186816 elapsed seconds: 251 [08-07-07 07:06:29.0428] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 251963 server timestamp: 0 [08-07-07 07:06:29.8254] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: DSCO, len: 1 [08-07-07 07:06:29.8257] Slim::Networking::Slimproto::_disco_handler (549) Squeezebox got disconnection on the data channel: Connection timed out [08-07-07 07:06:29.9232] Slim::Player::Source::errorOpening (1645) Error: While opening current track, so mark it as already played! [08-07-07 07:06:29.9238] Slim::Player::Source::errorOpening (1645) Backtrace: frame 0: Slim::Utils::Log::logBacktrace (/Slim/Player/Source.pm line 1645) frame 1: Slim::Player::Source::errorOpening (/Slim/Player/Squeezebox2.pm line 610) frame 2: Slim::Player::Squeezebox2::failedDirectStream (/Slim/Networking/Slimproto.pm line 561) frame 3: Slim::Networking::Slimproto::_disco_handler (/Slim/Networking/Slimproto.pm line 381) frame 4: Slim::Networking::Slimproto::client_readable (/Slim/Networking/Select.pm line 243) frame 5: (eval) (/Slim/Networking/Select.pm line 243) frame 6: Slim::Networking::Select::select (slimserver.pl line 503) frame 7: main::idle (slimserver.pl line 453) frame 8: main::main (slimserver.pl line 88) frame 9: PerlSvc::Interactive (perlsvc line 855) frame 10: PerlSvc::_interactive (slimserver.pl line 0) frame 11: (eval) (slimserver.pl line 0) [08-07-07 07:06:29.9343] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: grfe, length: 1284 [08-07-07 07:06:29.9349] Slim::Player::Source::playmode (298) 00:04:20:12:36:52: Switching to mode stop from play [08-07-07 07:06:29.9357] Slim::Player::Source::playmode (401) 00:04:20:12:36:52 New play mode: stop [08-07-07 07:06:29.9360] Slim::Player::Source::playmode (444) 00:04:20:12:36:52: Stopping and clearing out old chunks [08-07-07 07:06:29.9365] Slim::Player::Squeezebox::stream (805) stream called: q paused: undef format: undef url: undef [08-07-07 07:06:29.9369] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox.pm line 263) frame 4: Slim::Player::Squeezebox::stop (/Slim/Player/Squeezebox2.pm line 241) frame 5: Slim::Player::Squeezebox2::stop (/Slim/Player/Source.pm line 452) frame 6: Slim::Player::Source::playmode (/Slim/Player/Squeezebox2.pm line 621) frame 7: Slim::Player::Squeezebox2::failedDirectStream (/Slim/Networking/Slimproto.pm line 561) frame 8: Slim::Networking::Slimproto::_disco_handler (/Slim/Networking/Slimproto.pm line 381) frame 9: Slim::Networking::Slimproto::client_readable (/Slim/Networking/Select.pm line 243) frame 10: (eval) (/Slim/Networking/Select.pm line 243) frame 11: Slim::Networking::Select::select (slimserver.pl line 503) frame 12: main::idle (slimserver.pl line 453) frame 13: main::main (slimserver.pl line 88) frame 14: PerlSvc::Interactive (perlsvc line 855) frame 15: PerlSvc::_interactive (slimserver.pl line 0) frame 16: (eval) (slimserver.pl line 0) [08-07-07 07:06:29.9373] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:06:29.9376] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:06:29.9380] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:06:29.9387] Slim::Player::Source::resetSong (1631) Resetting song buffer. [08-07-07 07:06:29.9391] Slim::Player::Source::resetSongQueue (1460) Resetting song queue [08-07-07 07:06:29.9394] Slim::Player::Source::resetSongQueue (1470) Song queue is now 2 [08-07-07 07:06:29.9399] Slim::Player::Source::playmode (556) 00:04:20:12:36:52: Current playmode: stop [08-07-07 07:06:29.9410] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:29.9415] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMd bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1294063 [08-07-07 07:06:29.9418] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 252 [08-07-07 07:06:29.9421] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 252493 server timestamp: 0 [08-07-07 07:06:29.9424] Slim::Player::Source::decoderUnderrun (582) 00:04:20:12:36:52: Decoder underrun while this mode: stop [08-07-07 07:06:29.9426] Slim::Player::Source::decoderUnderrun (588) 00:04:20:12:36:52: discarding bogus decoderUnderrun while stopped [08-07-07 07:06:29.9429] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:29.9433] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMf bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1294174 [08-07-07 07:06:29.9436] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 252 [08-07-07 07:06:29.9439] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 252493 server timestamp: 0 [08-07-07 07:06:29.9442] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:29.9446] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1294174 [08-07-07 07:06:29.9449] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:29.9452] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 0 [08-07-07 07:06:34.0065] Slim::Networking::Slimproto::check_all_clients (198) Checking if 00:04:20:12:36:52 is still alive [08-07-07 07:06:34.0069] Slim::Player::Squeezebox::stream (805) stream called: t paused: undef format: undef url: undef [08-07-07 07:06:34.0073] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox2.pm line 236) frame 4: Slim::Player::Squeezebox2::requestStatus (/Slim/Networking/Slimproto.pm line 220) frame 5: Slim::Networking::Slimproto::check_all_clients (/Slim/Utils/Timers.pm line 198) frame 6: (eval) (/Slim/Utils/Timers.pm line 198) frame 7: Slim::Utils::Timers::checkTimers (slimserver.pl line 506) frame 8: main::idle (slimserver.pl line 453) frame 9: main::main (slimserver.pl line 88) frame 10: PerlSvc::Interactive (perlsvc line 855) frame 11: PerlSvc::_interactive (slimserver.pl line 0) frame 12: (eval) (slimserver.pl line 0) [08-07-07 07:06:34.0077] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:06:34.0080] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:06:34.0083] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:06:34.0095] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:34.0099] Slim::Networking::Slimproto::_stat_handler (657) 00:04:20:12:36:52 latency=1, from (1, 1.5, 1, 1.5, 2, 1, 1.5, 1, 6.5, 1) [08-07-07 07:06:34.0104] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1298234 [08-07-07 07:06:34.0107] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:34.0110] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 4204816818 [08-07-07 07:06:34.0115] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:34.0119] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1298235 [08-07-07 07:06:34.0122] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:34.0125] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 0 [08-07-07 07:06:34.9451] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: grfe, length: 1284 [08-07-07 07:06:39.0143] Slim::Networking::Slimproto::check_all_clients (198) Checking if 00:04:20:12:36:52 is still alive [08-07-07 07:06:39.0149] Slim::Player::Squeezebox::stream (805) stream called: t paused: undef format: undef url: undef [08-07-07 07:06:39.0154] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox2.pm line 236) frame 4: Slim::Player::Squeezebox2::requestStatus (/Slim/Networking/Slimproto.pm line 220) frame 5: Slim::Networking::Slimproto::check_all_clients (/Slim/Utils/Timers.pm line 198) frame 6: (eval) (/Slim/Utils/Timers.pm line 198) frame 7: Slim::Utils::Timers::checkTimers (slimserver.pl line 506) frame 8: main::idle (slimserver.pl line 453) frame 9: main::main (slimserver.pl line 88) frame 10: PerlSvc::Interactive (perlsvc line 855) frame 11: PerlSvc::_interactive (slimserver.pl line 0) frame 12: (eval) (slimserver.pl line 0) [08-07-07 07:06:39.0160] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:06:39.0164] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:06:39.0167] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:06:39.0181] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:39.0187] Slim::Networking::Slimproto::_stat_handler (657) 00:04:20:12:36:52 latency=1, from (1.5, 1, 1.5, 2, 1, 1.5, 1, 6.5, 1, 1) [08-07-07 07:06:39.0192] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1303243 [08-07-07 07:06:39.0196] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:39.0200] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 4204821826 [08-07-07 07:06:39.0206] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:39.0212] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1303244 [08-07-07 07:06:39.0216] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:39.0219] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 0 [08-07-07 07:06:44.0045] Slim::Networking::Slimproto::check_all_clients (198) Checking if 00:04:20:12:36:52 is still alive [08-07-07 07:06:44.0052] Slim::Player::Squeezebox::stream (805) stream called: t paused: undef format: undef url: undef [08-07-07 07:06:44.0058] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox2.pm line 236) frame 4: Slim::Player::Squeezebox2::requestStatus (/Slim/Networking/Slimproto.pm line 220) frame 5: Slim::Networking::Slimproto::check_all_clients (/Slim/Utils/Timers.pm line 198) frame 6: (eval) (/Slim/Utils/Timers.pm line 198) frame 7: Slim::Utils::Timers::checkTimers (slimserver.pl line 506) frame 8: main::idle (slimserver.pl line 453) frame 9: main::main (slimserver.pl line 88) frame 10: PerlSvc::Interactive (perlsvc line 855) frame 11: PerlSvc::_interactive (slimserver.pl line 0) frame 12: (eval) (slimserver.pl line 0) [08-07-07 07:06:44.0064] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:06:44.0070] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:06:44.0076] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:06:44.0101] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:44.0108] Slim::Networking::Slimproto::_stat_handler (657) 00:04:20:12:36:52 latency=1, from (1, 1.5, 2, 1, 1.5, 1, 6.5, 1, 1, 2) [08-07-07 07:06:44.0116] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1308236 [08-07-07 07:06:44.0121] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:44.0126] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 4204826816 [08-07-07 07:06:44.0132] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:44.0140] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1308236 [08-07-07 07:06:44.0145] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:44.0150] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 0 [08-07-07 07:06:49.0136] Slim::Networking::Slimproto::check_all_clients (198) Checking if 00:04:20:12:36:52 is still alive [08-07-07 07:06:49.0141] Slim::Player::Squeezebox::stream (805) stream called: t paused: undef format: undef url: undef [08-07-07 07:06:49.0145] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox2.pm line 236) frame 4: Slim::Player::Squeezebox2::requestStatus (/Slim/Networking/Slimproto.pm line 220) frame 5: Slim::Networking::Slimproto::check_all_clients (/Slim/Utils/Timers.pm line 198) frame 6: (eval) (/Slim/Utils/Timers.pm line 198) frame 7: Slim::Utils::Timers::checkTimers (slimserver.pl line 506) frame 8: main::idle (slimserver.pl line 453) frame 9: main::main (slimserver.pl line 88) frame 10: PerlSvc::Interactive (perlsvc line 855) frame 11: PerlSvc::_interactive (slimserver.pl line 0) frame 12: (eval) (slimserver.pl line 0) [08-07-07 07:06:49.0148] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:06:49.0151] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:06:49.0154] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:06:49.0166] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:49.0170] Slim::Networking::Slimproto::_stat_handler (657) 00:04:20:12:36:52 latency=1, from (1.5, 2, 1, 1.5, 1, 6.5, 1, 1, 2, 1) [08-07-07 07:06:49.0175] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1313242 [08-07-07 07:06:49.0178] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:49.0180] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 4204831825 [08-07-07 07:06:49.0185] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:49.0189] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1313242 [08-07-07 07:06:49.0192] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:49.0195] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 0 [08-07-07 07:06:54.0056] Slim::Networking::Slimproto::check_all_clients (198) Checking if 00:04:20:12:36:52 is still alive [08-07-07 07:06:54.0060] Slim::Player::Squeezebox::stream (805) stream called: t paused: undef format: undef url: undef [08-07-07 07:06:54.0064] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox2.pm line 236) frame 4: Slim::Player::Squeezebox2::requestStatus (/Slim/Networking/Slimproto.pm line 220) frame 5: Slim::Networking::Slimproto::check_all_clients (/Slim/Utils/Timers.pm line 198) frame 6: (eval) (/Slim/Utils/Timers.pm line 198) frame 7: Slim::Utils::Timers::checkTimers (slimserver.pl line 506) frame 8: main::idle (slimserver.pl line 453) frame 9: main::main (slimserver.pl line 88) frame 10: PerlSvc::Interactive (perlsvc line 855) frame 11: PerlSvc::_interactive (slimserver.pl line 0) frame 12: (eval) (slimserver.pl line 0) [08-07-07 07:06:54.0068] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:06:54.0071] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:06:54.0073] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:06:54.0085] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:54.0089] Slim::Networking::Slimproto::_stat_handler (657) 00:04:20:12:36:52 latency=1, from (2, 1, 1.5, 1, 6.5, 1, 1, 2, 1, 1) [08-07-07 07:06:54.0094] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1318234 [08-07-07 07:06:54.0097] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:54.0099] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 4204836817 [08-07-07 07:06:54.0104] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:54.0108] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1318234 [08-07-07 07:06:54.0111] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:54.0114] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 0 [08-07-07 07:06:59.0132] Slim::Networking::Slimproto::check_all_clients (198) Checking if 00:04:20:12:36:52 is still alive [08-07-07 07:06:59.0136] Slim::Player::Squeezebox::stream (805) stream called: t paused: undef format: undef url: undef [08-07-07 07:06:59.0140] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox2.pm line 236) frame 4: Slim::Player::Squeezebox2::requestStatus (/Slim/Networking/Slimproto.pm line 220) frame 5: Slim::Networking::Slimproto::check_all_clients (/Slim/Utils/Timers.pm line 198) frame 6: (eval) (/Slim/Utils/Timers.pm line 198) frame 7: Slim::Utils::Timers::checkTimers (slimserver.pl line 506) frame 8: main::idle (slimserver.pl line 453) frame 9: main::main (slimserver.pl line 88) frame 10: PerlSvc::Interactive (perlsvc line 855) frame 11: PerlSvc::_interactive (slimserver.pl line 0) frame 12: (eval) (slimserver.pl line 0) [08-07-07 07:06:59.0143] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:06:59.0146] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:06:59.0149] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:06:59.0161] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:59.0165] Slim::Networking::Slimproto::_stat_handler (657) 00:04:20:12:36:52 latency=1, from (1, 1.5, 1, 6.5, 1, 1, 2, 1, 1, 1) [08-07-07 07:06:59.0170] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1323242 [08-07-07 07:06:59.0172] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:59.0175] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 4204841824 [08-07-07 07:06:59.0180] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:06:59.0184] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1323242 [08-07-07 07:06:59.0187] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:06:59.0190] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 0 [08-07-07 07:07:04.0053] Slim::Networking::Slimproto::check_all_clients (198) Checking if 00:04:20:12:36:52 is still alive [08-07-07 07:07:04.0060] Slim::Player::Squeezebox::stream (805) stream called: t paused: undef format: undef url: undef [08-07-07 07:07:04.0067] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox2.pm line 236) frame 4: Slim::Player::Squeezebox2::requestStatus (/Slim/Networking/Slimproto.pm line 220) frame 5: Slim::Networking::Slimproto::check_all_clients (/Slim/Utils/Timers.pm line 198) frame 6: (eval) (/Slim/Utils/Timers.pm line 198) frame 7: Slim::Utils::Timers::checkTimers (slimserver.pl line 506) frame 8: main::idle (slimserver.pl line 453) frame 9: main::main (slimserver.pl line 88) frame 10: PerlSvc::Interactive (perlsvc line 855) frame 11: PerlSvc::_interactive (slimserver.pl line 0) frame 12: (eval) (slimserver.pl line 0) [08-07-07 07:07:04.0073] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:07:04.0078] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:07:04.0083] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:07:04.0099] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:07:04.0106] Slim::Networking::Slimproto::_stat_handler (657) 00:04:20:12:36:52 latency=1, from (1.5, 1, 6.5, 1, 1, 2, 1, 1, 1, 1.5) [08-07-07 07:07:04.0113] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1328235 [08-07-07 07:07:04.0119] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:07:04.0124] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 4204846817 [08-07-07 07:07:04.0131] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:07:04.0139] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1328236 [08-07-07 07:07:04.0145] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:07:04.0150] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 0 [08-07-07 07:07:09.0130] Slim::Networking::Slimproto::check_all_clients (198) Checking if 00:04:20:12:36:52 is still alive [08-07-07 07:07:09.0137] Slim::Player::Squeezebox::stream (805) stream called: t paused: undef format: undef url: undef [08-07-07 07:07:09.0144] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox2.pm line 236) frame 4: Slim::Player::Squeezebox2::requestStatus (/Slim/Networking/Slimproto.pm line 220) frame 5: Slim::Networking::Slimproto::check_all_clients (/Slim/Utils/Timers.pm line 198) frame 6: (eval) (/Slim/Utils/Timers.pm line 198) frame 7: Slim::Utils::Timers::checkTimers (slimserver.pl line 506) frame 8: main::idle (slimserver.pl line 453) frame 9: main::main (slimserver.pl line 88) frame 10: PerlSvc::Interactive (perlsvc line 855) frame 11: PerlSvc::_interactive (slimserver.pl line 0) frame 12: (eval) (slimserver.pl line 0) [08-07-07 07:07:09.0150] Slim::Player::Squeezebox::stream (1125) flags: 0 [08-07-07 07:07:09.0156] Slim::Player::Squeezebox::stream (1151) sending strm frame of length: 24 request string: [] [08-07-07 07:07:09.0161] Slim::Player::Squeezebox::sendFrame (1215) sending squeezebox frame: strm, length: 24 [08-07-07 07:07:09.0352] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:07:09.0363] Slim::Networking::Slimproto::_stat_handler (657) 00:04:20:12:36:52 latency=1, from (1, 6.5, 1, 1, 2, 1, 1, 1, 1.5, 10.5) [08-07-07 07:07:09.0374] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: STMt bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1333243 [08-07-07 07:07:09.0382] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:07:09.0388] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 4204851825 [08-07-07 07:07:09.0394] Slim::Networking::Slimproto::client_readable (368) Slimproto frame: STAT, len: 55 [08-07-07 07:07:09.0403] Slim::Networking::Slimproto::_stat_handler (721) 00:04:20:12:36:52 Squeezebox stream status: event_code: strm bytes_rec_H 0 bytes_rec_L 0 fullness: 0 (0%) bufferSize 3145728 fullness 0 bytes_received 0 signal_strength: 65535 jiffies: 1333244 [08-07-07 07:07:09.0408] Slim::Networking::Slimproto::_stat_handler (733) output size: 3528000 output fullness: 0 elapsed seconds: 0 [08-07-07 07:07:09.0413] Slim::Networking::Slimproto::_stat_handler (745) elapsed milliseconds: 0 server timestamp: 0 [08-07-07 07:07:14.0048] Slim::Networking::Slimproto::check_all_clients (198) Checking if 00:04:20:12:36:52 is still alive [08-07-07 07:07:14.0053] Slim::Player::Squeezebox::stream (805) stream called: t paused: undef format: undef url: undef [08-07-07 07:07:14.0056] Slim::Player::Squeezebox::stream (811) Backtrace: frame 0: Slim::Player::Squeezebox::__ANON__ ((eval 1512) line 17) frame 1: Log::Log4perl::Logger::__ANON__ (/Log/Log4perl/Logger.pm line 772) frame 2: Log::Log4perl::Logger::__ANON__ (/Slim/Player/Squeezebox.pm line 811) frame 3: Slim::Player::Squeezebox::stream (/Slim/Player/Squeezebox2.pm line 236) frame 4: Slim::Player::Squeezebox2::requestStatus (/Slim/Networking/Slimproto.pm line 220) frame 5: Slim::Networking::Slimproto::check_all_clients (/Slim/Utils/Timers.pm line 198) frame 6: (eval) (/Slim/Utils/Timers.pm line 198) frame 7: Slim::Utils::Timers::checkTimers (slimserver.pl line 506) frame 8: main::idle (slimserver.pl line 453) frame 9: main::main (slimserver.pl line 88) frame 10: PerlSvc::Interactive (perlsvc line 855) frame 11: PerlSvc::_interactive (slimserver.pl line 0) frame 12: (eval) (slimserver.pl line 0)