Feb 07 17:37:00 volumio-pi4-beta volumio[8677]: verbose: New Socket.io Connection to 10.10.60.49:3000 from 10.10.60.50 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 07 17:37:01 volumio-pi4-beta 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. Feb 07 17:37:01 volumio-pi4-beta 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. Feb 07 17:37:01 volumio-pi4-beta systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 17:37:01 volumio-pi4-beta sudo[8879]: pam_unix(sudo:session): session closed for user root Feb 07 17:37:01 volumio-pi4-beta sudo[8808]: pam_unix(sudo:session): session closed for user root Feb 07 17:37:01 volumio-pi4-beta sudo[8822]: pam_unix(sudo:session): session closed for user root Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 07 17:37:01 volumio-pi4-beta systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 85. Feb 07 17:37:01 volumio-pi4-beta systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 07 17:37:01 volumio-pi4-beta systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:37:01 volumio-pi4-beta systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:37:01 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 07 17:37:01 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:37:01 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Consumed 3.151s CPU time. Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: error: MPD error: The expression evaluated to a falsy value: Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: assert.ok(self.idling) Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: error: The expression evaluated to a falsy value: Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: assert.ok(self.idling) Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS DSD at system boot, trial number 4 ,retrying in 5 seconds Feb 07 17:37:01 volumio-pi4-beta sudo[8927]: pam_unix(sudo:session): session closed for user root Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS FLAC at system boot, trial number 4 ,retrying in 5 seconds Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS MP3 at system boot, trial number 4 ,retrying in 5 seconds Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS Now_That's_What_I_Call_Music! at system boot, trial number 4 ,retrying in 5 seconds Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS Hed_Kandi_Discography at system boot, trial number 4 ,retrying in 5 seconds Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS Cafe_Del_Mar_Discography at system boot, trial number 4 ,retrying in 5 seconds Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: error: updateQueue error: null Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: touch_display: Volumio Kiosk started. Feb 07 17:37:01 volumio-pi4-beta sudo[8877]: pam_unix(sudo:session): session closed for user root Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::volumioGetState Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: CorePlayQueue::getTrack 0 Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: Completed starting Core Plugins Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: ------------------------------------------- Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: ----- MyVolumio plugins startup ---- Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: ------------------------------------------- Feb 07 17:37:01 volumio-pi4-beta upmpdcli[8954]: Could not open config: /tmp/upmpdcli.conf Feb 07 17:37:01 volumio-pi4-beta systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:37:01 volumio-pi4-beta systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: MPD running with PID8848 Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: ,establishing connection Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: error: updateQueue error: null Feb 07 17:37:01 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:37:01 volumio-pi4-beta go-librespot[8966]: go-librespot daemon starting... Feb 07 17:37:01 volumio-pi4-beta sudo[8907]: pam_unix(sudo:session): session closed for user root Feb 07 17:37:01 volumio-pi4-beta go-librespot[8969]: time="2026-02-07T17:37:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:37:01 volumio-pi4-beta go-librespot[8969]: time="2026-02-07T17:37:01+07:00" level=debug msg="app state loaded" Feb 07 17:37:01 volumio-pi4-beta go-librespot[8969]: time="2026-02-07T17:37:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:37:01 volumio-pi4-beta volumio[8677]: info: touch_display: X display number found: 0 Feb 07 17:37:02 volumio-pi4-beta volumio[8677]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 07 17:37:02 volumio-pi4-beta volumio[8677]: info: touch_display: X display number found: 0 Feb 07 17:37:02 volumio-pi4-beta volumio[8677]: info: touch_display: HDMI audio configuration already present in volumiokiosk.sh Feb 07 17:37:02 volumio-pi4-beta volumio[8677]: info: touch_display: X display number found: 0 Feb 07 17:37:02 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::volumioGetState Feb 07 17:37:02 volumio-pi4-beta volumio[8677]: info: CorePlayQueue::getTrack 0 Feb 07 17:37:02 volumio-pi4-beta volumio[8677]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 07 17:37:04 volumio-pi4-beta volumio[8677]: info: go-librespot daemon successfully initialized Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: error: [now-playing] Error fetching https://openweathermap.org: TypeError: fetch failed Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: at async fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:25:26) Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: at async doGet (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:118:22) Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): fetch failed Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 07 17:37:06 volumio-pi4-beta volumio[8677]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 07 17:37:07 volumio-pi4-beta volumio[8677]: info: Initializing connection to go-librespot Websocket Feb 07 17:37:08 volumio-pi4-beta volumio[8677]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 17:37:15 volumio-pi4-beta systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 07 17:37:15 volumio-pi4-beta systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 07 17:37:15 volumio-pi4-beta systemd[1]: setdatetime-helper.service: Consumed 1.448s CPU time. Feb 07 17:37:16 volumio-pi4-beta systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 86. Feb 07 17:37:16 volumio-pi4-beta systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:37:16 volumio-pi4-beta systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:37:17 volumio-pi4-beta upmpdcli[9010]: Could not open config: /tmp/upmpdcli.conf Feb 07 17:37:17 volumio-pi4-beta systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:37:17 volumio-pi4-beta systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 07 17:37:21 volumio-pi4-beta volumio[8677]: error: MyVolumio Plugin failed to start in a timely fashion Feb 07 17:37:21 volumio-pi4-beta volumio[8677]: [Metrics] CommandRouter: 44s 116.80ms Feb 07 17:37:21 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::volumiosetStartupVolume Feb 07 17:37:21 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:37:21 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:37:21 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::Close All Modals sent Feb 07 17:37:21 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::Close All Modals sent Feb 07 17:37:23 volumio-pi4-beta volumio[8677]: verbose: New Socket.io Connection to 10.10.60.49:3000 from 10.10.60.50 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 07 17:37:23 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 07 17:37:23 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 07 17:37:23 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 07 17:37:25 volumio-pi4-beta go-librespot[8969]: time="2026-02-07T17:37:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" Feb 07 17:37:25 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:37:25 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:37:26 volumio-pi4-beta volumio[8677]: info: Discovery: adding ffe6037d-239d-40ee-a126-b8e02b337650 Feb 07 17:37:26 volumio-pi4-beta volumio[8677]: info: Discovery: Found device Volumio-Pi5-beta Feb 07 17:37:26 volumio-pi4-beta volumio[8677]: info: Discovery: Connecting to remote: 10.10.60.50 Feb 07 17:37:26 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 17:37:26 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:37:26 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 07 17:37:28 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 07 17:37:28 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:37:28 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:37:28 volumio-pi4-beta go-librespot[9033]: go-librespot daemon starting... Feb 07 17:37:28 volumio-pi4-beta go-librespot[9034]: time="2026-02-07T17:37:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:37:28 volumio-pi4-beta go-librespot[9034]: time="2026-02-07T17:37:28+07:00" level=debug msg="app state loaded" Feb 07 17:37:28 volumio-pi4-beta go-librespot[9034]: time="2026-02-07T17:37:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:37:30 volumio-pi4-beta volumio[8677]: Cannot compose Albumart path Feb 07 17:37:32 volumio-pi4-beta systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 87. Feb 07 17:37:32 volumio-pi4-beta systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:37:32 volumio-pi4-beta systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:37:32 volumio-pi4-beta upmpdcli[9056]: Could not open config: /tmp/upmpdcli.conf Feb 07 17:37:32 volumio-pi4-beta systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:37:32 volumio-pi4-beta systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 07 17:37:33 volumio-pi4-beta volumio[8677]: info: Discovery: adding f7b8c42e-68d2-42b8-9d73-db972896eb17 Feb 07 17:37:33 volumio-pi4-beta volumio[8677]: info: Discovery: Found device Volumio-Pi4-beta Feb 07 17:37:33 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::volumioGetState Feb 07 17:37:33 volumio-pi4-beta volumio[8677]: info: CorePlayQueue::getTrack 0 Feb 07 17:37:33 volumio-pi4-beta volumio[8677]: info: BOOT COMPLETED Feb 07 17:37:47 volumio-pi4-beta systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 88. Feb 07 17:37:47 volumio-pi4-beta systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:37:47 volumio-pi4-beta systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:37:47 volumio-pi4-beta upmpdcli[9071]: Could not open config: /tmp/upmpdcli.conf Feb 07 17:37:47 volumio-pi4-beta systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:37:47 volumio-pi4-beta systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 07 17:37:54 volumio-pi4-beta volumio[8677]: info: Discovery: this is already registered, f7b8c42e-68d2-42b8-9d73-db972896eb17 Feb 07 17:37:54 volumio-pi4-beta volumio[8677]: info: Discovery: Found device Volumio-Pi4-beta Feb 07 17:37:54 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::volumioGetState Feb 07 17:37:54 volumio-pi4-beta volumio[8677]: info: CorePlayQueue::getTrack 0 Feb 07 17:37:54 volumio-pi4-beta volumio[8677]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 07 17:37:55 volumio-pi4-beta volumio[8677]: info: An error occurred while refreshing Spotify Token Error [ERR_TLS_CERT_ALTNAME_INVALID]: Hostname/IP does not match certificate's altnames: Host: oauth-performer.prod.vlmapi.io. is not in the cert's altnames: DNS:*.bkk.redeyeninja.com, DNS:bkk.redeyeninja.com Feb 07 17:37:58 volumio-pi4-beta go-librespot[9034]: time="2026-02-07T17:37:58+07:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Feb 07 17:37:58 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:37:58 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:38:01 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 07 17:38:01 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:38:01 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:38:01 volumio-pi4-beta go-librespot[9087]: go-librespot daemon starting... Feb 07 17:38:01 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:38:01 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:01+07:00" level=debug msg="app state loaded" Feb 07 17:38:01 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:38:02 volumio-pi4-beta systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 89. Feb 07 17:38:02 volumio-pi4-beta systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:38:02 volumio-pi4-beta systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:38:02 volumio-pi4-beta upmpdcli[9108]: Could not open config: /tmp/upmpdcli.conf Feb 07 17:38:02 volumio-pi4-beta systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:38:02 volumio-pi4-beta systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 07 17:38:05 volumio-pi4-beta volumio[8677]: info: Discovery: Connected to remote: 10.10.60.50 Feb 07 17:38:05 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:05+07:00" level=debug msg="new websocket client" Feb 07 17:38:05 volumio-pi4-beta volumio[8677]: info: Connection to go-librespot Websocket established Feb 07 17:38:06 volumio-pi4-beta volumio[8677]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 07 17:38:08 volumio-pi4-beta volumio[8677]: info: Getting Spotify volume Feb 07 17:38:11 volumio-pi4-beta volumio[8677]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 07 17:38:17 volumio-pi4-beta systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 90. Feb 07 17:38:17 volumio-pi4-beta systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:38:17 volumio-pi4-beta systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:38:18 volumio-pi4-beta upmpdcli[9125]: Could not open config: /tmp/upmpdcli.conf Feb 07 17:38:18 volumio-pi4-beta systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:38:18 volumio-pi4-beta systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 07 17:38:21 volumio-pi4-beta volumio[8677]: verbose: New Socket.io Connection to 10.10.60.49:3000 from 10.10.60.50 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 07 17:38:22 volumio-pi4-beta volumio[8677]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 07 17:38:22 volumio-pi4-beta volumio[8677]: verbose: New Socket.io Connection to 10.10.60.49:3000 from 10.10.60.50 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 07 17:38:22 volumio-pi4-beta volumio[8677]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 07 17:38:22 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::volumioGetState Feb 07 17:38:22 volumio-pi4-beta volumio[8677]: info: CorePlayQueue::getTrack 0 Feb 07 17:38:22 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::volumioGetState Feb 07 17:38:22 volumio-pi4-beta volumio[8677]: info: CorePlayQueue::getTrack 0 Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: Adding plugin bluetooth to MyMusic Plugins Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: Adding plugin multiroom to MyMusic Plugins Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: Adding plugin metavolumio to MyMusic Plugins Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: Adding plugin cd_controller to MyMusic Plugins Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 07 17:38:23 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 07 17:38:24 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 07 17:38:24 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 07 17:38:24 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:38:24 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:38:24 volumio-pi4-beta volumio[8677]: info: Starting MyVolumio Remote Streaming Endpoints Feb 07 17:38:25 volumio-pi4-beta volumio[8677]: info: MyVolumio login type: Token Feb 07 17:38:25 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 07 17:38:25 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 07 17:38:26 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 07 17:38:26 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 07 17:38:26 volumio-pi4-beta volumio[8677]: info: Streaming services startup Feb 07 17:38:26 volumio-pi4-beta volumio[8677]: info: Starting Streaming Daemon Feb 07 17:38:26 volumio-pi4-beta sudo[9142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 07 17:38:26 volumio-pi4-beta sudo[9142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:38:26 volumio-pi4-beta volumio[8677]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 07 17:38:26 volumio-pi4-beta sudo[9142]: pam_unix(sudo:session): session closed for user root Feb 07 17:38:26 volumio-pi4-beta volumio[8677]: error: Cannot start Volumio Streaming Daemon Feb 07 17:38:26 volumio-pi4-beta volumio[8677]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 07 17:38:26 volumio-pi4-beta volumio[8677]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 07 17:38:26 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 07 17:38:26 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::volumioGetState Feb 07 17:38:26 volumio-pi4-beta volumio[8677]: info: CorePlayQueue::getTrack 0 Feb 07 17:38:27 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:27+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 17:38:27 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:27+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 07 17:38:27 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:27+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 07 17:38:27 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:27+07:00" level=info msg="zeroconf server listening on port 45183" Feb 07 17:38:28 volumio-pi4-beta volumio[8677]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 07 17:38:33 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:33+07:00" level=debug msg="obtained new client token: AADQ0/GJw5ZPlz9N5/JVE7J+hMQEPh10P896Ej+WjAHLIVDrGqQb0yPFYsNDMVeaAnjMwmtWRQ0xJeWgF0qRKoSy6RnB89xdqv/00/dAQIGY6skPVDqxkTo2e3lUxZSuiL+P98CMfVraLtaYSSJQza4gApFL67G8Xe5W6kCk+ZvvshvdUp0dmK8PPX8UufV+qk14XbNXNx1nc5H6woM09rpqBF+7fNWuXHA5XRFt0yzmvBS/SznbLoGl" Feb 07 17:38:33 volumio-pi4-beta systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 91. Feb 07 17:38:33 volumio-pi4-beta systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:38:33 volumio-pi4-beta systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 07 17:38:33 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:33+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:38:33 volumio-pi4-beta upmpdcli[9163]: Could not open config: /tmp/upmpdcli.conf Feb 07 17:38:33 volumio-pi4-beta systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:38:33 volumio-pi4-beta systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 07 17:38:33 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:33+07:00" level=debug msg="completed keyexchange" Feb 07 17:38:33 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:33+07:00" level=debug msg="completed challenge" Feb 07 17:38:33 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:33+07:00" level=info msg="authenticated AP" username="21*********************6q" Feb 07 17:38:35 volumio-pi4-beta volumio[8677]: info: MyVolumio token set successfully Feb 07 17:38:35 volumio-pi4-beta volumio[8677]: info: MYVOLUMIO: Adding device Feb 07 17:38:35 volumio-pi4-beta volumio[8677]: info: MYVOLUMIO: Evaluating Server Feb 07 17:38:36 volumio-pi4-beta volumio[8677]: info: MyVolumio status changed Feb 07 17:38:36 volumio-pi4-beta volumio[8677]: info: Streaming services startup Feb 07 17:38:36 volumio-pi4-beta volumio[8677]: info: Starting Streaming Daemon Feb 07 17:38:36 volumio-pi4-beta volumio[8677]: info: Removing browser output: myVolumio user plan is not superstar Feb 07 17:38:36 volumio-pi4-beta volumio[8677]: info: Removing audio output: Feb 07 17:38:36 volumio-pi4-beta volumio[8677]: info: Stoppping Tunnel 1 Feb 07 17:38:36 volumio-pi4-beta sudo[9185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 07 17:38:36 volumio-pi4-beta sudo[9185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:38:36 volumio-pi4-beta sudo[9187]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 07 17:38:36 volumio-pi4-beta sudo[9187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:38:36 volumio-pi4-beta 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. Feb 07 17:38:36 volumio-pi4-beta 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. Feb 07 17:38:36 volumio-pi4-beta sudo[9185]: pam_unix(sudo:session): session closed for user root Feb 07 17:38:36 volumio-pi4-beta volumio[8677]: error: Cannot start Volumio Streaming Daemon Feb 07 17:38:36 volumio-pi4-beta volumio[8677]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 07 17:38:36 volumio-pi4-beta volumio[8677]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 07 17:38:36 volumio-pi4-beta 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. Feb 07 17:38:36 volumio-pi4-beta 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. Feb 07 17:38:36 volumio-pi4-beta 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. Feb 07 17:38:36 volumio-pi4-beta 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. Feb 07 17:38:36 volumio-pi4-beta 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. Feb 07 17:38:36 volumio-pi4-beta 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. Feb 07 17:38:36 volumio-pi4-beta sudo[9187]: pam_unix(sudo:session): session closed for user root Feb 07 17:38:36 volumio-pi4-beta volumio[8677]: info: Remote SSH Stopped Feb 07 17:38:39 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 07 17:38:40 volumio-pi4-beta volumio[8677]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 07 17:38:40 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::volumioGetState Feb 07 17:38:40 volumio-pi4-beta volumio[8677]: info: CorePlayQueue::getTrack 0 Feb 07 17:38:44 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:38:44 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:38:44 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 07 17:38:44 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 07 17:38:44 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 07 17:38:44 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::volumioGetBrowseSources Feb 07 17:38:44 volumio-pi4-beta volumio[8677]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 17:38:46 volumio-pi4-beta go-librespot[9088]: time="2026-02-07T17:38:46+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed requesting login5 endpoint: failed requesting login5: Post \"https://login5.spotify.com/v3/login\": net/http: TLS handshake timeout" Feb 07 17:38:46 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:38:46 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:38:46 volumio-pi4-beta volumio[8677]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 17:38:46 volumio-pi4-beta volumio[8677]: Error: socket hang up Feb 07 17:38:46 volumio-pi4-beta volumio[8677]: at connResetException (node:internal/errors:720:14) Feb 07 17:38:46 volumio-pi4-beta volumio[8677]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 07 17:38:46 volumio-pi4-beta volumio[8677]: at Socket.emit (node:events:526:35) Feb 07 17:38:46 volumio-pi4-beta volumio[8677]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 07 17:38:46 volumio-pi4-beta volumio[8677]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 07 17:38:46 volumio-pi4-beta volumio[8677]: code: 'ECONNRESET', Feb 07 17:38:46 volumio-pi4-beta volumio[8677]: response: undefined Feb 07 17:38:46 volumio-pi4-beta volumio[8677]: } Feb 07 17:38:46 volumio-pi4-beta volumio[8677]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 17:38:47 volumio-pi4-beta sudo[9218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 17:37' Feb 07 17:38:47 volumio-pi4-beta sudo[9218]: 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"