I joined in on this bug as I thought it was the same thing but I am not so sure now:
https://github.com/ralph-irving/squeezelite/issues/155 LMS with a Slimp3, a piCorePlayer, a laptop and a desktop. The Slimp3 and the PCP work fine, the laptop and the desktop seem to be really flaky. Laptop was only brought into the equation as a comparison with desktop but it behaves similarly. Logitech Media Server Version: 8.2.1 - 1628483320 @ Mon Aug 9 06:43:27 CEST 2021 Perl Version: 5.32.1 - x86_64-linux-gnu-thread-multi desktop: squeezelite v1.9.9-1392 boomBox: squeezelite v1.9.8-1287-pCP laptop: squeezelite v1.9.8-1317 On the server, I can see that this is logged repeatedly so long as either laptop or desktop has Squeezelite running. I am not sure the significance of it but boomBox doesn't cause these messages when it's online, so I assume it's not good: Code: -------------------- [21-12-10 10:27:24.7339] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-10 10:27:24.8349] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-10 10:27:24.9359] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc -------------------- The progress bar in the web interface jumps around quite a lot. Some of the time it is in what I reckon is the correct place, a lot of the time the track progress is shown as 100% which definitely isn't true. Last night I was playing an internet stream and it just wouldn't stop the stream - this is me mashing the Pause button repeatedly to no avail: Code: -------------------- [21-12-09 22:46:10.2859] Slim::Player::StreamingController::pause (2136) 60:45:cb:70:28:bc [21-12-09 22:46:10.2862] Slim::Player::Source::playmode (95) 60:45:cb:70:28:bc: Current playmode: play [21-12-09 22:46:10.3370] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:10.4383] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:10.4886] Slim::Player::StreamingController::pause (2136) 60:45:cb:70:28:bc [21-12-09 22:46:10.4889] Slim::Player::Source::playmode (95) 60:45:cb:70:28:bc: Current playmode: play [21-12-09 22:46:10.5428] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:10.6444] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:10.6982] Slim::Player::StreamingController::pause (2136) 60:45:cb:70:28:bc [21-12-09 22:46:10.6985] Slim::Player::Source::playmode (95) 60:45:cb:70:28:bc: Current playmode: play [21-12-09 22:46:10.7457] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:10.8467] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:10.8973] Slim::Player::StreamingController::pause (2136) 60:45:cb:70:28:bc [21-12-09 22:46:10.8976] Slim::Player::Source::playmode (95) 60:45:cb:70:28:bc: Current playmode: play [21-12-09 22:46:10.9513] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:11.0526] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:11.0892] Slim::Player::StreamingController::pause (2136) 60:45:cb:70:28:bc [21-12-09 22:46:11.0895] Slim::Player::Source::playmode (95) 60:45:cb:70:28:bc: Current playmode: play [21-12-09 22:46:11.1537] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:11.2551] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:11.2733] Slim::Player::StreamingController::pause (2136) 60:45:cb:70:28:bc [21-12-09 22:46:11.2736] Slim::Player::Source::playmode (95) 60:45:cb:70:28:bc: Current playmode: play [21-12-09 22:46:11.3566] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:11.4575] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:11.4672] Slim::Player::StreamingController::pause (2136) 60:45:cb:70:28:bc [21-12-09 22:46:11.4675] Slim::Player::Source::playmode (95) 60:45:cb:70:28:bc: Current playmode: play [21-12-09 22:46:11.5583] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc [21-12-09 22:46:11.6594] Slim::Player::StreamingController::playerActive (2013) 60:45:cb:70:28:bc player already active: 60:45:cb:70:28:bc -------------------- I just killed the process in the end. If I turn up the debug level on the client: Code: -------------------- $ squeezelite -d all=debug [10:41:37.208422] stream_init:454 init stream [10:41:37.208478] stream_init:455 streambuf size: 2097152 [10:41:37.211024] output_init_alsa:936 init output [10:41:37.211068] output_init_alsa:975 requested alsa_buffer: 40 alsa_period: 4 format: any mmap: 1 [10:41:37.211084] output_init_common:360 outputbuf size: 3528000 [10:41:37.211134] output_init_common:384 idle timeout: 0 [10:41:37.222756] output_init_common:426 supported rates: 384000 352800 192000 176400 96000 88200 48000 44100 32000 24000 22500 16000 12000 11025 8000 [10:41:37.254154] output_init_alsa:1002 memory locked [10:41:37.254222] output_init_alsa:1008 glibc detected using mallopt [10:41:37.398647] decode_init:153 init decode [10:41:37.398787] register_dsd:908 using dsd to decode dsf,dff [10:41:37.398825] register_ff:797 using ffmpeg to decode alc [10:41:37.398844] register_ff:781 using ffmpeg to decode wma,wmap [10:41:37.398855] register_faad:663 using faad to decode aac [10:41:37.398866] register_vorbis:385 using vorbis to decode ogg [10:41:37.398876] register_flac:336 using flac to decode ogf,flc [10:41:37.398886] register_pcm:483 using pcm to decode aif,pcm [10:41:37.398903] register_mad:423 using mad to decode mp3 [10:41:37.398913] decode_init:194 include codecs: exclude codecs: [10:41:37.399136] discover_server:795 sending discovery [10:41:37.399504] discover_server:806 got response from: 192.168.1.3:3483 [10:41:37.399628] slimproto:898 connecting to 192.168.1.3:3483 [10:41:37.399830] slimproto:937 connected [10:41:37.399872] sendHELO:148 mac: 60:45:cb:70:28:bc [10:41:37.399920] sendHELO:150 cap: CanHTTPS=1,Model=squeezelite,AccuratePlayPoints=1,HasDigitalOut=1,HasPolarityInversion=1,Balance=1,Firmware=v1.9.9-1392,ModelName=SqueezeLite,MaxSampleRate=384000,dsf,dff,alc,wma,wmap,aac,ogg,ogf,flc,aif,pcm,mp3 [10:41:37.410825] process:528 strm [10:41:37.410885] process_strm:280 strm command s [10:41:37.410895] process_strm:349 strm s autostart: 1 transition period: 10 transition type: 0 codec: f [10:41:37.410904] sendSTAT:195 STAT: STMf [10:41:37.410926] codec_open:264 codec open: 'f' [10:41:37.410994] connect_socket:164 connecting to 192.168.1.3:9000 [10:41:37.411290] stream_sock:600 header: GET /stream.mp3?player=60:45:cb:70:28:bc HTTP/1.0 [10:41:37.411336] sendSTAT:195 STAT: STMc [10:41:37.411362] process_strm:384 set fade mode: 0, channels: 0, invert: 0 [10:41:37.411395] process:528 audg [10:41:37.411416] process_audg:440 audg gainL: 65536 gainR: 65536 adjust: 1 [10:41:37.411436] set_volume:233 setting internal gain left: 65536 right: 65536 [10:41:37.411489] process:528 setd [10:41:37.411506] process:528 setd [10:41:37.411521] process:528 aude [10:41:37.411533] process_aude:422 enable spdif: 1 dac: 1 [10:41:37.411553] process:528 audg [10:41:37.411566] process_audg:440 audg gainL: 65536 gainR: 65536 adjust: 1 [10:41:37.411589] set_volume:233 setting internal gain left: 65536 right: 65536 [10:41:37.415085] stream_thread:331 headers: len: 493 HTTP/1.1 200 OK Server: Logitech Media Server (8.2.1 - 1628483320) Connection: close Content-Type: audio/x-flac Set-Cookie: Squeezebox-albumView=; path=/ Set-Cookie: Squeezebox-expandPlayerControl=true; path=/ Set-Cookie: Squeezebox-expanded-MY_MUSIC=1; path=/ Set-Cookie: Squeezebox-expanded-FAVORITES=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGINS=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGIN_MY_APPS_MODULE_NAME=1; path=/ Set-Cookie: Squeezebox-expanded-RADIO=1; path=/ [10:41:37.415393] sendRESP:226 RESP [10:41:37.500297] write_cb:145 setting track_start [10:41:37.501526] slimproto_run:579 error reading from socket: closed [10:41:37.508477] _output_frames:65 start buffer frames: 131072 [10:41:37.508561] _output_frames:153 track start sample rate: 44100 replay_gain: 0 [10:41:37.602156] slimproto:937 connected [10:41:37.602257] sendHELO:148 mac: 60:45:cb:70:28:bc [10:41:37.602273] sendHELO:150 cap: CanHTTPS=1,Model=squeezelite,AccuratePlayPoints=1,HasDigitalOut=1,HasPolarityInversion=1,Balance=1,Firmware=v1.9.9-1392,ModelName=SqueezeLite,MaxSampleRate=384000,dsf,dff,alc,wma,wmap,aac,ogg,ogf,flc,aif,pcm,mp3 [10:41:37.602334] sendSTAT:195 STAT: STMs [10:41:37.602350] sendSTAT:195 STAT: STMt [10:41:37.603344] process:528 setd [10:41:37.603421] process:528 setd [10:41:37.603524] process:528 aude [10:41:37.603543] process_aude:422 enable spdif: 1 dac: 1 [10:41:37.603568] process:528 audg [10:41:37.603583] process_audg:440 audg gainL: 65536 gainR: 65536 adjust: 1 [10:41:37.603599] set_volume:233 setting internal gain left: 65536 right: 65536 [10:41:37.704353] slimproto_run:579 error reading from socket: closed -------------------- the last 14 lines repeat forever. ------------------------------------------------------------------------ troffasky's Profile: http://forums.slimdevices.com/member.php?userid=70248 View this thread: http://forums.slimdevices.com/showthread.php?t=115547 _______________________________________________ plugins mailing list [email protected] http://lists.slimdevices.com/mailman/listinfo/plugins
