Mar 25 06:20:01 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:01 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13266. Mar 25 06:20:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:01 volumio go-librespot[15778]: go-librespot daemon starting... Mar 25 06:20:01 volumio go-librespot[15779]: time="2026-03-25T06:20:01+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:01 volumio go-librespot[15779]: time="2026-03-25T06:20:01+01:00" level=debug msg="app state loaded" Mar 25 06:20:01 volumio go-librespot[15779]: time="2026-03-25T06:20:01+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:01 volumio go-librespot[15779]: time="2026-03-25T06:20:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:01 volumio go-librespot[15779]: time="2026-03-25T06:20:01+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:04 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:04 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13267. Mar 25 06:20:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:04 volumio go-librespot[15787]: go-librespot daemon starting... Mar 25 06:20:04 volumio go-librespot[15788]: time="2026-03-25T06:20:04+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:04 volumio go-librespot[15788]: time="2026-03-25T06:20:04+01:00" level=debug msg="app state loaded" Mar 25 06:20:04 volumio go-librespot[15788]: time="2026-03-25T06:20:04+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:05 volumio go-librespot[15788]: time="2026-03-25T06:20:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:05 volumio go-librespot[15788]: time="2026-03-25T06:20:05+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:07 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:07 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13268. Mar 25 06:20:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:08 volumio go-librespot[15810]: go-librespot daemon starting... Mar 25 06:20:08 volumio go-librespot[15811]: time="2026-03-25T06:20:08+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:08 volumio go-librespot[15811]: time="2026-03-25T06:20:08+01:00" level=debug msg="app state loaded" Mar 25 06:20:08 volumio go-librespot[15811]: time="2026-03-25T06:20:08+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:08 volumio go-librespot[15811]: time="2026-03-25T06:20:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:08 volumio go-librespot[15811]: time="2026-03-25T06:20:08+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:10 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:10 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13269. Mar 25 06:20:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:11 volumio go-librespot[15820]: go-librespot daemon starting... Mar 25 06:20:11 volumio go-librespot[15821]: time="2026-03-25T06:20:11+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:11 volumio go-librespot[15821]: time="2026-03-25T06:20:11+01:00" level=debug msg="app state loaded" Mar 25 06:20:11 volumio go-librespot[15821]: time="2026-03-25T06:20:11+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:11 volumio go-librespot[15821]: time="2026-03-25T06:20:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:11 volumio go-librespot[15821]: time="2026-03-25T06:20:11+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:13 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:13 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13270. Mar 25 06:20:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:14 volumio go-librespot[15831]: go-librespot daemon starting... Mar 25 06:20:14 volumio go-librespot[15832]: time="2026-03-25T06:20:14+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:14 volumio go-librespot[15832]: time="2026-03-25T06:20:14+01:00" level=debug msg="app state loaded" Mar 25 06:20:14 volumio go-librespot[15832]: time="2026-03-25T06:20:14+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:14 volumio go-librespot[15832]: time="2026-03-25T06:20:14+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:14 volumio go-librespot[15832]: time="2026-03-25T06:20:14+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:16 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:16 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13271. Mar 25 06:20:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:17 volumio go-librespot[15854]: go-librespot daemon starting... Mar 25 06:20:18 volumio go-librespot[15855]: time="2026-03-25T06:20:18+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:18 volumio go-librespot[15855]: time="2026-03-25T06:20:18+01:00" level=debug msg="app state loaded" Mar 25 06:20:18 volumio go-librespot[15855]: time="2026-03-25T06:20:18+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:18 volumio go-librespot[15855]: time="2026-03-25T06:20:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:18 volumio go-librespot[15855]: time="2026-03-25T06:20:18+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:19 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:19 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:20 volumio volumio5-onboarding[15722]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Mar 25 06:20:20 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:20 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 25 06:20:20 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1423. Mar 25 06:20:20 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 25 06:20:20 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 25 06:20:20 volumio volumio5-onboarding[15864]: time=2026-03-25T06:20:20.998+01:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Mar 25 06:20:21 volumio volumio[19326]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Mar 25 06:20:21 volumio volumio[19326]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Mar 25 06:20:21 volumio volumio[19326]: info: Received Get System Info Mar 25 06:20:21 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 06:20:21 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 06:20:21 volumio volumio[19326]: info: Discovery: Getting this device information Mar 25 06:20:21 volumio volumio[19326]: info: CoreCommandRouter::volumioGetState Mar 25 06:20:21 volumio volumio[19326]: info: CorePlayQueue::getTrack 0 Mar 25 06:20:21 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 06:20:21 volumio volumio5-onboarding[15864]: time=2026-03-25T06:20:21.034+01:00 level=INFO msg="system info for 17e1d0cf529b0e73cdfb11f77f8fe917" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Mar 25 06:20:21 volumio volumio5-onboarding[15864]: time=2026-03-25T06:20:21.050+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 25 06:20:21 volumio volumio[19326]: info: Received Get System Info Mar 25 06:20:21 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 06:20:21 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 06:20:21 volumio volumio[19326]: info: Discovery: Getting this device information Mar 25 06:20:21 volumio volumio[19326]: info: CoreCommandRouter::volumioGetState Mar 25 06:20:21 volumio volumio[19326]: info: CorePlayQueue::getTrack 0 Mar 25 06:20:21 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 06:20:21 volumio volumio-remote-updater[15732]: Test mode disabled Mar 25 06:20:21 volumio volumio-remote-updater[15732]: Alpha mode disabled Mar 25 06:20:21 volumio volumio-remote-updater[15732]: Alpha legacy test mode disabled Mar 25 06:20:21 volumio volumio-remote-updater[15732]: curl_easy_perform() failed: Couldn't resolve host name Mar 25 06:20:21 volumio volumio-remote-updater[15732]: terminate called after throwing an instance of 'std::invalid_argument' Mar 25 06:20:21 volumio volumio-remote-updater[15732]: what(): parse error - unexpected end of input Mar 25 06:20:21 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Mar 25 06:20:21 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Mar 25 06:20:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13272. Mar 25 06:20:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:21 volumio go-librespot[15874]: go-librespot daemon starting... Mar 25 06:20:21 volumio go-librespot[15875]: time="2026-03-25T06:20:21+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:21 volumio go-librespot[15875]: time="2026-03-25T06:20:21+01:00" level=debug msg="app state loaded" Mar 25 06:20:21 volumio go-librespot[15875]: time="2026-03-25T06:20:21+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:21 volumio go-librespot[15875]: time="2026-03-25T06:20:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:21 volumio go-librespot[15875]: time="2026-03-25T06:20:21+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:21 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1424. Mar 25 06:20:21 volumio systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Mar 25 06:20:21 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Mar 25 06:20:21 volumio volumio-remote-updater[15883]: Volumio OTA Client: 1.7.0 Mar 25 06:20:21 volumio volumio-remote-updater[15883]: Error: No active session Mar 25 06:20:21 volumio volumio-remote-updater[15883]: [2026-03-25 06:20:21] [connect] Successful connection Mar 25 06:20:21 volumio volumio-remote-updater[15883]: [2026-03-25 06:20:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774416021 101 Mar 25 06:20:21 volumio volumio[19326]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Mar 25 06:20:22 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:22 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13273. Mar 25 06:20:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:24 volumio go-librespot[15886]: go-librespot daemon starting... Mar 25 06:20:24 volumio go-librespot[15887]: time="2026-03-25T06:20:24+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:24 volumio go-librespot[15887]: time="2026-03-25T06:20:24+01:00" level=debug msg="app state loaded" Mar 25 06:20:24 volumio go-librespot[15887]: time="2026-03-25T06:20:24+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:24 volumio go-librespot[15887]: time="2026-03-25T06:20:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:24 volumio go-librespot[15887]: time="2026-03-25T06:20:24+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:25 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:25 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13274. Mar 25 06:20:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:27 volumio go-librespot[15895]: go-librespot daemon starting... Mar 25 06:20:27 volumio go-librespot[15896]: time="2026-03-25T06:20:27+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:27 volumio go-librespot[15896]: time="2026-03-25T06:20:27+01:00" level=debug msg="app state loaded" Mar 25 06:20:27 volumio go-librespot[15896]: time="2026-03-25T06:20:27+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:27 volumio go-librespot[15896]: time="2026-03-25T06:20:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:27 volumio go-librespot[15896]: time="2026-03-25T06:20:27+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:28 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:28 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13275. Mar 25 06:20:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:30 volumio go-librespot[15918]: go-librespot daemon starting... Mar 25 06:20:31 volumio go-librespot[15919]: time="2026-03-25T06:20:31+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:31 volumio go-librespot[15919]: time="2026-03-25T06:20:31+01:00" level=debug msg="app state loaded" Mar 25 06:20:31 volumio go-librespot[15919]: time="2026-03-25T06:20:31+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:31 volumio go-librespot[15919]: time="2026-03-25T06:20:31+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:31 volumio go-librespot[15919]: time="2026-03-25T06:20:31+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:31 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:31 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13276. Mar 25 06:20:34 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:34 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:34 volumio go-librespot[15927]: go-librespot daemon starting... Mar 25 06:20:34 volumio go-librespot[15928]: time="2026-03-25T06:20:34+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:34 volumio go-librespot[15928]: time="2026-03-25T06:20:34+01:00" level=debug msg="app state loaded" Mar 25 06:20:34 volumio go-librespot[15928]: time="2026-03-25T06:20:34+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:34 volumio go-librespot[15928]: time="2026-03-25T06:20:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:34 volumio go-librespot[15928]: time="2026-03-25T06:20:34+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:37 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:37 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13277. Mar 25 06:20:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:37 volumio go-librespot[15936]: go-librespot daemon starting... Mar 25 06:20:37 volumio go-librespot[15937]: time="2026-03-25T06:20:37+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:37 volumio go-librespot[15937]: time="2026-03-25T06:20:37+01:00" level=debug msg="app state loaded" Mar 25 06:20:37 volumio go-librespot[15937]: time="2026-03-25T06:20:37+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:37 volumio go-librespot[15937]: time="2026-03-25T06:20:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:37 volumio go-librespot[15937]: time="2026-03-25T06:20:37+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:40 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:40 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13278. Mar 25 06:20:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:40 volumio go-librespot[15960]: go-librespot daemon starting... Mar 25 06:20:40 volumio go-librespot[15961]: time="2026-03-25T06:20:40+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:40 volumio go-librespot[15961]: time="2026-03-25T06:20:40+01:00" level=debug msg="app state loaded" Mar 25 06:20:40 volumio go-librespot[15961]: time="2026-03-25T06:20:40+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:40 volumio go-librespot[15961]: time="2026-03-25T06:20:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:40 volumio go-librespot[15961]: time="2026-03-25T06:20:40+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:43 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:43 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13279. Mar 25 06:20:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:43 volumio go-librespot[15970]: go-librespot daemon starting... Mar 25 06:20:44 volumio go-librespot[15971]: time="2026-03-25T06:20:44+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:44 volumio go-librespot[15971]: time="2026-03-25T06:20:44+01:00" level=debug msg="app state loaded" Mar 25 06:20:44 volumio go-librespot[15971]: time="2026-03-25T06:20:44+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:44 volumio go-librespot[15971]: time="2026-03-25T06:20:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:44 volumio go-librespot[15971]: time="2026-03-25T06:20:44+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:46 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:46 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13280. Mar 25 06:20:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:47 volumio go-librespot[15979]: go-librespot daemon starting... Mar 25 06:20:47 volumio go-librespot[15980]: time="2026-03-25T06:20:47+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:47 volumio go-librespot[15980]: time="2026-03-25T06:20:47+01:00" level=debug msg="app state loaded" Mar 25 06:20:47 volumio go-librespot[15980]: time="2026-03-25T06:20:47+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:47 volumio go-librespot[15980]: time="2026-03-25T06:20:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:47 volumio go-librespot[15980]: time="2026-03-25T06:20:47+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:49 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:49 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13281. Mar 25 06:20:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:50 volumio go-librespot[16002]: go-librespot daemon starting... Mar 25 06:20:50 volumio go-librespot[16003]: time="2026-03-25T06:20:50+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:50 volumio go-librespot[16003]: time="2026-03-25T06:20:50+01:00" level=debug msg="app state loaded" Mar 25 06:20:50 volumio go-librespot[16003]: time="2026-03-25T06:20:50+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:50 volumio go-librespot[16003]: time="2026-03-25T06:20:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:50 volumio go-librespot[16003]: time="2026-03-25T06:20:50+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:51 volumio volumio5-onboarding[15864]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Mar 25 06:20:51 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:51 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 25 06:20:51 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1424. Mar 25 06:20:51 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 25 06:20:51 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 25 06:20:51 volumio volumio5-onboarding[16011]: time=2026-03-25T06:20:51.238+01:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Mar 25 06:20:51 volumio volumio[19326]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Mar 25 06:20:51 volumio volumio[19326]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Mar 25 06:20:51 volumio volumio[19326]: info: Received Get System Info Mar 25 06:20:51 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 06:20:51 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 06:20:51 volumio volumio[19326]: info: Discovery: Getting this device information Mar 25 06:20:51 volumio volumio[19326]: info: CoreCommandRouter::volumioGetState Mar 25 06:20:51 volumio volumio[19326]: info: CorePlayQueue::getTrack 0 Mar 25 06:20:51 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 06:20:51 volumio volumio5-onboarding[16011]: time=2026-03-25T06:20:51.274+01:00 level=INFO msg="system info for 17e1d0cf529b0e73cdfb11f77f8fe917" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Mar 25 06:20:51 volumio volumio5-onboarding[16011]: time=2026-03-25T06:20:51.288+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 25 06:20:51 volumio volumio[19326]: info: Received Get System Info Mar 25 06:20:51 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 06:20:51 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 06:20:51 volumio volumio[19326]: info: Discovery: Getting this device information Mar 25 06:20:51 volumio volumio[19326]: info: CoreCommandRouter::volumioGetState Mar 25 06:20:51 volumio volumio[19326]: info: CorePlayQueue::getTrack 0 Mar 25 06:20:51 volumio volumio[19326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 06:20:51 volumio volumio-remote-updater[15883]: Test mode disabled Mar 25 06:20:51 volumio volumio-remote-updater[15883]: Alpha mode disabled Mar 25 06:20:51 volumio volumio-remote-updater[15883]: Alpha legacy test mode disabled Mar 25 06:20:51 volumio volumio-remote-updater[15883]: curl_easy_perform() failed: Couldn't resolve host name Mar 25 06:20:51 volumio volumio-remote-updater[15883]: terminate called after throwing an instance of 'std::invalid_argument' Mar 25 06:20:51 volumio volumio-remote-updater[15883]: what(): parse error - unexpected end of input Mar 25 06:20:51 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Mar 25 06:20:51 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Mar 25 06:20:51 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1425. Mar 25 06:20:51 volumio systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Mar 25 06:20:51 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Mar 25 06:20:51 volumio volumio-remote-updater[16022]: Volumio OTA Client: 1.7.0 Mar 25 06:20:51 volumio volumio-remote-updater[16022]: Error: No active session Mar 25 06:20:51 volumio volumio-remote-updater[16022]: [2026-03-25 06:20:51] [connect] Successful connection Mar 25 06:20:51 volumio volumio-remote-updater[16022]: [2026-03-25 06:20:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774416051 101 Mar 25 06:20:51 volumio volumio[19326]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Mar 25 06:20:52 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:52 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13282. Mar 25 06:20:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:53 volumio go-librespot[16025]: go-librespot daemon starting... Mar 25 06:20:53 volumio go-librespot[16026]: time="2026-03-25T06:20:53+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:53 volumio go-librespot[16026]: time="2026-03-25T06:20:53+01:00" level=debug msg="app state loaded" Mar 25 06:20:53 volumio go-librespot[16026]: time="2026-03-25T06:20:53+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:53 volumio go-librespot[16026]: time="2026-03-25T06:20:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:53 volumio go-librespot[16026]: time="2026-03-25T06:20:53+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:55 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:55 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:20:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13283. Mar 25 06:20:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:20:56 volumio go-librespot[16034]: go-librespot daemon starting... Mar 25 06:20:56 volumio go-librespot[16035]: time="2026-03-25T06:20:56+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:20:56 volumio go-librespot[16035]: time="2026-03-25T06:20:56+01:00" level=debug msg="app state loaded" Mar 25 06:20:56 volumio go-librespot[16035]: time="2026-03-25T06:20:56+01:00" level=debug msg="stored credentials not found" Mar 25 06:20:57 volumio go-librespot[16035]: time="2026-03-25T06:20:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:20:57 volumio go-librespot[16035]: time="2026-03-25T06:20:57+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:20:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:20:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:20:58 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:20:58 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:21:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13284. Mar 25 06:21:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:21:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:21:00 volumio go-librespot[16058]: go-librespot daemon starting... Mar 25 06:21:00 volumio go-librespot[16059]: time="2026-03-25T06:21:00+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:21:00 volumio go-librespot[16059]: time="2026-03-25T06:21:00+01:00" level=debug msg="app state loaded" Mar 25 06:21:00 volumio go-librespot[16059]: time="2026-03-25T06:21:00+01:00" level=debug msg="stored credentials not found" Mar 25 06:21:00 volumio go-librespot[16059]: time="2026-03-25T06:21:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:21:00 volumio go-librespot[16059]: time="2026-03-25T06:21:00+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 25 06:21:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:21:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:21:01 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:21:01 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:21:02 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 25 06:21:02 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 25 06:21:02 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 25 06:21:02 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 25 06:21:02 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 25 06:21:02 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 25 06:21:02 volumio welcome[16084]: Resolved ip:[1] 192.168.0.232 Mar 25 06:21:02 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 25 06:21:02 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 25 06:21:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13285. Mar 25 06:21:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:21:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:21:03 volumio go-librespot[16096]: go-librespot daemon starting... Mar 25 06:21:03 volumio go-librespot[16097]: time="2026-03-25T06:21:03+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:21:03 volumio go-librespot[16097]: time="2026-03-25T06:21:03+01:00" level=debug msg="app state loaded" Mar 25 06:21:03 volumio go-librespot[16097]: time="2026-03-25T06:21:03+01:00" level=debug msg="stored credentials not found" Mar 25 06:21:03 volumio go-librespot[16097]: time="2026-03-25T06:21:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:21:03 volumio go-librespot[16097]: time="2026-03-25T06:21:03+01:00" level=fatal msg="failed running zeroconf" 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 2026-03-25T06:21:03+01:00 is before 2026-07-09T00:00:00Z" Mar 25 06:21:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:21:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:21:04 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:21:04 volumio volumio[19326]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:21:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13286. Mar 25 06:21:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:21:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:21:07 volumio go-librespot[16107]: go-librespot daemon starting... Mar 25 06:21:07 volumio go-librespot[16108]: time="2026-03-25T06:21:07+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:21:07 volumio go-librespot[16108]: time="2026-03-25T06:21:07+01:00" level=debug msg="app state loaded" Mar 25 06:21:07 volumio go-librespot[16108]: time="2026-03-25T06:21:07+01:00" level=debug msg="stored credentials not found" Mar 25 06:21:07 volumio go-librespot[16108]: time="2026-03-25T06:21:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:21:07 volumio volumio[19326]: info: Initializing connection to go-librespot Websocket Mar 25 06:21:07 volumio go-librespot[16108]: time="2026-03-25T06:21:07+01:00" level=debug msg="new websocket client" Mar 25 06:21:07 volumio volumio[19326]: info: Connection to go-librespot Websocket established Mar 25 06:21:07 volumio go-librespot[16108]: time="2026-03-25T06:21:07+01:00" level=fatal msg="failed running zeroconf" 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 2026-03-25T06:21:07+01:00 is before 2026-07-09T00:00:00Z" Mar 25 06:21:07 volumio volumio[19326]: info: Connection to go-librespot Websocket closed Mar 25 06:21:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:21:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:21:10 volumio volumio[19326]: info: Getting Spotify volume Mar 25 06:21:10 volumio volumio[19326]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 06:21:10 volumio volumio[19326]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 25 06:21:10 volumio volumio[19326]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 25 06:21:10 volumio volumio[19326]: errno: -111, Mar 25 06:21:10 volumio volumio[19326]: code: 'ECONNREFUSED', Mar 25 06:21:10 volumio volumio[19326]: syscall: 'connect', Mar 25 06:21:10 volumio volumio[19326]: address: '127.0.0.1', Mar 25 06:21:10 volumio volumio[19326]: port: 9879, Mar 25 06:21:10 volumio volumio[19326]: response: undefined Mar 25 06:21:10 volumio volumio[19326]: } Mar 25 06:21:10 volumio volumio[19326]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 06:21:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13287. Mar 25 06:21:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:21:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 25 06:21:10 volumio go-librespot[16143]: go-librespot daemon starting... Mar 25 06:21:10 volumio go-librespot[16144]: time="2026-03-25T06:21:10+01:00" level=info msg="running go-librespot 0.7.1" Mar 25 06:21:10 volumio go-librespot[16144]: time="2026-03-25T06:21:10+01:00" level=debug msg="app state loaded" Mar 25 06:21:10 volumio go-librespot[16144]: time="2026-03-25T06:21:10+01:00" level=debug msg="stored credentials not found" Mar 25 06:21:10 volumio go-librespot[16144]: time="2026-03-25T06:21:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 25 06:21:10 volumio go-librespot[16144]: time="2026-03-25T06:21:10+01:00" level=fatal msg="failed running zeroconf" 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 2026-03-25T06:21:10+01:00 is before 2026-07-09T00:00:00Z" Mar 25 06:21:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 25 06:21:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 25 06:21:11 volumio sudo[16155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 06:20' Mar 25 06:21:11 volumio sudo[16155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"