Mar 28 04:41:00 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1079. Mar 28 04:41:00 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:00 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:00 volumio-cristi go-librespot[17063]: go-librespot daemon starting... Mar 28 04:41:00 volumio-cristi go-librespot[17064]: time="2026-03-28T04:41:00+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:00 volumio-cristi go-librespot[17064]: time="2026-03-28T04:41:00+02:00" level=debug msg="app state loaded" Mar 28 04:41:00 volumio-cristi go-librespot[17064]: time="2026-03-28T04:41:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:00 volumio-cristi go-librespot[17064]: time="2026-03-28T04:41:00+02: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 28 04:41:00 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:00 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:02 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:02 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:03 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1080. Mar 28 04:41:03 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:03 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:03 volumio-cristi go-librespot[17072]: go-librespot daemon starting... Mar 28 04:41:03 volumio-cristi go-librespot[17073]: time="2026-03-28T04:41:03+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:03 volumio-cristi go-librespot[17073]: time="2026-03-28T04:41:03+02:00" level=debug msg="app state loaded" Mar 28 04:41:03 volumio-cristi go-librespot[17073]: time="2026-03-28T04:41:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:03 volumio-cristi go-librespot[17073]: time="2026-03-28T04:41:03+02: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 28 04:41:03 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:03 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:05 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:06 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:06 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1081. Mar 28 04:41:06 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:06 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:06 volumio-cristi go-librespot[17095]: go-librespot daemon starting... Mar 28 04:41:06 volumio-cristi go-librespot[17096]: time="2026-03-28T04:41:06+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:06 volumio-cristi go-librespot[17096]: time="2026-03-28T04:41:06+02:00" level=debug msg="app state loaded" Mar 28 04:41:06 volumio-cristi go-librespot[17096]: time="2026-03-28T04:41:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:06 volumio-cristi go-librespot[17096]: time="2026-03-28T04:41:06+02: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 28 04:41:06 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:06 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:09 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:09 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:10 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1082. Mar 28 04:41:10 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:10 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:10 volumio-cristi go-librespot[17104]: go-librespot daemon starting... Mar 28 04:41:10 volumio-cristi go-librespot[17105]: time="2026-03-28T04:41:10+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:10 volumio-cristi go-librespot[17105]: time="2026-03-28T04:41:10+02:00" level=debug msg="app state loaded" Mar 28 04:41:10 volumio-cristi go-librespot[17105]: time="2026-03-28T04:41:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:10 volumio-cristi go-librespot[17105]: time="2026-03-28T04:41:10+02: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 28 04:41:10 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:10 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:12 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:12 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:13 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1083. Mar 28 04:41:13 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:13 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:13 volumio-cristi go-librespot[17113]: go-librespot daemon starting... Mar 28 04:41:13 volumio-cristi go-librespot[17114]: time="2026-03-28T04:41:13+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:13 volumio-cristi go-librespot[17114]: time="2026-03-28T04:41:13+02:00" level=debug msg="app state loaded" Mar 28 04:41:13 volumio-cristi go-librespot[17114]: time="2026-03-28T04:41:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:13 volumio-cristi go-librespot[17114]: time="2026-03-28T04:41:13+02: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 28 04:41:13 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:13 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:15 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:15 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:16 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1084. Mar 28 04:41:16 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:16 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:16 volumio-cristi go-librespot[17122]: go-librespot daemon starting... Mar 28 04:41:16 volumio-cristi go-librespot[17136]: time="2026-03-28T04:41:16+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:16 volumio-cristi go-librespot[17136]: time="2026-03-28T04:41:16+02:00" level=debug msg="app state loaded" Mar 28 04:41:16 volumio-cristi go-librespot[17136]: time="2026-03-28T04:41:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:16 volumio-cristi go-librespot[17136]: time="2026-03-28T04:41:16+02: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 28 04:41:16 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:16 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:18 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:18 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:19 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1085. Mar 28 04:41:19 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:19 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:19 volumio-cristi go-librespot[17146]: go-librespot daemon starting... Mar 28 04:41:19 volumio-cristi go-librespot[17147]: time="2026-03-28T04:41:19+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:19 volumio-cristi go-librespot[17147]: time="2026-03-28T04:41:19+02:00" level=debug msg="app state loaded" Mar 28 04:41:19 volumio-cristi go-librespot[17147]: time="2026-03-28T04:41:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:19 volumio-cristi go-librespot[17147]: time="2026-03-28T04:41:19+02: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 28 04:41:19 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:19 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:21 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:21 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:23 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1086. Mar 28 04:41:23 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:23 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:23 volumio-cristi go-librespot[17155]: go-librespot daemon starting... Mar 28 04:41:23 volumio-cristi go-librespot[17156]: time="2026-03-28T04:41:23+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:23 volumio-cristi go-librespot[17156]: time="2026-03-28T04:41:23+02:00" level=debug msg="app state loaded" Mar 28 04:41:23 volumio-cristi go-librespot[17156]: time="2026-03-28T04:41:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:23 volumio-cristi go-librespot[17156]: time="2026-03-28T04:41:23+02: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 28 04:41:23 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:23 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:24 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:24 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:26 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1087. Mar 28 04:41:26 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:26 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:26 volumio-cristi go-librespot[17164]: go-librespot daemon starting... Mar 28 04:41:26 volumio-cristi go-librespot[17165]: time="2026-03-28T04:41:26+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:26 volumio-cristi go-librespot[17165]: time="2026-03-28T04:41:26+02:00" level=debug msg="app state loaded" Mar 28 04:41:26 volumio-cristi go-librespot[17165]: time="2026-03-28T04:41:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:26 volumio-cristi go-librespot[17165]: time="2026-03-28T04:41:26+02: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 28 04:41:26 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:26 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:27 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:27 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:29 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1088. Mar 28 04:41:29 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:29 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:29 volumio-cristi go-librespot[17187]: go-librespot daemon starting... Mar 28 04:41:29 volumio-cristi go-librespot[17188]: time="2026-03-28T04:41:29+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:29 volumio-cristi go-librespot[17188]: time="2026-03-28T04:41:29+02:00" level=debug msg="app state loaded" Mar 28 04:41:29 volumio-cristi go-librespot[17188]: time="2026-03-28T04:41:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:29 volumio-cristi go-librespot[17188]: time="2026-03-28T04:41:29+02: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 28 04:41:29 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:29 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:30 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:30 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:32 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1089. Mar 28 04:41:32 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:32 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:32 volumio-cristi go-librespot[17196]: go-librespot daemon starting... Mar 28 04:41:32 volumio-cristi go-librespot[17197]: time="2026-03-28T04:41:32+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:32 volumio-cristi go-librespot[17197]: time="2026-03-28T04:41:32+02:00" level=debug msg="app state loaded" Mar 28 04:41:32 volumio-cristi go-librespot[17197]: time="2026-03-28T04:41:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:32 volumio-cristi go-librespot[17197]: time="2026-03-28T04:41:32+02: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 28 04:41:32 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:32 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:33 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:33 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:36 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:36 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:36 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1090. Mar 28 04:41:36 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:36 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:36 volumio-cristi go-librespot[17205]: go-librespot daemon starting... Mar 28 04:41:36 volumio-cristi go-librespot[17206]: time="2026-03-28T04:41:36+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:36 volumio-cristi go-librespot[17206]: time="2026-03-28T04:41:36+02:00" level=debug msg="app state loaded" Mar 28 04:41:36 volumio-cristi go-librespot[17206]: time="2026-03-28T04:41:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:36 volumio-cristi go-librespot[17206]: time="2026-03-28T04:41:36+02: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 28 04:41:36 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:36 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:39 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:39 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:39 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1091. Mar 28 04:41:39 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:39 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:39 volumio-cristi go-librespot[17228]: go-librespot daemon starting... Mar 28 04:41:39 volumio-cristi go-librespot[17229]: time="2026-03-28T04:41:39+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:39 volumio-cristi go-librespot[17229]: time="2026-03-28T04:41:39+02:00" level=debug msg="app state loaded" Mar 28 04:41:39 volumio-cristi go-librespot[17229]: time="2026-03-28T04:41:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:39 volumio-cristi go-librespot[17229]: time="2026-03-28T04:41:39+02: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 28 04:41:39 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:39 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:42 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:42 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:42 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1092. Mar 28 04:41:42 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:42 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:42 volumio-cristi go-librespot[17238]: go-librespot daemon starting... Mar 28 04:41:42 volumio-cristi go-librespot[17239]: time="2026-03-28T04:41:42+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:42 volumio-cristi go-librespot[17239]: time="2026-03-28T04:41:42+02:00" level=debug msg="app state loaded" Mar 28 04:41:42 volumio-cristi go-librespot[17239]: time="2026-03-28T04:41:42+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:42 volumio-cristi go-librespot[17239]: time="2026-03-28T04:41:42+02: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 28 04:41:42 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:42 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:45 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:45 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:45 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1093. Mar 28 04:41:45 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:45 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:45 volumio-cristi go-librespot[17248]: go-librespot daemon starting... Mar 28 04:41:45 volumio-cristi go-librespot[17249]: time="2026-03-28T04:41:45+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:45 volumio-cristi go-librespot[17249]: time="2026-03-28T04:41:45+02:00" level=debug msg="app state loaded" Mar 28 04:41:45 volumio-cristi go-librespot[17249]: time="2026-03-28T04:41:45+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:45 volumio-cristi go-librespot[17249]: time="2026-03-28T04:41:45+02: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 28 04:41:45 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:45 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:48 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:48 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:49 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1094. Mar 28 04:41:49 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:49 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:49 volumio-cristi go-librespot[17272]: go-librespot daemon starting... Mar 28 04:41:49 volumio-cristi go-librespot[17273]: time="2026-03-28T04:41:49+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:49 volumio-cristi go-librespot[17273]: time="2026-03-28T04:41:49+02:00" level=debug msg="app state loaded" Mar 28 04:41:49 volumio-cristi go-librespot[17273]: time="2026-03-28T04:41:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:49 volumio-cristi go-librespot[17273]: time="2026-03-28T04:41:49+02: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 28 04:41:49 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:49 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:51 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:51 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:52 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1095. Mar 28 04:41:52 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:52 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:52 volumio-cristi go-librespot[17281]: go-librespot daemon starting... Mar 28 04:41:52 volumio-cristi go-librespot[17282]: time="2026-03-28T04:41:52+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:52 volumio-cristi go-librespot[17282]: time="2026-03-28T04:41:52+02:00" level=debug msg="app state loaded" Mar 28 04:41:52 volumio-cristi go-librespot[17282]: time="2026-03-28T04:41:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:52 volumio-cristi go-librespot[17282]: time="2026-03-28T04:41:52+02: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 28 04:41:52 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:52 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:54 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:54 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:55 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1096. Mar 28 04:41:55 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:55 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:55 volumio-cristi go-librespot[17290]: go-librespot daemon starting... Mar 28 04:41:55 volumio-cristi go-librespot[17291]: time="2026-03-28T04:41:55+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:55 volumio-cristi go-librespot[17291]: time="2026-03-28T04:41:55+02:00" level=debug msg="app state loaded" Mar 28 04:41:55 volumio-cristi go-librespot[17291]: time="2026-03-28T04:41:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:55 volumio-cristi go-librespot[17291]: time="2026-03-28T04:41:55+02: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 28 04:41:55 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:55 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:55 volumio-cristi dhcpcd[787]: eth0: carrier acquired Mar 28 04:41:55 volumio-cristi kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Mar 28 04:41:56 volumio-cristi dhcpcd[787]: eth0: IAID eb:ae:94:6a Mar 28 04:41:56 volumio-cristi dhcpcd[787]: eth0: soliciting an IPv6 router Mar 28 04:41:56 volumio-cristi volumio[1130]: info: Received Get System Info Mar 28 04:41:56 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 04:41:56 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 04:41:56 volumio-cristi volumio[1130]: info: Discovery: Getting this device information Mar 28 04:41:56 volumio-cristi volumio[1130]: info: CoreCommandRouter::volumioGetState Mar 28 04:41:56 volumio-cristi volumio[1130]: info: CorePlayQueue::getTrack 0 Mar 28 04:41:56 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 04:41:56 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 04:41:56 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 04:41:56 volumio-cristi ifplugd(eth0)[907]: Link beat detected. Mar 28 04:41:56 volumio-cristi ifplugd(eth0)[907]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Mar 28 04:41:56 volumio-cristi ifplugd(eth0)[907]: client: sending commands to dhcpcd process Mar 28 04:41:56 volumio-cristi dhcpcd[787]: control_free: No such file or directory Mar 28 04:41:56 volumio-cristi dhcpcd[787]: ps_ctl_dispatch: cannot handle another client Mar 28 04:41:57 volumio-cristi ifplugd(eth0)[907]: Program executed successfully. Mar 28 04:41:57 volumio-cristi dhcpcd[787]: eth0: soliciting a DHCP lease Mar 28 04:41:57 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:41:57 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:41:57 volumio-cristi volumio5-onboarding[1317]: time=2026-03-28T04:41:57.394+02:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 28 04:41:57 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: === SNM TRANSITION === Mar 28 04:41:57 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Mar 28 04:41:57 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: New ethernet state: connected Mar 28 04:41:57 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: Single Network Mode: enabled Mar 28 04:41:57 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: First start: no Mar 28 04:41:57 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Mar 28 04:41:57 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: === END TRANSITION === Mar 28 04:41:57 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Mar 28 04:41:57 volumio-cristi sudo[17365]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Mar 28 04:41:57 volumio-cristi sudo[17365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 04:41:57 volumio-cristi dhcpcd[17366]: dhcpcd not running Mar 28 04:41:57 volumio-cristi sudo[17365]: pam_unix(sudo:session): session closed for user root Mar 28 04:41:57 volumio-cristi wireless.js[716]: dhcpcd not running Mar 28 04:41:57 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Mar 28 04:41:57 volumio-cristi systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Mar 28 04:41:57 volumio-cristi dnsmasq[16063]: exiting on receipt of SIGTERM Mar 28 04:41:57 volumio-cristi systemd[1]: dnsmasq.service: Deactivated successfully. Mar 28 04:41:57 volumio-cristi systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Mar 28 04:41:57 volumio-cristi systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Mar 28 04:41:58 volumio-cristi dhcpcd[787]: wlan0: carrier lost Mar 28 04:41:58 volumio-cristi systemd[1]: hostapd.service: Deactivated successfully. Mar 28 04:41:58 volumio-cristi systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Mar 28 04:41:58 volumio-cristi sudo[17390]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 28 04:41:58 volumio-cristi sudo[17390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 04:41:58 volumio-cristi avahi-daemon[696]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 28 04:41:58 volumio-cristi avahi-daemon[696]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 28 04:41:58 volumio-cristi avahi-daemon[696]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 28 04:41:58 volumio-cristi sudo[17390]: pam_unix(sudo:session): session closed for user root Mar 28 04:41:58 volumio-cristi volumio[1130]: info: Discovery: A device disappeared from network Mar 28 04:41:58 volumio-cristi sudo[17393]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 28 04:41:58 volumio-cristi sudo[17393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 04:41:58 volumio-cristi systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 28 04:41:58 volumio-cristi systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Mar 28 04:41:58 volumio-cristi systemd[1]: welcome.service: Deactivated successfully. Mar 28 04:41:58 volumio-cristi systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 28 04:41:58 volumio-cristi systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 28 04:41:58 volumio-cristi systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 28 04:41:58 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1097. Mar 28 04:41:58 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:58 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:41:58 volumio-cristi go-librespot[17397]: go-librespot daemon starting... Mar 28 04:41:58 volumio-cristi go-librespot[17398]: time="2026-03-28T04:41:58+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:41:58 volumio-cristi go-librespot[17398]: time="2026-03-28T04:41:58+02:00" level=debug msg="app state loaded" Mar 28 04:41:58 volumio-cristi go-librespot[17398]: time="2026-03-28T04:41:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:41:58 volumio-cristi go-librespot[17398]: time="2026-03-28T04:41:58+02: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 28 04:41:58 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:41:58 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:41:59 volumio-cristi sudo[17393]: pam_unix(sudo:session): session closed for user root Mar 28 04:41:59 volumio-cristi welcome[17395]: Resolved ip:[0] Mar 28 04:41:59 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: Cleaning previous... Mar 28 04:41:59 volumio-cristi volumio[1130]: info: Received Get System Info Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 04:41:59 volumio-cristi volumio[1130]: info: Discovery: Getting this device information Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::volumioGetState Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CorePlayQueue::getTrack 0 Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 04:41:59 volumio-cristi systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 04:41:59 volumio-cristi systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 28 04:41:59 volumio-cristi sudo[17409]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 28 04:41:59 volumio-cristi sudo[17409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 04:41:59 volumio-cristi sudo[17409]: pam_unix(sudo:session): session closed for user root Mar 28 04:41:59 volumio-cristi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 28 04:41:59 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Mar 28 04:41:59 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 2ms Mar 28 04:41:59 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:fb:c1:3f) Mar 28 04:41:59 volumio-cristi sudo[17417]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 28 04:41:59 volumio-cristi sudo[17417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 04:41:59 volumio-cristi sudo[17417]: pam_unix(sudo:session): session closed for user root Mar 28 04:41:59 volumio-cristi sudo[17425]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 28 04:41:59 volumio-cristi sudo[17425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 04:41:59 volumio-cristi volumio[1130]: info: Received Get System Info Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 04:41:59 volumio-cristi volumio[1130]: info: Discovery: Getting this device information Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::volumioGetState Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CorePlayQueue::getTrack 0 Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 04:41:59 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 04:41:59 volumio-cristi sudo[17425]: pam_unix(sudo:session): session closed for user root Mar 28 04:41:59 volumio-cristi wireless.js[716]: WIRELESS.JS - INFO: Regdomain already correct: FR Mar 28 04:42:00 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:42:00 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:42:00 volumio-cristi volumio[1130]: info: Received Get System Info Mar 28 04:42:00 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 04:42:00 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 04:42:00 volumio-cristi volumio[1130]: info: Discovery: Getting this device information Mar 28 04:42:00 volumio-cristi volumio[1130]: info: CoreCommandRouter::volumioGetState Mar 28 04:42:00 volumio-cristi volumio[1130]: info: CorePlayQueue::getTrack 0 Mar 28 04:42:00 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 04:42:00 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 04:42:00 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 04:42:01 volumio-cristi ntpd[886]: IO: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=288 secs Mar 28 04:42:02 volumio-cristi volumio5-onboarding[1317]: time=2026-03-28T04:42:02.022+02:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 28 04:42:02 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1098. Mar 28 04:42:02 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:42:02 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:42:02 volumio-cristi go-librespot[17430]: go-librespot daemon starting... Mar 28 04:42:02 volumio-cristi go-librespot[17431]: time="2026-03-28T04:42:02+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:42:02 volumio-cristi go-librespot[17431]: time="2026-03-28T04:42:02+02:00" level=debug msg="app state loaded" Mar 28 04:42:02 volumio-cristi go-librespot[17431]: time="2026-03-28T04:42:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:42:02 volumio-cristi go-librespot[17431]: time="2026-03-28T04:42:02+02: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 28 04:42:02 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:42:02 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:42:02 volumio-cristi dhcpcd[787]: eth0: offered 192.168.0.222 from 192.168.0.254 Mar 28 04:42:02 volumio-cristi dhcpcd[787]: eth0: probing address 192.168.0.222/24 Mar 28 04:42:03 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:42:03 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:42:05 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1099. Mar 28 04:42:05 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:42:05 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:42:05 volumio-cristi go-librespot[17440]: go-librespot daemon starting... Mar 28 04:42:05 volumio-cristi go-librespot[17441]: time="2026-03-28T04:42:05+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:42:05 volumio-cristi go-librespot[17441]: time="2026-03-28T04:42:05+02:00" level=debug msg="app state loaded" Mar 28 04:42:05 volumio-cristi go-librespot[17441]: time="2026-03-28T04:42:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:42:05 volumio-cristi go-librespot[17441]: time="2026-03-28T04:42:05+02: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 28 04:42:05 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:42:05 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:42:06 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:42:06 volumio-cristi volumio[1130]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 04:42:08 volumio-cristi dhcpcd[787]: eth0: leased 192.168.0.222 for 60 seconds Mar 28 04:42:08 volumio-cristi avahi-daemon[696]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.222. Mar 28 04:42:08 volumio-cristi avahi-daemon[696]: New relevant interface eth0.IPv4 for mDNS. Mar 28 04:42:08 volumio-cristi avahi-daemon[696]: Registering new address record for 192.168.0.222 on eth0.IPv4. Mar 28 04:42:08 volumio-cristi dhcpcd[787]: eth0: adding route to 192.168.0.0/24 Mar 28 04:42:08 volumio-cristi dhcpcd[787]: eth0: adding default route via 192.168.0.254 Mar 28 04:42:08 volumio-cristi systemd[1]: welcome.service: Deactivated successfully. Mar 28 04:42:08 volumio-cristi systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 28 04:42:08 volumio-cristi systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 28 04:42:08 volumio-cristi systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 28 04:42:08 volumio-cristi welcome[17482]: Resolved ip:[1] 192.168.0.222 Mar 28 04:42:08 volumio-cristi systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 28 04:42:08 volumio-cristi systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 28 04:42:08 volumio-cristi volumio[1130]: info: Received Get System Info Mar 28 04:42:08 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 04:42:08 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 04:42:08 volumio-cristi volumio[1130]: info: Discovery: Getting this device information Mar 28 04:42:08 volumio-cristi volumio[1130]: info: CoreCommandRouter::volumioGetState Mar 28 04:42:08 volumio-cristi volumio[1130]: info: CorePlayQueue::getTrack 0 Mar 28 04:42:08 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 04:42:08 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 28 04:42:08 volumio-cristi volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 28 04:42:08 volumio-cristi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1100. Mar 28 04:42:08 volumio-cristi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:42:08 volumio-cristi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 04:42:08 volumio-cristi go-librespot[17489]: go-librespot daemon starting... Mar 28 04:42:08 volumio-cristi go-librespot[17490]: time="2026-03-28T04:42:08+02:00" level=info msg="running go-librespot 0.6.2" Mar 28 04:42:08 volumio-cristi go-librespot[17490]: time="2026-03-28T04:42:08+02:00" level=debug msg="app state loaded" Mar 28 04:42:08 volumio-cristi go-librespot[17490]: time="2026-03-28T04:42:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 04:42:09 volumio-cristi volumio[1130]: info: Initializing connection to go-librespot Websocket Mar 28 04:42:09 volumio-cristi go-librespot[17490]: time="2026-03-28T04:42:09+02:00" level=debug msg="new websocket client" Mar 28 04:42:09 volumio-cristi volumio[1130]: info: Connection to go-librespot Websocket established Mar 28 04:42:09 volumio-cristi ntpd[886]: IO: Listen normally on 4 eth0 192.168.0.222:123 Mar 28 04:42:09 volumio-cristi ntpd[886]: IO: new interface(s) found: waking up resolver Mar 28 04:42:09 volumio-cristi ntpd[886]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 28 04:42:09 volumio-cristi volumio5-onboarding[1317]: time=2026-03-28T04:42:09.559+02:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 28 04:42:12 volumio-cristi volumio[1130]: info: Getting Spotify volume Mar 28 04:42:14 volumio-cristi volumio[1130]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 28 04:42:14 volumio-cristi volumio[1130]: info: CoreCommandRouter::volumioGetState Mar 28 04:42:14 volumio-cristi volumio[1130]: info: CorePlayQueue::getTrack 0 Mar 28 04:42:14 volumio-cristi volumio[1130]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 28 04:42:14 volumio-cristi volumio[1130]: SPOTIFY: SPOTIFY VOLUME undefined Mar 28 04:42:14 volumio-cristi volumio[1130]: SPOTIFY: VOLUMIO VOLUME 100 Mar 28 04:42:14 volumio-cristi volumio[1130]: info: Aligning Spotify Volume to Volumio Volume Mar 28 04:42:14 volumio-cristi volumio[1130]: info: CoreCommandRouter::volumioGetState Mar 28 04:42:14 volumio-cristi volumio[1130]: info: CorePlayQueue::getTrack 0 Mar 28 04:42:14 volumio-cristi volumio[1130]: info: Setting Spotify Volume from Volumio: 100 Mar 28 04:42:14 volumio-cristi ntpd[886]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Mar 28 04:42:14 volumio-cristi ntpd[886]: DNS: Pool taking: 45.134.48.236 Mar 28 04:42:14 volumio-cristi ntpd[886]: DNS: Pool taking: 80.96.108.7 Mar 28 04:42:14 volumio-cristi ntpd[886]: DNS: Pool taking: 89.36.93.9 Mar 28 04:42:14 volumio-cristi ntpd[886]: DNS: Pool taking: 217.156.67.138 Mar 28 04:42:14 volumio-cristi ntpd[886]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Mar 28 04:42:15 volumio-cristi ntpd[886]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 28 04:42:15 volumio-cristi volumio[1130]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Mar 28 04:42:15 volumio-cristi volumio[1130]: info: Sending Spotify command with payload to local API: /player/volume Mar 28 04:42:16 volumio-cristi volumio[1130]: info: Volumio Network Manager: Network status updated: 1 Mar 28 04:42:17 volumio-cristi go-librespot[17490]: time="2026-03-28T04:42:17+02: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-28T04:42:16+02:00 is before 2026-07-09T00:00:00Z" Mar 28 04:42:17 volumio-cristi volumio[1130]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Mar 28 04:42:17 volumio-cristi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 04:42:17 volumio-cristi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 04:42:17 volumio-cristi volumio[1130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 04:42:17 volumio-cristi volumio[1130]: Error: socket hang up Mar 28 04:42:17 volumio-cristi volumio[1130]: at connResetException (node:internal/errors:720:14) Mar 28 04:42:17 volumio-cristi volumio[1130]: at Socket.socketOnEnd (node:_http_client:519:23) Mar 28 04:42:17 volumio-cristi volumio[1130]: at Socket.emit (node:events:526:35) Mar 28 04:42:17 volumio-cristi volumio[1130]: at endReadableNT (node:internal/streams/readable:1376:12) Mar 28 04:42:17 volumio-cristi volumio[1130]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Mar 28 04:42:17 volumio-cristi volumio[1130]: code: 'ECONNRESET', Mar 28 04:42:17 volumio-cristi volumio[1130]: response: undefined Mar 28 04:42:17 volumio-cristi volumio[1130]: } Mar 28 04:42:17 volumio-cristi volumio[1130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 04:42:17 volumio-cristi sudo[17529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 04:41' Mar 28 04:42:17 volumio-cristi sudo[17529]: 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"