Mar 29 18:41:12 volumio go-librespot[26597]: time="2026-03-29T18:41:12+01:00" level=trace msg="sent dealer ping" Mar 29 18:41:12 volumio go-librespot[26597]: time="2026-03-29T18:41:12+01:00" level=trace msg="received dealer pong" Mar 29 18:41:25 volumio volumio[26389]: info: [jellyfin-poller] Polled http://192.168.1.103:8096: offline Mar 29 18:41:42 volumio go-librespot[26597]: time="2026-03-29T18:41:42+01:00" level=trace msg="sent dealer ping" Mar 29 18:41:42 volumio go-librespot[26597]: time="2026-03-29T18:41:42+01:00" level=trace msg="received dealer pong" Mar 29 18:41:47 volumio volumio[26389]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"} Mar 29 18:41:47 volumio volumio[26389]: info: CoreCommandRouter::volumioGetState Mar 29 18:41:47 volumio volumio[26389]: verbose: UNSET VOLATILE: Service: squeezelite_mc Mar 29 18:41:47 volumio volumio[26389]: info: [squeezelite_mc] Pushing empty state... Mar 29 18:41:47 volumio volumio[26389]: info: CoreCommandRouter::servicePushState Mar 29 18:41:47 volumio volumio[26389]: info: CoreStateMachine::pushState Mar 29 18:41:47 volumio volumio[26389]: info: CoreCommandRouter::volumioPushState Mar 29 18:41:47 volumio volumio[26389]: info: CoreCommandRouter::servicePushState Mar 29 18:41:47 volumio volumio[26389]: info: CoreStateMachine::pushState Mar 29 18:41:47 volumio volumio[26389]: info: CoreCommandRouter::volumioPushState Mar 29 18:41:47 volumio volumio[26389]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"} Mar 29 18:41:58 volumio volumio[26389]: info: [jellyfin-poller] Polled http://192.168.1.103:8096: offline Mar 29 18:42:12 volumio go-librespot[26597]: time="2026-03-29T18:42:12+01:00" level=trace msg="sent dealer ping" Mar 29 18:42:12 volumio go-librespot[26597]: time="2026-03-29T18:42:12+01:00" level=trace msg="received dealer pong" Mar 29 18:42:16 volumio volumio[26389]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"} Mar 29 18:42:18 volumio volumio[26389]: info: [squeezelite_mc] Notification listener started Mar 29 18:42:18 volumio volumio[26389]: info: [squeezelite_mc] Getting players connected to Server (192.168.1.199) Mar 29 18:42:22 volumio volumio[26389]: info: [squeezelite_mc] 'client' notification received from Server (192.168.1.199); type is 'connect' Mar 29 18:42:22 volumio volumio[26389]: info: [squeezelite_mc] Getting players connected to Server (192.168.1.199) Mar 29 18:42:22 volumio volumio[26389]: info: [squeezelite_mc] Players connected to Server (192.168.1.199): [{"id":"dc:a6:32:bd:8e:44","uuid":null,"ip":"192.168.1.180","name":"volumio","server":{"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"}},{"id":"c0:f5:35:00:75:b6","uuid":null,"ip":"192.168.1.125","name":"Raspberries","server":{"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"}}] Mar 29 18:42:22 volumio volumio[26389]: info: [squeezelite_mc] Player found: {"id":"dc:a6:32:bd:8e:44","uuid":null,"ip":"192.168.1.180","name":"volumio","server":{"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"}} Mar 29 18:42:22 volumio volumio[26389]: info: [squeezelite_mc] Players connected to Server (192.168.1.199): [{"id":"dc:a6:32:bd:8e:44","uuid":null,"ip":"192.168.1.180","name":"volumio","server":{"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"}},{"id":"c0:f5:35:00:75:b6","uuid":null,"ip":"192.168.1.125","name":"Raspberries","server":{"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"}}] Mar 29 18:42:22 volumio volumio[26389]: info: [squeezelite_mc] Player found: {"id":"dc:a6:32:bd:8e:44","uuid":null,"ip":"192.168.1.180","name":"volumio","server":{"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"}} Mar 29 18:42:23 volumio volumio[26389]: info: [squeezelite_mc] 'client' notification received from Server (192.168.1.199); type is 'connect' Mar 29 18:42:23 volumio volumio[26389]: info: [squeezelite_mc] Getting players connected to Server (192.168.1.199) Mar 29 18:42:23 volumio volumio[26389]: info: [squeezelite_mc] Players connected to Server (192.168.1.199): [{"id":"dc:a6:32:bd:8e:44","uuid":null,"ip":"192.168.1.180","name":"volumio","server":{"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"}},{"id":"c0:f5:35:00:75:b6","uuid":null,"ip":"192.168.1.125","name":"Raspberries","server":{"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"}},{"id":"54:78:c9:06:cd:86","uuid":null,"ip":"192.168.1.164","name":"Bedroom Wiim","server":{"ip":"192.168.1.199","name":"Server","ver":"8.5.3","uuid":"243fc22e-7ca7-4e01-92ad-841674c020a6","jsonPort":"9000","cliPort":"9090"}}] Mar 29 18:42:26 volumio volumio[26389]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 18:42:26 volumio volumio[26389]: NotificationListenerError: Failed to send server command 'subscribe play,stop,pause,playlist,mixer,sync'. Mar 29 18:42:26 volumio volumio[26389]: at CommandQueue._CommandQueue_shiftAndExec (/data/plugins/music_service/squeezelite_mc/node_modules/lms-cli-notifications/dist/cjs/utils/CommandQueue.js:91:26) Mar 29 18:42:26 volumio volumio[26389]: at runNextTicks (node:internal/process/task_queues:60:5) Mar 29 18:42:26 volumio volumio[26389]: at listOnTimeout (node:internal/timers:540:9) Mar 29 18:42:26 volumio volumio[26389]: at process.processTimers (node:internal/timers:514:7) { Mar 29 18:42:26 volumio volumio[26389]: code: -20, Mar 29 18:42:26 volumio volumio[26389]: cause: Error: response not received Mar 29 18:42:26 volumio volumio[26389]: at Timeout._onTimeout (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:297:48) Mar 29 18:42:26 volumio volumio[26389]: at listOnTimeout (node:internal/timers:573:17) Mar 29 18:42:26 volumio volumio[26389]: at process.processTimers (node:internal/timers:514:7) Mar 29 18:42:26 volumio volumio[26389]: } Mar 29 18:42:26 volumio volumio[26389]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 18:42:27 volumio sudo[14382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 18:41' Mar 29 18:42:27 volumio sudo[14382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"