[08-08-10 07:06:19.6618] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01194.685: Trace: radeadecoder.c[407]: waiting for more RAD bytes [08-08-10 07:06:19.6643] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01194.688: Trace: radeadecoder.c[407]: waiting for more RAD bytes [08-08-10 07:06:19.6794] Slim::Player::Source::decoderUnderrun (607) 00:04:20:12:00:02: Decoder underrun while this mode: playout-play [08-08-10 07:06:19.6799] Slim::Player::Source::nextsong (1700) The next song is number 6, was 5 [08-08-10 07:06:19.6804] Slim::Player::Source::nextsong (1700) The next song is number 6, was 5 [08-08-10 07:06:19.6822] Slim::Plugin::RhapsodyDirect::ProtocolHandler::onDecoderUnderrun (377) End of track, logging usage info (172 seconds)... [08-08-10 07:06:19.6872] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:02 RPDS packet sent: "\4\x03172" [08-08-10 07:06:19.6877] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01194.698: Info: slim_tcpdata.c[264]: Sending decoder underrun event [08-08-10 07:06:19.6880] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01194.700: Info: mp3.c[230]: Reached end of stream [08-08-10 07:06:19.6884] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01194.700: Info: slim_tcpdata.c[164]: event: d [08-08-10 07:06:19.6890] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01194.703: Trace: decode_mp3.c[269]: last frame padding=943 [08-08-10 07:06:19.6893] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01194.706: Trace: decode_output.c[873]: removing 7544 bytes from output fifo [08-08-10 07:06:19.6896] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01194.709: Info: decode.c[762]: Decode thread: handling message [08-08-10 07:06:19.7214] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01194.711: Info: decode.c[556]: Seems like a song finished streaming [08-08-10 07:06:19.7218] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01194.713: Trace: radeadecoder.c[510]: code 4 [08-08-10 07:06:20.2557] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01195.271: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:20.7923] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:02 Got RPDS packet: \"\4" [08-08-10 07:06:20.7959] Slim::Plugin::RhapsodyDirect::RPDS::rpds (86) 00:04:20:12:00:02 RPDS packet sent: "\3\13Tra.3185422" [08-08-10 07:06:20.7974] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01195.803: Trace: radeadecoder.c[1035]: recv_logMeteringInfo_response [08-08-10 07:06:20.8310] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01195.818: Trace: radeadecoder.c[510]: code 3 [08-08-10 07:06:21.2544] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01196.271: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:21.6658] Slim::Plugin::RhapsodyDirect::RPDS::rpds_handler (145) 00:04:20:12:00:02 Got RPDS packet: \"\3\x001http://llnw-668.listen.com/6/8/6/8/2/65128686.rad" [08-08-10 07:06:21.6847] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01196.666: Trace: radeadecoder.c[945]: recv_getTrackPlaybackInfo_response [08-08-10 07:06:21.9695] Slim::Player::Source::skipahead (956) **skipahead: opening next song [08-08-10 07:06:21.9704] Slim::Player::Source::gotoNext (1350) Opening next song... [08-08-10 07:06:21.9715] Slim::Player::Source::nextsong (1700) The next song is number 6, was 5 [08-08-10 07:06:21.9747] Slim::Player::TranscodingHelper::getConvertCommand (215) undermax = 1, type = mp3, squeezebox2 = 00:04:20:12:00:02 [08-08-10 07:06:21.9756] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-00:04:20:12:00:02 [08-08-10 07:06:21.9764] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-00:04:20:12:00:02 [08-08-10 07:06:21.9772] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-squeezebox2-* [08-08-10 07:06:21.9780] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wma-*-* [08-08-10 07:06:21.9789] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-00:04:20:12:00:02 [08-08-10 07:06:21.9797] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-00:04:20:12:00:02 [08-08-10 07:06:21.9805] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-squeezebox2-* [08-08-10 07:06:21.9813] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-ogg-*-* [08-08-10 07:06:21.9821] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-00:04:20:12:00:02 [08-08-10 07:06:21.9829] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-00:04:20:12:00:02 [08-08-10 07:06:21.9837] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-squeezebox2-* [08-08-10 07:06:21.9845] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-flc-*-* [08-08-10 07:06:21.9853] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-00:04:20:12:00:02 [08-08-10 07:06:21.9861] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-00:04:20:12:00:02 [08-08-10 07:06:21.9869] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-squeezebox2-* [08-08-10 07:06:21.9876] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-aif-*-* [08-08-10 07:06:21.9888] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-00:04:20:12:00:02 [08-08-10 07:06:21.9896] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-00:04:20:12:00:02 [08-08-10 07:06:21.9904] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-squeezebox2-* [08-08-10 07:06:21.9912] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-wav-*-* [08-08-10 07:06:21.9920] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-00:04:20:12:00:02 [08-08-10 07:06:21.9927] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-00:04:20:12:00:02 [08-08-10 07:06:21.9935] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-squeezebox2-* [08-08-10 07:06:21.9943] Slim::Player::TranscodingHelper::checkBin (152) Checking formats for: mp3-mp3-*-* [08-08-10 07:06:21.9951] Slim::Player::TranscodingHelper::enabledFormat (119) Checking to see if mp3-mp3-*-* is enabled [08-08-10 07:06:21.9959] Slim::Player::TranscodingHelper::checkBin (160) enabled [08-08-10 07:06:21.9967] Slim::Player::TranscodingHelper::checkBin (162) Found command: - [08-08-10 07:06:22.2352] Slim::Player::TranscodingHelper::getConvertCommand (316) Matched Format: mp3 Type: mp3 Command: - [08-08-10 07:06:22.2356] Slim::Player::Source::gotoNext (1437) opening next song (old format: mp3, new: mp3) current playmode: playout-play [08-08-10 07:06:22.2359] Slim::Player::Source::streamingSongIndex (1494) Adding song index 6 to song queue [08-08-10 07:06:22.2362] Slim::Player::Source::streamingSongIndex (1515) Song queue is now 6,5 [08-08-10 07:06:22.2365] Slim::Player::Source::skipahead (962) **skipahead: restarting [08-08-10 07:06:22.2368] Slim::Player::Source::playmode (309) 00:04:20:12:00:02: Switching to mode play from playout-play [08-08-10 07:06:22.2373] Slim::Player::Source::resetSong (1739) Resetting song buffer. [08-08-10 07:06:22.2432] Slim::Player::Source::openSong (1840) Trying to open: rhapd://Tra.3185422.mp3 [08-08-10 07:06:22.2438] Slim::Player::Source::openSong (1876) URL supports direct streaming [rhapd://Tra.3185422.mp3] [08-08-10 07:06:22.2501] Slim::Player::Source::playmode (412) 00:04:20:12:00:02 New play mode: play [08-08-10 07:06:22.2520] Slim::Player::Squeezebox::stream (1017) This player supports direct streaming for rhapd://Tra.3185422.mp3 as http://llnw-668.listen.com/6/8/6/8/2/65128686.rad, let's do it. [08-08-10 07:06:22.2597] Slim::Player::Squeezebox::stream (1055) setting up direct stream (3496968560:80) autostart: 3. [08-08-10 07:06:22.2601] Slim::Player::Squeezebox::stream (1056) request string: GET /6/8/6/8/2/65128686.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw-668.listen.com User-Agent: iTunes/4.7.1 (Windows; N; Windows XP; 586; EN; cp1255) SqueezeCenter/7.2/22491 Icy-Metadata: 1 [08-08-10 07:06:22.2723] Slim::Player::Source::playmode (581) 00:04:20:12:00:02: Current playmode: play [08-08-10 07:06:22.2767] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.271: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:22.2773] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.278: Trace: decode.c[688]: gain[0] 10000 gain[1] 10000 [08-08-10 07:06:22.3103] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.303: Info: slim_tcpdata.c[746]: STRM control frame came in, command: s [08-08-10 07:06:22.3141] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.306: Info: slim_tcpdata.c[782]: len request_string: 00eb [08-08-10 07:06:22.3147] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.308: Info: slim_tcpdata.c[789]: req: GET /6/8/6/8/2/65128686.rad HTTP/1.0 Cache-Control: no-cache Connection: close Accept: */* Host: llnw-668.listen.com User-Agent: iTunes/4.7.1 (Windows; [08-08-10 07:06:22.3163] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.315: Trace: slim_tcpdata.c[791]: FLAGS 0 [08-08-10 07:06:22.3166] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.317: Info: decode.c[509]: decode_start [08-08-10 07:06:22.3170] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.318: Info: slim_tcpdata.c[164]: event: f [08-08-10 07:06:22.3175] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.318: Info: decode.c[762]: Decode thread: handling message [08-08-10 07:06:22.3178] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.322: Info: decode.c[212]: decode_start_handler [08-08-10 07:06:22.3183] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.322: Info: slim_tcpdata.c[689]: data connect: 208.111.129.112:80 [08-08-10 07:06:22.3186] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.325: Info: decode.c[295]: initializing MP3 [08-08-10 07:06:22.3190] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.327: Info: slim_tcpdata.c[164]: event: c [08-08-10 07:06:22.3193] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.332: Trace: decode.c[688]: gain[0] 10000 gain[1] 10000 [08-08-10 07:06:22.3432] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.369: Info: mp3.c[275]: mp3_decoder_init() [08-08-10 07:06:22.3454] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.372: Info: mp3.c[74]: read_tables [08-08-10 07:06:22.3499] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.374: Info: mp3.c[34]: loading table /D.dat.bin at address 60393f00 [08-08-10 07:06:22.3517] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.377: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3530] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.379: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3547] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.381: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3567] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.382: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3582] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.384: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3597] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.386: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3615] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.387: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3631] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.389: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3650] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.391: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3666] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.392: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3681] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.394: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3696] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.396: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3713] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.397: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3729] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.399: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3746] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.400: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3761] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.402: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3768] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.404: Info: mp3.c[58]: extmem [08-08-10 07:06:22.3800] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.405: Info: mp3.c[34]: loading table /hufftab0.dat.bin at address 4002c94c [08-08-10 07:06:22.3820] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.409: Info: mp3.c[54]: pram [08-08-10 07:06:22.3858] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.411: Info: mp3.c[34]: loading table /hufftab1.dat.bin at address 4002c950 [08-08-10 07:06:22.3881] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.415: Info: mp3.c[54]: pram [08-08-10 07:06:22.3913] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.417: Info: mp3.c[34]: loading table /hufftab2.dat.bin at address 4002c960 [08-08-10 07:06:22.3932] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.421: Info: mp3.c[54]: pram [08-08-10 07:06:22.3967] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.422: Info: mp3.c[34]: loading table /hufftab3.dat.bin at address 4002c980 [08-08-10 07:06:22.3988] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.426: Info: mp3.c[54]: pram [08-08-10 07:06:22.4023] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.428: Info: mp3.c[34]: loading table /hufftab5.dat.bin at address 4002c9a0 [08-08-10 07:06:22.4044] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.432: Info: mp3.c[54]: pram [08-08-10 07:06:22.4102] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.433: Info: mp3.c[34]: loading table /hufftab6.dat.bin at address 4002c9d4 [08-08-10 07:06:22.4108] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.438: Info: mp3.c[54]: pram [08-08-10 07:06:22.4143] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.439: Info: mp3.c[34]: loading table /hufftab7.dat.bin at address 4002ca0c [08-08-10 07:06:22.4163] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.443: Info: mp3.c[54]: pram [08-08-10 07:06:22.4202] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.445: Info: mp3.c[34]: loading table /hufftab8.dat.bin at address 4002cb0c [08-08-10 07:06:22.4230] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.449: Info: mp3.c[54]: pram [08-08-10 07:06:22.4237] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.451: Info: mp3.c[54]: pram [08-08-10 07:06:22.4273] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.452: Info: mp3.c[34]: loading table /hufftab9.dat.bin at address 4002cc14 [08-08-10 07:06:22.4290] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.456: Info: mp3.c[54]: pram [08-08-10 07:06:22.4325] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.458: Info: mp3.c[34]: loading table /hufftab10.dat.bin at address 4002cc80 [08-08-10 07:06:22.4346] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.462: Info: mp3.c[54]: pram [08-08-10 07:06:22.4364] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.464: Info: mp3.c[54]: pram [08-08-10 07:06:22.4397] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.465: Info: mp3.c[34]: loading table /hufftab11.dat.bin at address 4002cd4c [08-08-10 07:06:22.4418] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.469: Info: mp3.c[54]: pram [08-08-10 07:06:22.4434] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.471: Info: mp3.c[54]: pram [08-08-10 07:06:22.4518] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.472: Info: mp3.c[34]: loading table /hufftab12.dat.bin at address 4002ce1c [08-08-10 07:06:22.4522] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.476: Info: mp3.c[54]: pram [08-08-10 07:06:22.4525] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.478: Info: mp3.c[54]: pram [08-08-10 07:06:22.4545] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.480: Info: mp3.c[34]: loading table /hufftab13.dat.bin at address 4002cedc [08-08-10 07:06:22.4566] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.484: Info: mp3.c[54]: pram [08-08-10 07:06:22.4704] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.485: Info: mp3.c[54]: pram [08-08-10 07:06:22.4708] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.487: Info: mp3.c[54]: pram [08-08-10 07:06:22.4711] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.487: Info: slim_tcpdata.c[172]: est, 00000000 [08-08-10 07:06:22.4725] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.489: Info: mp3.c[54]: pram [08-08-10 07:06:22.4734] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.491: Info: mp3.c[54]: pram [08-08-10 07:06:22.4767] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.491: Info: slim_tcpdata.c[164]: event: e [08-08-10 07:06:22.4795] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.494: Info: mp3.c[54]: pram [08-08-10 07:06:22.4799] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.496: Info: mp3.c[54]: pram [08-08-10 07:06:22.4812] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.498: Info: mp3.c[34]: loading table /hufftab15.dat.bin at address 4002d1f8 [08-08-10 07:06:22.4826] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.502: Info: mp3.c[54]: pram [08-08-10 07:06:22.4830] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.504: Info: mp3.c[54]: pram [08-08-10 07:06:22.4833] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.506: Info: mp3.c[54]: pram [08-08-10 07:06:22.4839] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.507: Info: mp3.c[54]: pram [08-08-10 07:06:22.4842] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.509: Info: mp3.c[54]: pram [08-08-10 07:06:22.4845] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.510: Info: mp3.c[54]: pram [08-08-10 07:06:22.4867] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.512: Info: mp3.c[34]: loading table /hufftab16.dat.bin at address 4002d4f0 [08-08-10 07:06:22.4887] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.516: Info: mp3.c[54]: pram [08-08-10 07:06:22.4904] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.518: Info: mp3.c[54]: pram [08-08-10 07:06:22.4919] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.519: Info: mp3.c[54]: pram [08-08-10 07:06:22.4936] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.521: Info: mp3.c[54]: pram [08-08-10 07:06:22.4962] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.522: Info: mp3.c[54]: pram [08-08-10 07:06:22.4969] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.524: Info: mp3.c[54]: pram [08-08-10 07:06:22.4985] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.526: Info: mp3.c[54]: pram [08-08-10 07:06:22.5017] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.527: Info: mp3.c[34]: loading table /hufftab24.dat.bin at address 4002d814 [08-08-10 07:06:22.5039] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.531: Info: mp3.c[54]: pram [08-08-10 07:06:22.5058] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.533: Info: mp3.c[54]: pram [08-08-10 07:06:22.5084] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.534: Info: mp3.c[54]: pram [08-08-10 07:06:22.5100] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.536: Info: mp3.c[54]: pram [08-08-10 07:06:22.5117] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.538: Info: mp3.c[54]: pram [08-08-10 07:06:22.5132] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.539: Info: mp3.c[54]: pram [08-08-10 07:06:22.5139] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.541: Info: mp3.c[54]: pram [08-08-10 07:06:22.5498] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.543: Trace: decode_mp3.c[335]: DRM: RAD/EA initialized [08-08-10 07:06:22.5502] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.561: Trace: decode_output.c[943]: TRACK GAIN 65536 65536 [08-08-10 07:06:22.5506] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.564: Trace: decode_output.c[949]: track polarity inversion 0 [08-08-10 07:06:22.6489] Slim::Player::Squeezebox2::directHeaders (290) Processing headers for direct streaming: HTTP/1.0 200 OK Server: Apache/2.0.58 Accept-Ranges: bytes Content-Length: 3734380 Content-Type: text/plain Age: 70257 Date: Sun, 10 Aug 2008 16:06:25 GMT Last-Modified: Tue, 27 Mar 2007 09:42:42 GMT Connection: close [08-08-10 07:06:22.6494] Slim::Player::Squeezebox2::directHeaders (349) Processing 8 headers [08-08-10 07:06:22.6498] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Server: Apache/2.0.58 [08-08-10 07:06:22.6500] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Accept-Ranges: bytes [08-08-10 07:06:22.6503] Slim::Plugin::RhapsodyDirect::ProtocolHandler::parseDirectHeaders (61) RhapsodyDirect header: Content-Length: 3734380 [08-08-10 07:06:22.7931] Slim::Player::Squeezebox2::directHeaders (407) Got a stream type: mp3 bitrate: 192000 title: [08-08-10 07:06:22.7936] Slim::Player::Squeezebox2::directHeaders (459) Beginning direct stream! [08-08-10 07:06:22.8250] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.672: Info: slim_tcpdata.c[356]: Sending HTTP response to server [08-08-10 07:06:22.8258] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.674: Info: slim_tcpdata.c[164]: event: h [08-08-10 07:06:22.8261] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.677: Info: slim_tcpdata.c[398]: Allocating new savedBody with 1223 bytes [08-08-10 07:06:22.8264] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.680: Info: slim_tcpdata.c[391]: Appending 1 bytes to existing savedBody of size 1223 [08-08-10 07:06:22.8267] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.825: Info: slim_tcpdata.c[438]: Recieved continue message with interval 0, looping 0 and guids 0 [08-08-10 07:06:22.8271] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01197.829: Info: slim_tcpdata.c[445]: queuing up saved body. [08-08-10 07:06:23.2454] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01198.271: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:24.0175] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01199.029: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:06:24.0185] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01199.032: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:24.0212] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60931.284: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:06:24.0221] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60931.287: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:24.2593] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01199.162: Trace: radeadecoder.c[996]: recv_getEA_response [08-08-10 07:06:24.2603] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01199.213: Trace: radeadecoder.c[723]: base64 len is 2732 [08-08-10 07:06:24.2612] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01199.276: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:25.2584] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01200.276: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:26.2591] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01201.276: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:27.2587] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.276: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:27.6971] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.710: Info: decode.c[410]: decode_resume start_jiffies_ms=0 [08-08-10 07:06:27.6982] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.712: Info: decode.c[397]: resume_callback [08-08-10 07:06:27.7008] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.714: Info: decode.c[762]: Decode thread: handling message [08-08-10 07:06:27.7047] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.717: Info: decode.c[119]: decode_resume [08-08-10 07:06:27.7068] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.719: Info: decode.c[134]: decoder state: 00000001 audio state: 00000001 [08-08-10 07:06:27.7090] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.722: Trace: radeadecoder.c[200]: RAD: [08-08-10 07:06:27.7004] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.723: Trace: radeadecoder.c[201]: header_size = 24 [08-08-10 07:06:27.7060] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.726: Trace: radeadecoder.c[202]: major_version = 3 [08-08-10 07:06:27.7083] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.728: Trace: radeadecoder.c[203]: sMinorVersion 0 [08-08-10 07:06:27.7093] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.730: Trace: radeadecoder.c[204]: nMediaID 7388106 [08-08-10 07:06:27.7119] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.733: Trace: radeadecoder.c[205]: nMediaCodec 1024 [08-08-10 07:06:27.7157] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.735: Trace: radeadecoder.c[206]: nMediaFormat 192 [08-08-10 07:06:27.7180] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.738: Trace: radeadecoder.c[207]: nToComplete 3 [08-08-10 07:06:27.7200] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.740: Trace: radeadecoder.c[208]: nRadFileSize 3734380 [08-08-10 07:06:27.7228] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.743: Trace: radeadecoder.c[233]: Reached end of EA [08-08-10 07:06:27.7254] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.745: Trace: radeadecoder.c[279]: EA: [08-08-10 07:06:27.7277] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.747: Trace: radeadecoder.c[280]: nSize 24 [08-08-10 07:06:27.7298] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.749: Trace: radeadecoder.c[281]: sMajorVersion 3 [08-08-10 07:06:27.7312] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.751: Trace: radeadecoder.c[282]: sMinorVersion 0 [08-08-10 07:06:27.7351] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.754: Trace: radeadecoder.c[283]: nMediaID 7388106 [08-08-10 07:06:27.7362] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.756: Trace: radeadecoder.c[284]: nMediaCodec 1024 [08-08-10 07:06:27.7383] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.759: Trace: radeadecoder.c[285]: nMediaFormat 192 [08-08-10 07:06:27.7394] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.761: Trace: radeadecoder.c[286]: nValidationData 5a2c9085 [08-08-10 07:06:27.7414] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.764: Trace: radeadecoder.c[287]: nFileSize 29220 [08-08-10 07:06:27.7423] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.767: Trace: radeadecoder.c[304]: ea_block remaining 2048 2048 of 29220 [08-08-10 07:06:27.7478] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.775: Trace: radeadecoder.c[445]: validation_data 5a2c9085 [08-08-10 07:06:27.7725] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.798: Trace: decode_mp3.c[57]: xing magic 496e666f [08-08-10 07:06:27.7760] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.801: Trace: decode_mp3.c[67]: skipping xing frames [08-08-10 07:06:27.7788] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.803: Trace: decode_mp3.c[75]: skipping xing bytes [08-08-10 07:06:27.7798] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.805: Trace: decode_mp3.c[83]: skipping xing toc [08-08-10 07:06:27.7834] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.807: Trace: decode_mp3.c[91]: skipping xing scale [08-08-10 07:06:27.7861] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.810: Trace: decode_mp3.c[108]: lame magic 4c414d45 [08-08-10 07:06:27.7871] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.813: Trace: decode_mp3.c[122]: encoder delay 576 [08-08-10 07:06:27.7884] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.815: Trace: decode_mp3.c[123]: encoder padding 1728 [08-08-10 07:06:27.7993] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.826: Trace: decode_mp3.c[196]: encoder_delay=1105 pcm->samples=1152 offset=1105 [08-08-10 07:06:27.8026] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.830: Info: decode_output.c[646]: First buffer of track, start point is 00262980 [08-08-10 07:06:27.9121] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.855: Trace: radeadecoder.c[304]: ea_block remaining 1988 2048 of 29220 [08-08-10 07:06:27.9131] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.932: Trace: radeadecoder.c[304]: ea_block remaining 1964 2048 of 29220 [08-08-10 07:06:27.9565] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.982: Info: mp3.c[190]: Error in decoding frame: 0x238 [08-08-10 07:06:27.9587] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.985: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:27.9624] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.987: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:27.9633] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.990: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.0012] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01202.999: Trace: radeadecoder.c[304]: ea_block remaining 1940 2048 of 29220 [08-08-10 07:06:28.0022] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.014: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.0031] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.017: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.0041] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.021: Info: mp3.c[190]: Error in decoding frame: 0x102 [08-08-10 07:06:28.0050] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.023: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.0136] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.040: Trace: radeadecoder.c[304]: ea_block remaining 1916 2048 of 29220 [08-08-10 07:06:28.0262] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.054: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.0290] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.056: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.0494] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.076: Trace: radeadecoder.c[304]: ea_block remaining 1892 2048 of 29220 [08-08-10 07:06:28.0617] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.090: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.0645] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.092: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.0697] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.098: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.0729] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.100: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.1377] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.117: Trace: radeadecoder.c[304]: ea_block remaining 1868 2048 of 29220 [08-08-10 07:06:28.1389] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.129: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.1398] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.132: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.1407] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.153: Trace: radeadecoder.c[304]: ea_block remaining 1844 2048 of 29220 [08-08-10 07:06:28.1439] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.164: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.1448] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.167: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.1592] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.178: Info: mp3.c[190]: Error in decoding frame: 0x104 [08-08-10 07:06:28.1621] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.180: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.1691] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.194: Trace: radeadecoder.c[304]: ea_block remaining 1820 2048 of 29220 [08-08-10 07:06:28.1782] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.204: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.1797] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.207: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.2111] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.229: Trace: radeadecoder.c[304]: ea_block remaining 1796 2048 of 29220 [08-08-10 07:06:28.2220] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.239: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.2235] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.242: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.2282] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.248: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.2311] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.250: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.2524] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.271: Trace: radeadecoder.c[304]: ea_block remaining 1772 2048 of 29220 [08-08-10 07:06:28.2613] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.276: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:28.2642] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.281: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.2656] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.284: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.2727] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.292: Info: mp3.c[190]: Error in decoding frame: 0x104 [08-08-10 07:06:28.2755] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.295: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.2929] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.313: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.2957] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.315: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.3099] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.329: Trace: radeadecoder.c[304]: ea_block remaining 1748 2048 of 29220 [08-08-10 07:06:28.3277] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.347: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.3305] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.350: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.3962] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.364: Trace: radeadecoder.c[304]: ea_block remaining 1724 2048 of 29220 [08-08-10 07:06:28.3972] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.387: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.3981] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.390: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.4192] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.400: Info: mp3.c[190]: Error in decoding frame: 0x102 [08-08-10 07:06:28.4206] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.404: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.6452] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.417: Trace: radeadecoder.c[304]: ea_block remaining 1700 2048 of 29220 [08-08-10 07:06:28.6462] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.441: Info: mp3.c[190]: Error in decoding frame: 0x101 [08-08-10 07:06:28.6486] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.444: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.6497] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.453: Info: mp3.c[190]: Error in decoding frame: 0x104 [08-08-10 07:06:28.6512] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.456: Info: mp3.c[195]: Recoverable error, let's try again [08-08-10 07:06:28.6521] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 01203.468: Trace: radeadecoder.c[304]: ea_block remaining 1676 2048 of 29220 [08-08-10 07:06:29.0142] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60936.282: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:06:29.0151] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60936.285: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:34.0218] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60941.289: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:06:34.0228] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60941.292: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:39.0169] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60946.286: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:06:39.0173] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60946.288: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:44.0172] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60951.285: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:06:44.0182] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60951.288: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:44.6513] Slim::Player::Squeezebox::needsUpgrade (428) Reading firmware version file: C:\PROGRA~1\SQUEEZ~2\server\Firmware\squeezebox2.version [08-08-10 07:06:44.6583] Slim::Player::Squeezebox::needsUpgrade (485) squeezebox2 firmware is up-to-date, v. 110 [08-08-10 07:06:49.0168] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60956.282: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:06:49.0173] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60956.285: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:54.0209] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60961.290: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:06:54.0221] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60961.293: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:06:59.0183] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60966.283: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:06:59.0193] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60966.286: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:04.0254] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60971.290: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:04.0264] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60971.293: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:09.0220] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60976.287: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:09.0230] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60976.290: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:14.0181] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60981.285: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:14.0191] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60981.288: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:19.0163] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60986.281: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:19.0173] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60986.284: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:24.0213] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60991.283: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:24.0222] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60991.286: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:29.0306] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60996.287: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:29.0316] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 60996.290: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:34.0189] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61001.284: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:34.0199] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61001.286: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:39.0182] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61006.282: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:39.0192] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61006.285: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:44.0264] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61011.292: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:44.0271] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61011.295: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:44.2745] Slim::Web::JSONRPC::requestMethod (383) request not dispatchable! [08-08-10 07:07:44.2860] Slim::Web::JSONRPC::requestMethod (383) request not dispatchable! [08-08-10 07:07:44.2974] Slim::Web::JSONRPC::requestMethod (383) request not dispatchable! [08-08-10 07:07:44.3089] Slim::Web::JSONRPC::requestMethod (383) request not dispatchable! [08-08-10 07:07:44.3200] Slim::Web::JSONRPC::requestMethod (383) request not dispatchable! [08-08-10 07:07:44.3483] Slim::Web::JSONRPC::requestMethod (383) request not dispatchable! [08-08-10 07:07:49.0313] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61016.295: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:49.0512] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61016.298: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:49.3845] Slim::Web::JSONRPC::requestMethod (383) request not dispatchable! [08-08-10 07:07:54.0150] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61021.283: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:54.0155] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61021.286: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:54.4551] Slim::Web::JSONRPC::requestMethod (383) request not dispatchable! [08-08-10 07:07:59.0146] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61026.280: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:07:59.0156] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61026.283: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:07:59.5285] Slim::Web::JSONRPC::requestMethod (383) request not dispatchable! [08-08-10 07:08:04.0222] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61031.288: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:04.0232] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61031.291: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:08:04.5922] Slim::Web::JSONRPC::requestMethod (383) request not dispatchable! [08-08-10 07:08:09.0201] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61036.285: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:09.0211] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61036.288: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:08:09.8164] Slim::Web::JSONRPC::requestMethod (383) request not dispatchable! [08-08-10 07:08:14.0156] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61041.282: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:14.0166] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61041.285: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:08:19.0133] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61046.280: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:19.0143] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61046.283: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:08:24.0199] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61051.287: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:24.0209] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61051.290: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:08:29.0173] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61056.284: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:29.0183] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61056.287: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:08:34.0145] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61061.282: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:34.0155] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61061.285: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:08:39.0212] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61066.289: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:39.0222] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61066.292: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:08:44.0187] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61071.286: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:44.0197] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61071.289: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:08:49.0158] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61076.284: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:49.0168] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61076.287: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:08:54.0130] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61081.281: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:54.0140] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61081.284: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:08:59.0202] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61086.288: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:08:59.0212] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61086.291: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:04.0173] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61091.286: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:04.0183] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61091.289: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:09.0146] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61096.283: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:09.0156] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61096.286: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:14.0218] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61101.290: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:14.0228] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61101.293: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:19.0189] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61106.288: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:19.0199] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61106.291: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:24.0162] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61111.285: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:24.0181] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61111.288: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:29.0166] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61116.282: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:29.0176] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61116.285: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:34.0200] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61121.289: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:34.0207] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61121.292: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:39.0202] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61126.289: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:39.0212] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61126.292: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:44.0157] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61131.286: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:44.0165] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61131.289: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:49.0220] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61136.291: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:49.0233] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61136.294: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:54.0205] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61141.291: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:54.0213] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61141.293: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:09:59.0194] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61146.289: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:09:59.0204] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61146.292: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:10:04.0142] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61151.283: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:10:04.0152] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61151.286: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:10:09.0186] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61156.290: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:10:09.0190] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61156.293: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:10:14.0153] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61161.288: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:10:14.0163] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61161.291: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:10:19.0178] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61166.284: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:10:19.0182] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61166.287: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:10:20.9581] Slim::Player::Squeezebox::reconnect (116) 00:04:20:12:00:02 forcing stop on pseudo-reconnect [08-08-10 07:10:20.9642] Slim::Player::Squeezebox::needsUpgrade (428) Reading firmware version file: C:\PROGRA~1\SQUEEZ~2\server\Firmware\squeezebox2.version [08-08-10 07:10:20.9669] Slim::Player::Squeezebox::needsUpgrade (485) squeezebox2 firmware is up-to-date, v. 110 [08-08-10 07:10:20.9915] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00014.948: Info: slim_tcpdata.c[746]: STRM control frame came in, command: q [08-08-10 07:10:20.9919] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00014.950: Info: slim_tcpdata.c[164]: event: f [08-08-10 07:10:20.9922] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00014.950: Info: decode.c[762]: Decode thread: handling message [08-08-10 07:10:20.9925] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00014.953: Info: decode.c[162]: decode_stop [08-08-10 07:10:20.9929] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00014.955: Info: decode_output.c[185]: decode_output end [08-08-10 07:10:20.9944] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00014.957: Info: decode.c[762]: Decode thread: handling message [08-08-10 07:10:20.9947] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00014.960: Info: decode.c[579]: decode_set_visualizer [08-08-10 07:10:20.9955] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00014.962: Info: decode.c[762]: Decode thread: handling message [08-08-10 07:10:20.9958] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00014.964: Trace: decode.c[688]: gain[0] 10000 gain[1] 10000 [08-08-10 07:10:20.9961] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00014.965: Info: decode.c[579]: decode_set_visualizer [08-08-10 07:10:24.0893] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00018.030: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t [08-08-10 07:10:24.0896] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:12:00:02] 00018.032: Info: slim_tcpdata.c[164]: event: t [08-08-10 07:10:24.0905] Slim::Networking::Slimproto::_debug_handler (563) [00:04:20:07:00:01] 61171.324: Info: slim_tcpdata.c[746]: STRM control frame came in, command: t