Feb 21 22:58:00 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:00 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 816. Feb 21 22:58:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:02 volumio go-librespot[13153]: go-librespot daemon starting... Feb 21 22:58:02 volumio go-librespot[13154]: time="2026-02-21T22:58:02+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:02 volumio go-librespot[13154]: time="2026-02-21T22:58:02+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:03 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:03 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 817. Feb 21 22:58:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:05 volumio go-librespot[13161]: go-librespot daemon starting... Feb 21 22:58:05 volumio go-librespot[13162]: time="2026-02-21T22:58:05+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:05 volumio go-librespot[13162]: time="2026-02-21T22:58:05+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:06 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:06 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 818. Feb 21 22:58:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:08 volumio go-librespot[13168]: go-librespot daemon starting... Feb 21 22:58:08 volumio go-librespot[13169]: time="2026-02-21T22:58:08+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:08 volumio go-librespot[13169]: time="2026-02-21T22:58:08+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:09 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:09 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:11 volumio volumio[1294]: info: CoreCommandRouter::volumioPause Feb 21 22:58:11 volumio volumio[1294]: info: CoreStateMachine::pause Feb 21 22:58:11 volumio volumio[1294]: info: CoreStateMachine::stPlaybackTimer Feb 21 22:58:11 volumio volumio[1294]: info: CoreStateMachine::servicePause Feb 21 22:58:11 volumio volumio[1294]: info: CoreCommandRouter::servicePause Feb 21 22:58:11 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] pause Feb 21 22:58:11 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendPause -> calling D-Bus Pause() Feb 21 22:58:11 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: false from FC:31:5D:26:E6:4D Feb 21 22:58:11 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Received pause signal, scheduling idle check Feb 21 22:58:11 volumio volumio[1294]: info: CoreCommandRouter::servicePushState Feb 21 22:58:11 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:58:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:58:11 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:58:11 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:58:11 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:58:11 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:58:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 819. Feb 21 22:58:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:11 volumio go-librespot[13190]: go-librespot daemon starting... Feb 21 22:58:11 volumio go-librespot[13191]: time="2026-02-21T22:58:11+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:11 volumio go-librespot[13191]: time="2026-02-21T22:58:11+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:12 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:12 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:15 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:58:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 820. Feb 21 22:58:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:15 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:15 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:15 volumio go-librespot[13197]: go-librespot daemon starting... Feb 21 22:58:15 volumio go-librespot[13198]: time="2026-02-21T22:58:15+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:15 volumio go-librespot[13198]: time="2026-02-21T22:58:15+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:16 volumio bluealsa[988]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 21 22:58:16 volumio bluealsa[988]: ../src/ba-transport-pcm.c:307: Closing BT socket duplicate [15]: 16 Feb 21 22:58:16 volumio bluealsa[988]: ../src/ba-transport.c:381: Closing A2DP transport: 15 Feb 21 22:58:16 volumio bluealsa[988]: ../src/ba-transport-pcm.c:257: Exiting IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Feb 21 22:58:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] D: aplay.c:643: BT device marked as inactive: FC:31:5D:26:E6:4D Feb 21 22:58:17 volumio volumio[1294]: info: Executing endpoint metavolumio Feb 21 22:58:17 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 21 22:58:17 volumio volumio[1294]: info: Executing endpoint metavolumio Feb 21 22:58:17 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 21 22:58:18 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:18 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 821. Feb 21 22:58:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:18 volumio go-librespot[13204]: go-librespot daemon starting... Feb 21 22:58:18 volumio go-librespot[13205]: time="2026-02-21T22:58:18+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:18 volumio go-librespot[13205]: time="2026-02-21T22:58:18+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:20 volumio volumio[1294]: info: CoreCommandRouter::volumioVolatilePlay Feb 21 22:58:20 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] play Feb 21 22:58:20 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendPlay -> calling D-Bus Play() Feb 21 22:58:20 volumio bluealsa[988]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 21 22:58:20 volumio bluealsa[988]: ../src/ba-transport.c:319: New A2DP transport: 15 Feb 21 22:58:20 volumio bluealsa[988]: ../src/ba-transport.c:320: A2DP socket MTU: 15: R:1021 W:1004 Feb 21 22:58:20 volumio bluealsa[988]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 21 22:58:20 volumio bluealsa[988]: ../src/ba-transport.c:1075: Starting transport: A2DP Sink (SBC) Feb 21 22:58:20 volumio bluealsa[988]: ../src/ba-transport-pcm.c:294: Created BT socket duplicate: [15]: 16 Feb 21 22:58:20 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: true from FC:31:5D:26:E6:4D Feb 21 22:58:20 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Playback started, enabling output Feb 21 22:58:20 volumio bluealsa[988]: ../src/a2dp-sbc.c:331: PCM IO loop: START: a2dp_sbc_dec_thread: A2DP Sink (SBC) Feb 21 22:58:20 volumio volumio[1294]: info: CoreCommandRouter::volumioStop Feb 21 22:58:20 volumio volumio[1294]: info: CoreStateMachine::stop Feb 21 22:58:20 volumio volumio[1294]: info: CoreStateMachine::serviceStop Feb 21 22:58:20 volumio volumio[1294]: info: CoreCommandRouter::serviceStop Feb 21 22:58:20 volumio bluealsa[988]: ../src/ba-transport-pcm.c:373: Created new IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Feb 21 22:58:20 volumio volumio[1294]: error: WARNING: No stop method for service [object Object] Feb 21 22:58:20 volumio dbus-daemon[824]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.32" (uid=1000 pid=1294 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=823 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Feb 21 22:58:20 volumio bluealsa[988]: ../src/codec-sbc.c:278: SBC setup: 44100 Hz JointStereo allocation=Loudness blocks=16 sub-bands=8 bit-pool=53 => 327993 bps Feb 21 22:58:20 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] D: aplay.c:732: Opening ALSA playback PCM: name=volumio channels=2 rate=44100 Feb 21 22:58:20 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] D: aplay.c:339: Opening ALSA mixer: name=default elem=Master index=0 Feb 21 22:58:20 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] W: aplay.c:350: Couldn't open ALSA mixer: Mixer element not found Feb 21 22:58:20 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Seek received post-resume, pushing metadata Feb 21 22:58:20 volumio volumio[1294]: info: CoreCommandRouter::servicePushState Feb 21 22:58:20 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:58:20 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:58:20 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:58:20 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:58:20 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:58:20 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:58:21 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:21 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 822. Feb 21 22:58:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:21 volumio go-librespot[13229]: go-librespot daemon starting... Feb 21 22:58:21 volumio go-librespot[13230]: time="2026-02-21T22:58:21+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:21 volumio go-librespot[13230]: time="2026-02-21T22:58:21+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:22 volumio volumio[1294]: info: CoreCommandRouter::volumioPause Feb 21 22:58:22 volumio volumio[1294]: info: CoreStateMachine::pause Feb 21 22:58:22 volumio volumio[1294]: info: CoreStateMachine::stPlaybackTimer Feb 21 22:58:22 volumio volumio[1294]: info: CoreStateMachine::servicePause Feb 21 22:58:22 volumio volumio[1294]: info: CoreCommandRouter::servicePause Feb 21 22:58:22 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] pause Feb 21 22:58:22 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendPause -> calling D-Bus Pause() Feb 21 22:58:22 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: false from FC:31:5D:26:E6:4D Feb 21 22:58:22 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Received pause signal, scheduling idle check Feb 21 22:58:22 volumio volumio[1294]: info: CoreCommandRouter::servicePushState Feb 21 22:58:22 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:58:22 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:58:22 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:58:22 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:58:22 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:58:22 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:58:24 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:24 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 823. Feb 21 22:58:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:24 volumio go-librespot[13237]: go-librespot daemon starting... Feb 21 22:58:24 volumio go-librespot[13238]: time="2026-02-21T22:58:24+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:24 volumio go-librespot[13238]: time="2026-02-21T22:58:24+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:27 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:27 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:28 volumio bluealsa[988]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 21 22:58:28 volumio bluealsa[988]: ../src/ba-transport-pcm.c:307: Closing BT socket duplicate [15]: 16 Feb 21 22:58:28 volumio bluealsa[988]: ../src/ba-transport.c:381: Closing A2DP transport: 15 Feb 21 22:58:28 volumio bluealsa[988]: ../src/ba-transport-pcm.c:257: Exiting IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Feb 21 22:58:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 824. Feb 21 22:58:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:28 volumio go-librespot[13243]: go-librespot daemon starting... Feb 21 22:58:28 volumio go-librespot[13244]: time="2026-02-21T22:58:28+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:28 volumio go-librespot[13244]: time="2026-02-21T22:58:28+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:28 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] D: aplay.c:643: BT device marked as inactive: FC:31:5D:26:E6:4D Feb 21 22:58:30 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:30 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 825. Feb 21 22:58:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:31 volumio go-librespot[13265]: go-librespot daemon starting... Feb 21 22:58:31 volumio go-librespot[13266]: time="2026-02-21T22:58:31+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:31 volumio go-librespot[13266]: time="2026-02-21T22:58:31+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:33 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:33 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 826. Feb 21 22:58:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:34 volumio go-librespot[13272]: go-librespot daemon starting... Feb 21 22:58:34 volumio go-librespot[13273]: time="2026-02-21T22:58:34+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:34 volumio go-librespot[13273]: time="2026-02-21T22:58:34+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:36 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:36 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 827. Feb 21 22:58:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:37 volumio go-librespot[13281]: go-librespot daemon starting... Feb 21 22:58:37 volumio go-librespot[13282]: time="2026-02-21T22:58:37+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:37 volumio go-librespot[13282]: time="2026-02-21T22:58:37+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:39 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:39 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 828. Feb 21 22:58:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:41 volumio go-librespot[13302]: go-librespot daemon starting... Feb 21 22:58:41 volumio go-librespot[13303]: time="2026-02-21T22:58:41+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:41 volumio go-librespot[13303]: time="2026-02-21T22:58:41+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:42 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:42 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 829. Feb 21 22:58:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:44 volumio go-librespot[13310]: go-librespot daemon starting... Feb 21 22:58:44 volumio go-librespot[13311]: time="2026-02-21T22:58:44+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:44 volumio go-librespot[13311]: time="2026-02-21T22:58:44+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:45 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:45 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 830. Feb 21 22:58:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:47 volumio go-librespot[13318]: go-librespot daemon starting... Feb 21 22:58:47 volumio go-librespot[13319]: time="2026-02-21T22:58:47+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:47 volumio go-librespot[13319]: time="2026-02-21T22:58:47+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:48 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:48 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 831. Feb 21 22:58:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:50 volumio go-librespot[13340]: go-librespot daemon starting... Feb 21 22:58:50 volumio go-librespot[13341]: time="2026-02-21T22:58:50+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:50 volumio go-librespot[13341]: time="2026-02-21T22:58:50+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:50 volumio bluealsa[988]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 21 22:58:50 volumio bluealsa[988]: ../src/ba-transport.c:319: New A2DP transport: 15 Feb 21 22:58:50 volumio bluealsa[988]: ../src/ba-transport.c:320: A2DP socket MTU: 15: R:1021 W:1004 Feb 21 22:58:50 volumio bluealsa[988]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 21 22:58:50 volumio bluealsa[988]: ../src/ba-transport.c:1075: Starting transport: A2DP Sink (SBC) Feb 21 22:58:50 volumio bluealsa[988]: ../src/ba-transport-pcm.c:294: Created BT socket duplicate: [15]: 16 Feb 21 22:58:50 volumio bluealsa[988]: ../src/ba-transport-pcm.c:373: Created new IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Feb 21 22:58:50 volumio bluealsa[988]: ../src/a2dp-sbc.c:331: PCM IO loop: START: a2dp_sbc_dec_thread: A2DP Sink (SBC) Feb 21 22:58:50 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: true from FC:31:5D:26:E6:4D Feb 21 22:58:50 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Playback started, enabling output Feb 21 22:58:50 volumio volumio[1294]: info: CoreCommandRouter::volumioStop Feb 21 22:58:50 volumio volumio[1294]: info: CoreStateMachine::stop Feb 21 22:58:50 volumio volumio[1294]: info: CoreStateMachine::serviceStop Feb 21 22:58:50 volumio volumio[1294]: info: CoreCommandRouter::serviceStop Feb 21 22:58:50 volumio volumio[1294]: error: WARNING: No stop method for service [object Object] Feb 21 22:58:50 volumio dbus-daemon[824]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.32" (uid=1000 pid=1294 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=823 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Feb 21 22:58:51 volumio bluealsa[988]: ../src/codec-sbc.c:278: SBC setup: 44100 Hz JointStereo allocation=Loudness blocks=16 sub-bands=8 bit-pool=53 => 327993 bps Feb 21 22:58:51 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] D: aplay.c:732: Opening ALSA playback PCM: name=volumio channels=2 rate=44100 Feb 21 22:58:51 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] D: aplay.c:339: Opening ALSA mixer: name=default elem=Master index=0 Feb 21 22:58:51 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] W: aplay.c:350: Couldn't open ALSA mixer: Mixer element not found Feb 21 22:58:51 volumio bluealsa[988]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 21 22:58:51 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: false from FC:31:5D:26:E6:4D Feb 21 22:58:51 volumio bluealsa[988]: ../src/ba-transport-pcm.c:307: Closing BT socket duplicate [15]: 16 Feb 21 22:58:51 volumio bluealsa[988]: ../src/ba-transport.c:381: Closing A2DP transport: 15 Feb 21 22:58:51 volumio bluealsa[988]: ../src/ba-transport-pcm.c:257: Exiting IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Feb 21 22:58:51 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:51 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:51 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] D: aplay.c:643: BT device marked as inactive: FC:31:5D:26:E6:4D Feb 21 22:58:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 832. Feb 21 22:58:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:54 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:54 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:54 volumio go-librespot[13351]: go-librespot daemon starting... Feb 21 22:58:54 volumio go-librespot[13352]: time="2026-02-21T22:58:54+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:54 volumio go-librespot[13352]: time="2026-02-21T22:58:54+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:57 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:58:57 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:58:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 833. Feb 21 22:58:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:58:57 volumio go-librespot[13358]: go-librespot daemon starting... Feb 21 22:58:57 volumio go-librespot[13359]: time="2026-02-21T22:58:57+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:58:57 volumio go-librespot[13359]: time="2026-02-21T22:58:57+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:58:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:58:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:58:58 volumio volumio[1294]: info: CoreCommandRouter::volumioVolatilePlay Feb 21 22:58:58 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] play Feb 21 22:58:58 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendPlay -> calling D-Bus Play() Feb 21 22:58:58 volumio bluealsa[988]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 21 22:58:58 volumio bluealsa[988]: ../src/ba-transport.c:319: New A2DP transport: 15 Feb 21 22:58:58 volumio bluealsa[988]: ../src/ba-transport.c:320: A2DP socket MTU: 15: R:1021 W:1004 Feb 21 22:58:58 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: true from FC:31:5D:26:E6:4D Feb 21 22:58:58 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Playback started, enabling output Feb 21 22:58:58 volumio volumio[1294]: info: CoreCommandRouter::volumioStop Feb 21 22:58:58 volumio volumio[1294]: info: CoreStateMachine::stop Feb 21 22:58:58 volumio volumio[1294]: info: CoreStateMachine::serviceStop Feb 21 22:58:58 volumio volumio[1294]: info: CoreCommandRouter::serviceStop Feb 21 22:58:58 volumio volumio[1294]: error: WARNING: No stop method for service [object Object] Feb 21 22:58:58 volumio bluealsa[988]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 21 22:58:58 volumio bluealsa[988]: ../src/ba-transport.c:1075: Starting transport: A2DP Sink (SBC) Feb 21 22:58:58 volumio bluealsa[988]: ../src/ba-transport-pcm.c:294: Created BT socket duplicate: [15]: 16 Feb 21 22:58:58 volumio bluealsa[988]: ../src/a2dp-sbc.c:331: PCM IO loop: START: a2dp_sbc_dec_thread: A2DP Sink (SBC) Feb 21 22:58:58 volumio bluealsa[988]: ../src/ba-transport-pcm.c:373: Created new IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Feb 21 22:58:58 volumio dbus-daemon[824]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.32" (uid=1000 pid=1294 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=823 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Feb 21 22:58:58 volumio bluealsa[988]: ../src/codec-sbc.c:278: SBC setup: 44100 Hz JointStereo allocation=Loudness blocks=16 sub-bands=8 bit-pool=53 => 327993 bps Feb 21 22:58:58 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] D: aplay.c:732: Opening ALSA playback PCM: name=volumio channels=2 rate=44100 Feb 21 22:58:58 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] D: aplay.c:339: Opening ALSA mixer: name=default elem=Master index=0 Feb 21 22:58:58 volumio volumio[1294]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [7114] W: aplay.c:350: Couldn't open ALSA mixer: Mixer element not found Feb 21 22:58:58 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Seek received post-resume, pushing metadata Feb 21 22:58:58 volumio volumio[1294]: info: CoreCommandRouter::servicePushState Feb 21 22:58:58 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:58:58 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:58:58 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:58:58 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:58:58 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:58:58 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:00 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:59:00 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:59:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 834. Feb 21 22:59:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:00 volumio go-librespot[13381]: go-librespot daemon starting... Feb 21 22:59:00 volumio go-librespot[13382]: time="2026-02-21T22:59:00+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:59:00 volumio go-librespot[13382]: time="2026-02-21T22:59:00+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:59:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:59:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:59:03 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:59:03 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:59:03 volumio volumio[1294]: info: CoreCommandRouter::volumioNext Feb 21 22:59:03 volumio volumio[1294]: info: CoreStateMachine::next Feb 21 22:59:03 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] next Feb 21 22:59:03 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendNext -> calling D-Bus Next() Feb 21 22:59:03 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Received new metadata for FC:31:5D:26:E6:4D Feb 21 22:59:03 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [metaCache] Saved metadata to /tmp/bluetooth-cache/meta-FC:31:5D:26:E6:4D.json Feb 21 22:59:03 volumio volumio[1294]: info: CoreCommandRouter::servicePushState Feb 21 22:59:03 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:59:03 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:59:03 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:59:03 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:59:03 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:59:03 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 835. Feb 21 22:59:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:03 volumio go-librespot[13388]: go-librespot daemon starting... Feb 21 22:59:03 volumio go-librespot[13389]: time="2026-02-21T22:59:03+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:59:03 volumio go-librespot[13389]: time="2026-02-21T22:59:03+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:59:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:59:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:59:05 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Received new metadata for FC:31:5D:26:E6:4D Feb 21 22:59:05 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [metaCache] Saved metadata to /tmp/bluetooth-cache/meta-FC:31:5D:26:E6:4D.json Feb 21 22:59:05 volumio volumio[1294]: info: CoreCommandRouter::servicePushState Feb 21 22:59:05 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:59:05 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:59:05 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:59:05 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:59:05 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:59:05 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:06 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:59:06 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:59:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 836. Feb 21 22:59:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:07 volumio go-librespot[13398]: go-librespot daemon starting... Feb 21 22:59:07 volumio go-librespot[13399]: time="2026-02-21T22:59:07+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:59:07 volumio go-librespot[13399]: time="2026-02-21T22:59:07+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:59:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:59:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:59:09 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:59:09 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:59:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 837. Feb 21 22:59:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:10 volumio go-librespot[13419]: go-librespot daemon starting... Feb 21 22:59:10 volumio go-librespot[13420]: time="2026-02-21T22:59:10+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:59:10 volumio go-librespot[13420]: time="2026-02-21T22:59:10+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:59:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:59:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:59:11 volumio volumio[1294]: info: CoreCommandRouter::volumioNext Feb 21 22:59:11 volumio volumio[1294]: info: CoreStateMachine::next Feb 21 22:59:11 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] next Feb 21 22:59:11 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendNext -> calling D-Bus Next() Feb 21 22:59:11 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Received new metadata for FC:31:5D:26:E6:4D Feb 21 22:59:11 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [metaCache] Saved metadata to /tmp/bluetooth-cache/meta-FC:31:5D:26:E6:4D.json Feb 21 22:59:11 volumio volumio[1294]: info: CoreCommandRouter::servicePushState Feb 21 22:59:11 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:59:11 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:59:11 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:59:11 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:59:11 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:59:11 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:12 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:59:12 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:59:12 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Received new metadata for FC:31:5D:26:E6:4D Feb 21 22:59:12 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [metaCache] Saved metadata to /tmp/bluetooth-cache/meta-FC:31:5D:26:E6:4D.json Feb 21 22:59:12 volumio volumio[1294]: info: CoreCommandRouter::servicePushState Feb 21 22:59:12 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:59:12 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:59:12 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:59:12 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:59:12 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:59:12 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 838. Feb 21 22:59:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:13 volumio go-librespot[13427]: go-librespot daemon starting... Feb 21 22:59:13 volumio go-librespot[13428]: time="2026-02-21T22:59:13+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:59:13 volumio go-librespot[13428]: time="2026-02-21T22:59:13+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:59:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:59:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:59:14 volumio volumio[1294]: info: CoreCommandRouter::volumioNext Feb 21 22:59:14 volumio volumio[1294]: info: CoreStateMachine::next Feb 21 22:59:14 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] next Feb 21 22:59:14 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendNext -> calling D-Bus Next() Feb 21 22:59:14 volumio volumio[1294]: info: CoreCommandRouter::volumioNext Feb 21 22:59:14 volumio volumio[1294]: info: CoreStateMachine::next Feb 21 22:59:14 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] next Feb 21 22:59:14 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendNext -> calling D-Bus Next() Feb 21 22:59:14 volumio volumio[1294]: info: CoreCommandRouter::volumioNext Feb 21 22:59:14 volumio volumio[1294]: info: CoreStateMachine::next Feb 21 22:59:14 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] next Feb 21 22:59:14 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendNext -> calling D-Bus Next() Feb 21 22:59:14 volumio volumio[1294]: info: CoreCommandRouter::volumioNext Feb 21 22:59:14 volumio volumio[1294]: info: CoreStateMachine::next Feb 21 22:59:14 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] next Feb 21 22:59:14 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendNext -> calling D-Bus Next() Feb 21 22:59:14 volumio volumio[1294]: info: Executing endpoint metavolumio Feb 21 22:59:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 21 22:59:14 volumio volumio[1294]: info: Executing endpoint metavolumio Feb 21 22:59:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 21 22:59:14 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Received new metadata for FC:31:5D:26:E6:4D Feb 21 22:59:14 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [metaCache] Saved metadata to /tmp/bluetooth-cache/meta-FC:31:5D:26:E6:4D.json Feb 21 22:59:14 volumio volumio[1294]: info: CoreCommandRouter::servicePushState Feb 21 22:59:14 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:59:14 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:59:14 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:59:14 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:59:14 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:59:14 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:15 volumio volumio[1294]: info: Initializing connection to go-librespot Websocket Feb 21 22:59:15 volumio volumio[1294]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 21 22:59:15 volumio volumio[1294]: info: CoreCommandRouter::volumioNext Feb 21 22:59:15 volumio volumio[1294]: info: CoreStateMachine::next Feb 21 22:59:15 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] next Feb 21 22:59:15 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendNext -> calling D-Bus Next() Feb 21 22:59:15 volumio volumio[1294]: info: CoreCommandRouter::volumioNext Feb 21 22:59:15 volumio volumio[1294]: info: CoreStateMachine::next Feb 21 22:59:15 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [FUNC] next Feb 21 22:59:15 volumio volumio[1294]: ------------------------------------ BT MESSAGE: sendNext -> calling D-Bus Next() Feb 21 22:59:15 volumio volumio[1294]: ------------------------------------ BT MESSAGE: Received new metadata for FC:31:5D:26:E6:4D Feb 21 22:59:15 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [metaCache] Saved metadata to /tmp/bluetooth-cache/meta-FC:31:5D:26:E6:4D.json Feb 21 22:59:15 volumio volumio[1294]: info: CoreCommandRouter::servicePushState Feb 21 22:59:15 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:59:15 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:59:15 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:59:15 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:59:15 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:59:15 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 839. Feb 21 22:59:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 21 22:59:16 volumio go-librespot[13434]: go-librespot daemon starting... Feb 21 22:59:16 volumio go-librespot[13435]: time="2026-02-21T22:59:16+01:00" level=info msg="running go-librespot 0.6.2" Feb 21 22:59:16 volumio go-librespot[13435]: time="2026-02-21T22:59:16+01:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: invalid character '\\x00' looking for beginning of value" Feb 21 22:59:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 22:59:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 22:59:16 volumio volumio[1294]: info: VolumeController::SetAlsaVolume+ Feb 21 22:59:16 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Feb 21 22:59:16 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Feb 21 22:59:16 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] self.currentMAC = FC:31:5D:26:E6:4D Feb 21 22:59:16 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] transportManager.currentMAC = undefined Feb 21 22:59:16 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] MAC resolved for push = FC:31:5D:26:E6:4D Feb 21 22:59:16 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] Capabilities for FC:31:5D:26:E6:4D: {"Volume":true} Feb 21 22:59:16 volumio volumio[1294]: ------------------------------------ BT MESSAGE: pushVolumeToDevice: Device does not support D-Bus volume control (Volume method missing or inactive player) Feb 21 22:59:16 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setVolume Feb 21 22:59:16 volumio volumio[1294]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setVolume] in plugin alsa_controller Feb 21 22:59:17 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:59:17 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:59:17 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:17 volumio volumio[1294]: info: VolumeController::SetAlsaVolume+ Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] self.currentMAC = FC:31:5D:26:E6:4D Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] transportManager.currentMAC = undefined Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] MAC resolved for push = FC:31:5D:26:E6:4D Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] Capabilities for FC:31:5D:26:E6:4D: {"Volume":true} Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: pushVolumeToDevice: Device does not support D-Bus volume control (Volume method missing or inactive player) Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setVolume Feb 21 22:59:17 volumio volumio[1294]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setVolume] in plugin alsa_controller Feb 21 22:59:17 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:59:17 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:59:17 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:17 volumio volumio[1294]: info: VolumeController::SetAlsaVolume+ Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] self.currentMAC = FC:31:5D:26:E6:4D Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] transportManager.currentMAC = undefined Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] MAC resolved for push = FC:31:5D:26:E6:4D Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] Capabilities for FC:31:5D:26:E6:4D: {"Volume":true} Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: pushVolumeToDevice: Device does not support D-Bus volume control (Volume method missing or inactive player) Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setVolume Feb 21 22:59:17 volumio volumio[1294]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setVolume] in plugin alsa_controller Feb 21 22:59:17 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:59:17 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:59:17 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:17 volumio volumio[1294]: info: VolumeController::SetAlsaVolume+ Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] self.currentMAC = FC:31:5D:26:E6:4D Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] transportManager.currentMAC = undefined Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] MAC resolved for push = FC:31:5D:26:E6:4D Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] Capabilities for FC:31:5D:26:E6:4D: {"Volume":true} Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: pushVolumeToDevice: Device does not support D-Bus volume control (Volume method missing or inactive player) Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setVolume Feb 21 22:59:17 volumio volumio[1294]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setVolume] in plugin alsa_controller Feb 21 22:59:17 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:59:17 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:59:17 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:17 volumio volumio[1294]: info: VolumeController::SetAlsaVolume+ Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] self.currentMAC = FC:31:5D:26:E6:4D Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] transportManager.currentMAC = undefined Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] MAC resolved for push = FC:31:5D:26:E6:4D Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: [DEBUG] Capabilities for FC:31:5D:26:E6:4D: {"Volume":true} Feb 21 22:59:17 volumio volumio[1294]: ------------------------------------ BT MESSAGE: pushVolumeToDevice: Device does not support D-Bus volume control (Volume method missing or inactive player) Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setVolume Feb 21 22:59:17 volumio volumio[1294]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setVolume] in plugin alsa_controller Feb 21 22:59:17 volumio volumio[1294]: info: CoreStateMachine::pushState Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::volumioPushState Feb 21 22:59:17 volumio volumio[1294]: info: MRS: Pushing multiroomSync output update for this device Feb 21 22:59:17 volumio volumio[1294]: info: MRS: Pushing multiroomSync output Feb 21 22:59:17 volumio volumio[1294]: info: CoreCommandRouter::volumioGetState Feb 21 22:59:17 volumio volumio[1294]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 22:59:17 volumio volumio[1294]: TypeError: Cannot read properties of null (reading 'slice') Feb 21 22:59:17 volumio volumio[1294]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 21 22:59:17 volumio volumio[1294]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 21 22:59:17 volumio volumio[1294]: at Object.onceWrapper (node:events:629:26) Feb 21 22:59:17 volumio volumio[1294]: at ClientRequest.emit (node:events:514:28) Feb 21 22:59:17 volumio volumio[1294]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Feb 21 22:59:17 volumio volumio[1294]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Feb 21 22:59:17 volumio volumio[1294]: at TLSSocket.socketOnData (node:_http_client:535:22) Feb 21 22:59:17 volumio volumio[1294]: at TLSSocket.emit (node:events:514:28) Feb 21 22:59:17 volumio volumio[1294]: at addChunk (node:internal/streams/readable:343:12) Feb 21 22:59:17 volumio volumio[1294]: at readableAddChunk (node:internal/streams/readable:316:9) Feb 21 22:59:17 volumio volumio[1294]: at Readable.push (node:internal/streams/readable:253:10) Feb 21 22:59:17 volumio volumio[1294]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Feb 21 22:59:17 volumio volumio[1294]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 22:59:18 volumio sudo[13461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 22:58' Feb 21 22:59:18 volumio sudo[13461]: 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"