Oct 12 19:25:03 living-room volumio-remote-updater[848]: [2026-10-12 19:25:02] [connect] Successful connection Oct 12 19:25:05 living-room volumio[6081]: info: An error occurred while refreshing Spotify Token Error: Client network socket disconnected before secure TLS connection was established Oct 12 19:25:06 living-room systemd[1]: /lib/systemd/system/roonbridge.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 12 19:25:06 living-room systemd[1]: /lib/systemd/system/roonbridge.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 12 19:25:06 living-room systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 12 19:25:06 living-room systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 12 19:25:06 living-room systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 12 19:25:06 living-room systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 12 19:25:07 living-room volumio[6081]: info: touch_display: IframeKeyboardBridge extension installed successfully Oct 12 19:25:07 living-room volumio[6081]: info: Volumio called home Oct 12 19:25:08 living-room systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Oct 12 19:25:08 living-room systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 19:25:08 living-room systemd[1]: volumio5-onboarding.service: Consumed 3.516s CPU time. Oct 12 19:25:10 living-room systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 19:25:10 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 19:25:11 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 19:25:11 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 19:25:11 living-room volumio[6081]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Oct 12 19:25:11 living-room volumio[6081]: error: Plugin user_interface touch_display failed to complete 'onStart' in a timely fashion Oct 12 19:25:11 living-room volumio[6081]: info: ------------------------------------------- Oct 12 19:25:11 living-room volumio[6081]: info: ----- MyVolumio plugins startup ---- Oct 12 19:25:11 living-room volumio[6081]: info: ------------------------------------------- Oct 12 19:25:11 living-room volumio[6081]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 12 19:25:11 living-room sudo[6473]: pam_unix(sudo:session): session closed for user root Oct 12 19:25:11 living-room sudo[6472]: pam_unix(sudo:session): session closed for user root Oct 12 19:25:11 living-room volumio[6081]: info: Spotify config file written Oct 12 19:25:13 living-room sudo[6590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 12 19:25:13 living-room sudo[6590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 19:25:14 living-room systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Oct 12 19:25:14 living-room systemd[1]: go-librespot-daemon.service: Deactivated successfully. Oct 12 19:25:14 living-room systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 12 19:25:14 living-room systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 12 19:25:14 living-room sudo[6590]: pam_unix(sudo:session): session closed for user root Oct 12 19:25:15 living-room go-librespot[6611]: go-librespot daemon starting... Oct 12 19:25:18 living-room volumio5-onboarding[6582]: time=2026-10-12T19:25:17.948+01:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 12 19:25:19 living-room go-librespot[6612]: time="2026-10-12T19:25:19+01:00" level=info msg="running go-librespot 0.7.1" Oct 12 19:25:19 living-room go-librespot[6612]: time="2026-10-12T19:25:19+01:00" level=debug msg="app state loaded" Oct 12 19:25:19 living-room volumio-remote-updater[848]: [2026-10-12 19:25:19] [connect] Successful connection Oct 12 19:25:22 living-room go-librespot[6612]: time="2026-10-12T19:25:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 12 19:25:23 living-room go-librespot[6612]: time="2026-10-12T19:25:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 12 19:25:23 living-room go-librespot[6612]: time="2026-10-12T19:25:23+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 12 19:25:23 living-room go-librespot[6612]: time="2026-10-12T19:25:23+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 12 19:25:23 living-room go-librespot[6612]: time="2026-10-12T19:25:23+01:00" level=info msg="zeroconf server listening on port 39845" Oct 12 19:25:24 living-room go-librespot[6612]: time="2026-10-12T19:25:24+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 12 19:25:29 living-room volumio5-onboarding[6582]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:43492->127.0.0.1:3000: i/o timeout Oct 12 19:25:30 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 19:25:30 living-room volumio[6081]: info: Discovery: Getting this device information Oct 12 19:25:30 living-room volumio[6081]: info: CoreCommandRouter::volumioGetState Oct 12 19:25:30 living-room volumio[6081]: info: CorePlayQueue::getTrack 0 Oct 12 19:25:30 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 19:25:30 living-room systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:25:30 living-room systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 12 19:25:30 living-room systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 12 19:25:30 living-room systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Oct 12 19:25:30 living-room systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 19:25:30 living-room systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 19:25:34 living-room volumio5-onboarding[6641]: time=2026-10-12T19:25:34.595+01:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 12 19:25:35 living-room volumio-remote-updater[848]: [2026-10-12 19:25:35] [connect] Successful connection Oct 12 19:25:42 living-room volumio[6081]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 12 19:25:42 living-room volumio[6081]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 12 19:25:42 living-room volumio[6081]: info: touch_display: systemctl daemon-reload succeeded. Oct 12 19:25:43 living-room sudo[6681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 12 19:25:43 living-room sudo[6681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 19:25:44 living-room volumio[6081]: error: MyVolumio Plugin failed to start in a timely fashion Oct 12 19:25:44 living-room volumio[6081]: [Metrics] CommandRouter: 179s 999.07ms Oct 12 19:25:44 living-room volumio[6081]: info: CoreCommandRouter::volumiosetStartupVolume Oct 12 19:25:44 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:44 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 19:25:44 living-room volumio[6081]: info: CoreCommandRouter::Close All Modals sent Oct 12 19:25:44 living-room volumio[6081]: info: CoreCommandRouter::Close All Modals sent Oct 12 19:25:44 living-room sudo[6681]: pam_unix(sudo:session): session closed for user root Oct 12 19:25:45 living-room volumio[6081]: info: No need to fix Spotify hosts Oct 12 19:25:45 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:45 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:45 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:45 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:45 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:45 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:45 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:45 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:45 living-room systemd[1]: mpd.service: start operation timed out. Terminating. Oct 12 19:25:45 living-room systemd[1]: mpd.service: Failed with result 'timeout'. Oct 12 19:25:45 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 19:25:45 living-room systemd[1]: Failed to start mpd.service - Music Player Daemon. Oct 12 19:25:45 living-room systemd[1]: mpd.service: Consumed 7.084s CPU time. Oct 12 19:25:46 living-room sudo[6327]: pam_unix(sudo:session): session closed for user root Oct 12 19:25:46 living-room sudo[6356]: pam_unix(sudo:session): session closed for user root Oct 12 19:25:46 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:46 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:46 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:46 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:46 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:46 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:46 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:46 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:46 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:46 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 19:25:46 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 19:25:46 living-room volumio5-onboarding[6641]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:41242->127.0.0.1:3000: i/o timeout Oct 12 19:25:46 living-room systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:25:46 living-room systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 12 19:25:46 living-room systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Oct 12 19:25:46 living-room systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 19:25:46 living-room systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 19:25:47 living-room volumio[6081]: -> REQUEST 0 com.roonlabs.registry:1/info Oct 12 19:25:48 living-room volumio5-onboarding[6714]: time=2026-10-12T19:25:48.052+01:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 12 19:25:50 living-room volumio[6081]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 12 19:25:50 living-room volumio[6081]: info: touch_display: Volumio Kiosk started. Oct 12 19:25:50 living-room volumio[6081]: info: CoreCommandRouter::volumioGetState Oct 12 19:25:50 living-room volumio[6081]: info: CorePlayQueue::getTrack 0 Oct 12 19:25:50 living-room systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 12 19:25:50 living-room systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 12 19:25:50 living-room systemd[1]: setdatetime-helper.service: Consumed 2.054s CPU time. Oct 12 19:25:51 living-room volumio-remote-updater[848]: [2026-10-12 19:25:51] [connect] Successful connection Oct 12 19:25:52 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 12 19:25:52 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 19:25:52 living-room volumio[6081]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 12 19:25:57 living-room volumio[6081]: info: BOOT COMPLETED Oct 12 19:26:00 living-room volumio5-onboarding[6714]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:47944->127.0.0.1:3000: i/o timeout Oct 12 19:26:00 living-room systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:26:00 living-room systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 12 19:26:00 living-room systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Oct 12 19:26:00 living-room systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 19:26:00 living-room sudo[6750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Oct 12 19:26:00 living-room sudo[6750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 19:26:00 living-room sudo[6750]: pam_unix(sudo:session): session closed for user root Oct 12 19:26:00 living-room volumio[6081]: <- COMPLETE 0 Success {"core_id":"6fa9cc97-b226-4fc5-be91-96b69a21fcf3","display_name":"AS6704T-A746","display_version":"2.55 (build 1559) production"} Oct 12 19:26:00 living-room volumio[6081]: -> REQUEST 1 com.roonlabs.registry:1/register {"extension_id":"com.volumio.metaroon","display_name":"Volumio Roon Bridge","display_version":"1.0.0","publisher":"Volumio Community","email":"plugins@volumio.com","required_services":["com.roonlabs.transport:2","com.roonlabs.image:1"],"optional_services":["com.roonlabs.browse:1"],"provided_services":["com.roonlabs.volumecontrol:1","com.roonlabs.pairing:1","com.roonlabs.ping:1"],"token":"ec1cddb0-9f43-4c55-8314-e950dea3224d"} Oct 12 19:26:00 living-room systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 19:26:03 living-room volumio5-onboarding[6753]: time=2026-10-12T19:26:03.383+01:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 12 19:26:03 living-room volumio[6081]: info: touch_display: X display number found: 0 Oct 12 19:26:03 living-room volumio[6081]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 12 19:26:03 living-room volumio[6081]: Job for mpd.service failed because a timeout was exceeded. Oct 12 19:26:03 living-room volumio[6081]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Oct 12 19:26:03 living-room volumio[6081]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Oct 12 19:26:03 living-room volumio[6081]: Job for mpd.service failed because a timeout was exceeded. Oct 12 19:26:03 living-room volumio[6081]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Oct 12 19:26:03 living-room volumio[6081]: info: go-librespot daemon successfully initialized Oct 12 19:26:03 living-room systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 19:26:03 living-room volumio[6081]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 19:26:03 living-room volumio[6081]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Oct 12 19:26:03 living-room volumio[6081]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Oct 12 19:26:03 living-room volumio[6081]: at new NodeError (node:internal/errors:405:5) Oct 12 19:26:03 living-room volumio[6081]: at assert (node:internal/assert:14:11) Oct 12 19:26:03 living-room volumio[6081]: at internalConnectMultiple (node:net:1118:3) Oct 12 19:26:03 living-room volumio[6081]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Oct 12 19:26:03 living-room volumio[6081]: at listOnTimeout (node:internal/timers:575:11) Oct 12 19:26:03 living-room volumio[6081]: at process.processTimers (node:internal/timers:514:7) { Oct 12 19:26:03 living-room volumio[6081]: code: 'ERR_INTERNAL_ASSERTION' Oct 12 19:26:03 living-room volumio[6081]: } Oct 12 19:26:03 living-room volumio[6081]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 19:26:04 living-room sudo[6763]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 19:26:04 living-room sudo[6763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 19:26:04 living-room sudo[6763]: pam_unix(sudo:session): session closed for user root Oct 12 19:26:07 living-room volumio-remote-updater[848]: [2026-10-12 19:26:07] [connect] Successful connection Oct 12 19:26:14 living-room volumio5-onboarding[6753]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:59242->127.0.0.1:3000: i/o timeout Oct 12 19:26:14 living-room systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 12 19:26:14 living-room systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 12 19:26:15 living-room systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 6. Oct 12 19:26:15 living-room systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 19:26:15 living-room systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 12 19:26:19 living-room volumio5-onboarding[6796]: time=2026-10-12T19:26:19.856+01:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 12 19:26:22 living-room volumio-remote-updater[848]: [2026-10-12 19:26:22] [connect] Successful connection Oct 12 19:26:22 living-room sudo[6822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 19:25' Oct 12 19:26:22 living-room sudo[6822]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"