Dec 07 19:35:02 esszimmer volumio[2125]: info: CoreCommandRouter::volumioGetState Dec 07 19:35:02 esszimmer volumio[2125]: info: CorePlayQueue::getTrack 0 Dec 07 19:35:02 esszimmer volumio[2125]: info: Listing playlists Dec 07 19:35:02 esszimmer volumio[2125]: info: Listing playlists Dec 07 19:35:12 esszimmer volumio[2125]: info: CoreCommandRouter::volumioGetState Dec 07 19:35:12 esszimmer volumio[2125]: info: CorePlayQueue::getTrack 0 Dec 07 19:35:16 esszimmer go-librespot[2583]: time="2025-12-07T19:35:16+01: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)" Dec 07 19:35:16 esszimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 07 19:35:16 esszimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 07 19:35:19 esszimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 07 19:35:19 esszimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 07 19:35:19 esszimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 07 19:35:19 esszimmer go-librespot[2652]: go-librespot daemon starting... Dec 07 19:35:19 esszimmer go-librespot[2653]: time="2025-12-07T19:35:19+01:00" level=info msg="running go-librespot 0.4.0" Dec 07 19:35:19 esszimmer go-librespot[2653]: time="2025-12-07T19:35:19+01:00" level=debug msg="app state loaded" Dec 07 19:35:19 esszimmer go-librespot[2653]: time="2025-12-07T19:35:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 07 19:35:22 esszimmer volumio[2125]: info: CoreCommandRouter::volumioGetState Dec 07 19:35:22 esszimmer volumio[2125]: info: CorePlayQueue::getTrack 0 Dec 07 19:35:22 esszimmer volumio[2125]: info: Listing playlists Dec 07 19:35:22 esszimmer volumio[2125]: info: Listing playlists Dec 07 19:35:24 esszimmer ntpd[1189]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 07 19:35:24 esszimmer ntpd[1189]: DNS: dns_check: DNS error: -11, System error Dec 07 19:35:24 esszimmer ntpd[1189]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 07 19:35:32 esszimmer volumio[2125]: info: CoreCommandRouter::volumioGetState Dec 07 19:35:32 esszimmer volumio[2125]: info: CorePlayQueue::getTrack 0 Dec 07 19:35:39 esszimmer volumio[2125]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=b3d70fb8e433abebb286c5cba1bdc427" http://updates.volumio.org/downloader-v1/track-device Dec 07 19:35:39 esszimmer volumio[2125]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 07 19:35:39 esszimmer volumio[2125]: Dload Upload Total Spent Left Speed Dec 07 19:35:39 esszimmer volumio[2125]: [3.8K blob data] Dec 07 19:35:39 esszimmer volumio[2125]: retrying in 5 seconds, trial 1 Dec 07 19:35:39 esszimmer volumio[2125]: info: Volumio Calling Home Dec 07 19:35:42 esszimmer volumio[2125]: info: CoreCommandRouter::volumioGetState Dec 07 19:35:42 esszimmer volumio[2125]: info: CorePlayQueue::getTrack 0 Dec 07 19:35:42 esszimmer volumio[2125]: info: Listing playlists Dec 07 19:35:42 esszimmer volumio[2125]: info: Listing playlists Dec 07 19:35:49 esszimmer go-librespot[2653]: time="2025-12-07T19:35:49+01: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)" Dec 07 19:35:49 esszimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 07 19:35:49 esszimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 07 19:35:52 esszimmer volumio[2125]: info: CoreCommandRouter::volumioGetState Dec 07 19:35:52 esszimmer volumio[2125]: info: CorePlayQueue::getTrack 0 Dec 07 19:35:52 esszimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 07 19:35:52 esszimmer systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 07 19:35:52 esszimmer systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 07 19:35:52 esszimmer go-librespot[2714]: go-librespot daemon starting... Dec 07 19:35:52 esszimmer go-librespot[2715]: time="2025-12-07T19:35:52+01:00" level=info msg="running go-librespot 0.4.0" Dec 07 19:35:52 esszimmer go-librespot[2715]: time="2025-12-07T19:35:52+01:00" level=debug msg="app state loaded" Dec 07 19:35:52 esszimmer go-librespot[2715]: time="2025-12-07T19:35:52+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 07 19:36:00 esszimmer volumio[2125]: info: Discovery: adding a534aeb5-de9d-4653-8a00-b0de5945990e Dec 07 19:36:00 esszimmer volumio[2125]: info: Discovery: Found device Esszimmer Dec 07 19:36:00 esszimmer volumio[2125]: info: CoreCommandRouter::volumioGetState Dec 07 19:36:00 esszimmer volumio[2125]: info: CorePlayQueue::getTrack 0 Dec 07 19:36:00 esszimmer volumio[2125]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 07 19:36:00 esszimmer go-librespot[2715]: time="2025-12-07T19:36:00+01:00" level=debug msg="new websocket client" Dec 07 19:36:00 esszimmer volumio[2125]: info: Connection to go-librespot Websocket established Dec 07 19:36:00 esszimmer volumio[2125]: info: Discovery: this is already registered, a534aeb5-de9d-4653-8a00-b0de5945990e Dec 07 19:36:00 esszimmer volumio[2125]: info: Discovery: Found device Esszimmer Dec 07 19:36:00 esszimmer volumio[2125]: info: CoreCommandRouter::volumioGetState Dec 07 19:36:00 esszimmer volumio[2125]: info: CorePlayQueue::getTrack 0 Dec 07 19:36:00 esszimmer volumio[2125]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 07 19:36:00 esszimmer volumio[2125]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 07 19:36:00 esszimmer volumio[2125]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 07 19:36:00 esszimmer volumio[2125]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 07 19:36:00 esszimmer volumio[2125]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 07 19:36:00 esszimmer volumio[2125]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 07 19:36:00 esszimmer volumio[2125]: info: CoreCommandRouter::volumioGetState Dec 07 19:36:00 esszimmer volumio[2125]: info: CorePlayQueue::getTrack 0 Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 07 19:36:01 esszimmer volumio[2125]: info: Adding plugin bluetooth to MyMusic Plugins Dec 07 19:36:01 esszimmer volumio[2125]: info: Adding plugin multiroom to MyMusic Plugins Dec 07 19:36:01 esszimmer volumio[2125]: info: Adding plugin metavolumio to MyMusic Plugins Dec 07 19:36:01 esszimmer volumio[2125]: info: Adding plugin cd_controller to MyMusic Plugins Dec 07 19:36:01 esszimmer volumio[2125]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 07 19:36:01 esszimmer volumio[2125]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 07 19:36:01 esszimmer volumio[2125]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 07 19:36:01 esszimmer go-librespot[2715]: time="2025-12-07T19:36:01+01: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\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-07T19:36:01+01:00 is before 2025-12-08T00:00:00Z" Dec 07 19:36:01 esszimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 07 19:36:01 esszimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 07 19:36:01 esszimmer volumio[2125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 19:36:01 esszimmer volumio[2125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 07 19:36:01 esszimmer volumio[2125]: info: Starting MyVolumio Remote Streaming Endpoints Dec 07 19:36:01 esszimmer volumio[2125]: info: MyVolumio login type: Token Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 07 19:36:01 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 07 19:36:02 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 07 19:36:02 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 07 19:36:02 esszimmer volumio[2125]: info: Streaming services startup Dec 07 19:36:02 esszimmer volumio[2125]: info: Starting Streaming Daemon Dec 07 19:36:02 esszimmer sudo[2739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 07 19:36:02 esszimmer sudo[2739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 07 19:36:02 esszimmer volumio[2125]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 07 19:36:02 esszimmer sudo[2739]: pam_unix(sudo:session): session closed for user root Dec 07 19:36:02 esszimmer volumio[2125]: info: Connection to go-librespot Websocket closed Dec 07 19:36:02 esszimmer volumio[2125]: error: Cannot start Volumio Streaming Daemon Dec 07 19:36:02 esszimmer volumio[2125]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 07 19:36:02 esszimmer volumio[2125]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 07 19:36:02 esszimmer volumio[2125]: info: Volumio called home Dec 07 19:36:02 esszimmer volumio[2125]: error: MyVolumio Custom Token format not valid, refreshing it Dec 07 19:36:02 esszimmer volumio[2125]: info: MyVolumio login type: Token Dec 07 19:36:02 esszimmer volumio[2125]: info: CoreCommandRouter::volumioGetState Dec 07 19:36:02 esszimmer volumio[2125]: info: CorePlayQueue::getTrack 0 Dec 07 19:36:02 esszimmer volumio[2125]: info: Listing playlists Dec 07 19:36:02 esszimmer volumio[2125]: info: Listing playlists Dec 07 19:36:03 esszimmer volumio[2125]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 07 19:36:03 esszimmer volumio[2125]: info: MyVolumio token set successfully Dec 07 19:36:03 esszimmer volumio[2125]: info: MYVOLUMIO: Adding device Dec 07 19:36:03 esszimmer volumio[2125]: info: MYVOLUMIO: Evaluating Server Dec 07 19:36:03 esszimmer volumio[2125]: info: Getting Spotify volume Dec 07 19:36:03 esszimmer volumio[2125]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 19:36:03 esszimmer volumio[2125]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 07 19:36:03 esszimmer volumio[2125]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 07 19:36:03 esszimmer volumio[2125]: errno: -111, Dec 07 19:36:03 esszimmer volumio[2125]: code: 'ECONNREFUSED', Dec 07 19:36:03 esszimmer volumio[2125]: syscall: 'connect', Dec 07 19:36:03 esszimmer volumio[2125]: address: '127.0.0.1', Dec 07 19:36:03 esszimmer volumio[2125]: port: 9879, Dec 07 19:36:03 esszimmer volumio[2125]: response: undefined Dec 07 19:36:03 esszimmer volumio[2125]: } Dec 07 19:36:03 esszimmer volumio[2125]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 19:36:03 esszimmer sudo[2761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-07 19:35' Dec 07 19:36:03 esszimmer sudo[2761]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"