Sep 29 21:49:01 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 269. Sep 29 21:49:01 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:01 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:01 volumio-pifi go-librespot[4736]: go-librespot daemon starting... Sep 29 21:49:01 volumio-pifi go-librespot[4737]: time="2026-09-29T21:49:01+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:01 volumio-pifi go-librespot[4737]: time="2026-09-29T21:49:01+01:00" level=debug msg="app state loaded" Sep 29 21:49:01 volumio-pifi go-librespot[4737]: time="2026-09-29T21:49:01+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:01 volumio-pifi go-librespot[4737]: time="2026-09-29T21:49:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:01 volumio-pifi go-librespot[4737]: time="2026-09-29T21:49: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" Sep 29 21:49:01 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:01 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:01 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:01 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:04 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:04 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:04 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 270. Sep 29 21:49:04 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:04 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:04 volumio-pifi go-librespot[4743]: go-librespot daemon starting... Sep 29 21:49:04 volumio-pifi go-librespot[4744]: time="2026-09-29T21:49:04+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:04 volumio-pifi go-librespot[4744]: time="2026-09-29T21:49:04+01:00" level=debug msg="app state loaded" Sep 29 21:49:04 volumio-pifi go-librespot[4744]: time="2026-09-29T21:49:04+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:04 volumio-pifi go-librespot[4744]: time="2026-09-29T21:49:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:04 volumio-pifi go-librespot[4744]: time="2026-09-29T21:49:04+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" Sep 29 21:49:04 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:04 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:07 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:07 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:07 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 271. Sep 29 21:49:07 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:08 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:08 volumio-pifi go-librespot[4750]: go-librespot daemon starting... Sep 29 21:49:08 volumio-pifi go-librespot[4765]: time="2026-09-29T21:49:08+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:08 volumio-pifi go-librespot[4765]: time="2026-09-29T21:49:08+01:00" level=debug msg="app state loaded" Sep 29 21:49:08 volumio-pifi go-librespot[4765]: time="2026-09-29T21:49:08+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:08 volumio-pifi go-librespot[4765]: time="2026-09-29T21:49:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:08 volumio-pifi go-librespot[4765]: time="2026-09-29T21:49: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" Sep 29 21:49:08 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:08 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:10 volumio-pifi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 15. Sep 29 21:49:10 volumio-pifi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Sep 29 21:49:10 volumio-pifi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Sep 29 21:49:10 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:10 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:11 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 272. Sep 29 21:49:11 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:11 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:11 volumio-pifi go-librespot[4780]: go-librespot daemon starting... Sep 29 21:49:11 volumio-pifi go-librespot[4781]: time="2026-09-29T21:49:11+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:11 volumio-pifi go-librespot[4781]: time="2026-09-29T21:49:11+01:00" level=debug msg="app state loaded" Sep 29 21:49:11 volumio-pifi go-librespot[4781]: time="2026-09-29T21:49:11+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:11 volumio-pifi go-librespot[4781]: time="2026-09-29T21:49:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:11 volumio-pifi go-librespot[4781]: time="2026-09-29T21:49: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" Sep 29 21:49:11 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:11 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:13 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:13 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:14 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 273. Sep 29 21:49:14 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:14 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:14 volumio-pifi go-librespot[4787]: go-librespot daemon starting... Sep 29 21:49:14 volumio-pifi go-librespot[4788]: time="2026-09-29T21:49:14+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:14 volumio-pifi go-librespot[4788]: time="2026-09-29T21:49:14+01:00" level=debug msg="app state loaded" Sep 29 21:49:14 volumio-pifi go-librespot[4788]: time="2026-09-29T21:49:14+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:14 volumio-pifi go-librespot[4788]: time="2026-09-29T21:49:14+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:14 volumio-pifi go-librespot[4788]: time="2026-09-29T21:49: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" Sep 29 21:49:14 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:14 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:16 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:16 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:17 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 274. Sep 29 21:49:17 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:17 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:17 volumio-pifi go-librespot[4795]: go-librespot daemon starting... Sep 29 21:49:17 volumio-pifi go-librespot[4796]: time="2026-09-29T21:49:17+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:17 volumio-pifi go-librespot[4796]: time="2026-09-29T21:49:17+01:00" level=debug msg="app state loaded" Sep 29 21:49:17 volumio-pifi go-librespot[4796]: time="2026-09-29T21:49:17+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:17 volumio-pifi go-librespot[4796]: time="2026-09-29T21:49:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:17 volumio-pifi go-librespot[4796]: time="2026-09-29T21:49:17+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" Sep 29 21:49:17 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:17 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:19 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:19 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:20 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 275. Sep 29 21:49:20 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:21 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:21 volumio-pifi go-librespot[4816]: go-librespot daemon starting... Sep 29 21:49:21 volumio-pifi go-librespot[4817]: time="2026-09-29T21:49:21+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:21 volumio-pifi go-librespot[4817]: time="2026-09-29T21:49:21+01:00" level=debug msg="app state loaded" Sep 29 21:49:21 volumio-pifi go-librespot[4817]: time="2026-09-29T21:49:21+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:21 volumio-pifi go-librespot[4817]: time="2026-09-29T21:49:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:21 volumio-pifi go-librespot[4817]: time="2026-09-29T21:49: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" Sep 29 21:49:21 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:21 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:22 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:22 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:24 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 276. Sep 29 21:49:24 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:24 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:24 volumio-pifi go-librespot[4823]: go-librespot daemon starting... Sep 29 21:49:24 volumio-pifi go-librespot[4824]: time="2026-09-29T21:49:24+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:24 volumio-pifi go-librespot[4824]: time="2026-09-29T21:49:24+01:00" level=debug msg="app state loaded" Sep 29 21:49:24 volumio-pifi go-librespot[4824]: time="2026-09-29T21:49:24+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:24 volumio-pifi go-librespot[4824]: time="2026-09-29T21:49:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:24 volumio-pifi go-librespot[4824]: time="2026-09-29T21:49: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" Sep 29 21:49:24 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:24 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:25 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:25 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:27 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 277. Sep 29 21:49:27 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:27 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:27 volumio-pifi go-librespot[4830]: go-librespot daemon starting... Sep 29 21:49:27 volumio-pifi go-librespot[4831]: time="2026-09-29T21:49:27+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:27 volumio-pifi go-librespot[4831]: time="2026-09-29T21:49:27+01:00" level=debug msg="app state loaded" Sep 29 21:49:27 volumio-pifi go-librespot[4831]: time="2026-09-29T21:49:27+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:27 volumio-pifi go-librespot[4831]: time="2026-09-29T21:49:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:27 volumio-pifi go-librespot[4831]: time="2026-09-29T21:49: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" Sep 29 21:49:27 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:27 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:28 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:28 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:30 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 278. Sep 29 21:49:30 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:30 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:30 volumio-pifi go-librespot[4852]: go-librespot daemon starting... Sep 29 21:49:30 volumio-pifi go-librespot[4853]: time="2026-09-29T21:49:30+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:30 volumio-pifi go-librespot[4853]: time="2026-09-29T21:49:30+01:00" level=debug msg="app state loaded" Sep 29 21:49:30 volumio-pifi go-librespot[4853]: time="2026-09-29T21:49:30+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:30 volumio-pifi go-librespot[4853]: time="2026-09-29T21:49:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:30 volumio-pifi go-librespot[4853]: time="2026-09-29T21:49:30+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" Sep 29 21:49:30 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:30 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:31 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:31 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:33 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 279. Sep 29 21:49:33 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:34 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:34 volumio-pifi go-librespot[4859]: go-librespot daemon starting... Sep 29 21:49:34 volumio-pifi go-librespot[4860]: time="2026-09-29T21:49:34+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:34 volumio-pifi go-librespot[4860]: time="2026-09-29T21:49:34+01:00" level=debug msg="app state loaded" Sep 29 21:49:34 volumio-pifi go-librespot[4860]: time="2026-09-29T21:49:34+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:34 volumio-pifi go-librespot[4860]: time="2026-09-29T21:49:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:34 volumio-pifi go-librespot[4860]: time="2026-09-29T21:49: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" Sep 29 21:49:34 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:34 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:34 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:34 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:37 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 280. Sep 29 21:49:37 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:37 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:37 volumio-pifi go-librespot[4866]: go-librespot daemon starting... Sep 29 21:49:37 volumio-pifi go-librespot[4867]: time="2026-09-29T21:49:37+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:37 volumio-pifi go-librespot[4867]: time="2026-09-29T21:49:37+01:00" level=debug msg="app state loaded" Sep 29 21:49:37 volumio-pifi go-librespot[4867]: time="2026-09-29T21:49:37+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:37 volumio-pifi go-librespot[4867]: time="2026-09-29T21:49:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:37 volumio-pifi go-librespot[4867]: time="2026-09-29T21:49: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" Sep 29 21:49:37 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:37 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:37 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:37 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:40 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 281. Sep 29 21:49:40 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:40 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:40 volumio-pifi go-librespot[4887]: go-librespot daemon starting... Sep 29 21:49:40 volumio-pifi go-librespot[4888]: time="2026-09-29T21:49:40+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:40 volumio-pifi go-librespot[4888]: time="2026-09-29T21:49:40+01:00" level=debug msg="app state loaded" Sep 29 21:49:40 volumio-pifi go-librespot[4888]: time="2026-09-29T21:49:40+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:40 volumio-pifi go-librespot[4888]: time="2026-09-29T21:49:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:40 volumio-pifi go-librespot[4888]: time="2026-09-29T21:49: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" Sep 29 21:49:40 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:40 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:40 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:40 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:43 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 282. Sep 29 21:49:43 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:43 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:43 volumio-pifi go-librespot[4894]: go-librespot daemon starting... Sep 29 21:49:43 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:43 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:43 volumio-pifi go-librespot[4895]: time="2026-09-29T21:49:43+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:43 volumio-pifi go-librespot[4895]: time="2026-09-29T21:49:43+01:00" level=debug msg="app state loaded" Sep 29 21:49:43 volumio-pifi go-librespot[4895]: time="2026-09-29T21:49:43+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:43 volumio-pifi go-librespot[4895]: time="2026-09-29T21:49:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:43 volumio-pifi go-librespot[4895]: time="2026-09-29T21:49:43+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" Sep 29 21:49:43 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:43 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:46 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:46 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:46 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 283. Sep 29 21:49:46 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:47 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:47 volumio-pifi go-librespot[4901]: go-librespot daemon starting... Sep 29 21:49:47 volumio-pifi go-librespot[4902]: time="2026-09-29T21:49:47+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:47 volumio-pifi go-librespot[4902]: time="2026-09-29T21:49:47+01:00" level=debug msg="app state loaded" Sep 29 21:49:47 volumio-pifi go-librespot[4902]: time="2026-09-29T21:49:47+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:47 volumio-pifi go-librespot[4902]: time="2026-09-29T21:49:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:47 volumio-pifi go-librespot[4902]: time="2026-09-29T21:49: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" Sep 29 21:49:47 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:47 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:49 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:49 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:50 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 284. Sep 29 21:49:50 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:50 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:50 volumio-pifi go-librespot[4923]: go-librespot daemon starting... Sep 29 21:49:50 volumio-pifi go-librespot[4924]: time="2026-09-29T21:49:50+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:50 volumio-pifi go-librespot[4924]: time="2026-09-29T21:49:50+01:00" level=debug msg="app state loaded" Sep 29 21:49:50 volumio-pifi go-librespot[4924]: time="2026-09-29T21:49:50+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:50 volumio-pifi go-librespot[4924]: time="2026-09-29T21:49:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:50 volumio-pifi go-librespot[4924]: time="2026-09-29T21:49: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" Sep 29 21:49:50 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:50 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:52 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:52 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:53 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 285. Sep 29 21:49:53 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:53 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:53 volumio-pifi go-librespot[4930]: go-librespot daemon starting... Sep 29 21:49:53 volumio-pifi go-librespot[4931]: time="2026-09-29T21:49:53+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:53 volumio-pifi go-librespot[4931]: time="2026-09-29T21:49:53+01:00" level=debug msg="app state loaded" Sep 29 21:49:53 volumio-pifi go-librespot[4931]: time="2026-09-29T21:49:53+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:53 volumio-pifi go-librespot[4931]: time="2026-09-29T21:49:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:53 volumio-pifi go-librespot[4931]: time="2026-09-29T21:49: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" Sep 29 21:49:53 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:53 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:55 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:55 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:56 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 286. Sep 29 21:49:56 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:56 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:49:56 volumio-pifi go-librespot[4937]: go-librespot daemon starting... Sep 29 21:49:56 volumio-pifi go-librespot[4938]: time="2026-09-29T21:49:56+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:49:56 volumio-pifi go-librespot[4938]: time="2026-09-29T21:49:56+01:00" level=debug msg="app state loaded" Sep 29 21:49:56 volumio-pifi go-librespot[4938]: time="2026-09-29T21:49:56+01:00" level=debug msg="stored credentials not found" Sep 29 21:49:56 volumio-pifi go-librespot[4938]: time="2026-09-29T21:49:56+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:49:56 volumio-pifi go-librespot[4938]: time="2026-09-29T21:49:56+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" Sep 29 21:49:56 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:49:56 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:49:58 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:49:58 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:49:59 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 287. Sep 29 21:49:59 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:00 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:00 volumio-pifi go-librespot[4959]: go-librespot daemon starting... Sep 29 21:50:00 volumio-pifi go-librespot[4960]: time="2026-09-29T21:50:00+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:00 volumio-pifi go-librespot[4960]: time="2026-09-29T21:50:00+01:00" level=debug msg="app state loaded" Sep 29 21:50:00 volumio-pifi go-librespot[4960]: time="2026-09-29T21:50:00+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:00 volumio-pifi go-librespot[4960]: time="2026-09-29T21:50:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:00 volumio-pifi go-librespot[4960]: time="2026-09-29T21:50: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" Sep 29 21:50:00 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:00 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:01 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:01 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:03 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 288. Sep 29 21:50:03 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:03 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:03 volumio-pifi go-librespot[4967]: go-librespot daemon starting... Sep 29 21:50:03 volumio-pifi go-librespot[4968]: time="2026-09-29T21:50:03+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:03 volumio-pifi go-librespot[4968]: time="2026-09-29T21:50:03+01:00" level=debug msg="app state loaded" Sep 29 21:50:03 volumio-pifi go-librespot[4968]: time="2026-09-29T21:50:03+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:03 volumio-pifi go-librespot[4968]: time="2026-09-29T21:50:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:03 volumio-pifi go-librespot[4968]: time="2026-09-29T21:50: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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Sep 29 21:50:03 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:03 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:04 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:04 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:06 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 289. Sep 29 21:50:06 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:06 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:06 volumio-pifi go-librespot[4975]: go-librespot daemon starting... Sep 29 21:50:06 volumio-pifi go-librespot[4976]: time="2026-09-29T21:50:06+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:06 volumio-pifi go-librespot[4976]: time="2026-09-29T21:50:06+01:00" level=debug msg="app state loaded" Sep 29 21:50:06 volumio-pifi go-librespot[4976]: time="2026-09-29T21:50:06+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:06 volumio-pifi go-librespot[4976]: time="2026-09-29T21:50:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:06 volumio-pifi go-librespot[4976]: time="2026-09-29T21:50:06+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" Sep 29 21:50:06 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:06 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:07 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:07 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:09 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 290. Sep 29 21:50:09 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:09 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:09 volumio-pifi go-librespot[4996]: go-librespot daemon starting... Sep 29 21:50:09 volumio-pifi go-librespot[4997]: time="2026-09-29T21:50:09+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:09 volumio-pifi go-librespot[4997]: time="2026-09-29T21:50:09+01:00" level=debug msg="app state loaded" Sep 29 21:50:09 volumio-pifi go-librespot[4997]: time="2026-09-29T21:50:09+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:09 volumio-pifi go-librespot[4997]: time="2026-09-29T21:50:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:09 volumio-pifi go-librespot[4997]: time="2026-09-29T21:50:09+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" Sep 29 21:50:09 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:09 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:10 volumio-pifi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:10 volumio-pifi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 29 21:50:10 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:10 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:12 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 291. Sep 29 21:50:12 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:13 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:13 volumio-pifi go-librespot[5003]: go-librespot daemon starting... Sep 29 21:50:13 volumio-pifi go-librespot[5004]: time="2026-09-29T21:50:13+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:13 volumio-pifi go-librespot[5004]: time="2026-09-29T21:50:13+01:00" level=debug msg="app state loaded" Sep 29 21:50:13 volumio-pifi go-librespot[5004]: time="2026-09-29T21:50:13+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:13 volumio-pifi go-librespot[5004]: time="2026-09-29T21:50:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:13 volumio-pifi go-librespot[5004]: time="2026-09-29T21:50:13+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" Sep 29 21:50:13 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:13 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:13 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:13 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:16 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 292. Sep 29 21:50:16 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:16 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:16 volumio-pifi go-librespot[5010]: go-librespot daemon starting... Sep 29 21:50:16 volumio-pifi go-librespot[5011]: time="2026-09-29T21:50:16+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:16 volumio-pifi go-librespot[5011]: time="2026-09-29T21:50:16+01:00" level=debug msg="app state loaded" Sep 29 21:50:16 volumio-pifi go-librespot[5011]: time="2026-09-29T21:50:16+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:16 volumio-pifi go-librespot[5011]: time="2026-09-29T21:50:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:16 volumio-pifi go-librespot[5011]: time="2026-09-29T21:50:16+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" Sep 29 21:50:16 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:16 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:16 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:16 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:19 volumio-pifi dhcpcd[735]: eth0: carrier acquired Sep 29 21:50:19 volumio-pifi kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 29 21:50:19 volumio-pifi dhcpcd[735]: eth0: IAID eb:a3:6f:a1 Sep 29 21:50:19 volumio-pifi dhcpcd[735]: eth0: adding address fe80::a0ac:beda:1d7a:f0a4 Sep 29 21:50:19 volumio-pifi dhcpcd[735]: ipv6_addaddr1: Permission denied Sep 29 21:50:19 volumio-pifi dhcpcd[735]: eth0: probing address 192.168.1.220/24 Sep 29 21:50:19 volumio-pifi ifplugd(eth0)[986]: Link beat detected. Sep 29 21:50:19 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 293. Sep 29 21:50:19 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:19 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:19 volumio-pifi go-librespot[5033]: go-librespot daemon starting... Sep 29 21:50:19 volumio-pifi ifplugd(eth0)[986]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 29 21:50:19 volumio-pifi go-librespot[5034]: time="2026-09-29T21:50:19+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:19 volumio-pifi go-librespot[5034]: time="2026-09-29T21:50:19+01:00" level=debug msg="app state loaded" Sep 29 21:50:19 volumio-pifi go-librespot[5034]: time="2026-09-29T21:50:19+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:19 volumio-pifi go-librespot[5034]: time="2026-09-29T21:50:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:19 volumio-pifi go-librespot[5034]: time="2026-09-29T21:50:19+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" Sep 29 21:50:19 volumio-pifi ifplugd(eth0)[986]: client: ifup: interface eth0 already configured Sep 29 21:50:19 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:19 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:19 volumio-pifi dhcpcd[735]: eth0: soliciting an IPv6 router Sep 29 21:50:19 volumio-pifi ifplugd(eth0)[986]: Program executed successfully. Sep 29 21:50:19 volumio-pifi volumio[1056]: info: Received Get System Info Sep 29 21:50:19 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 29 21:50:19 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 29 21:50:19 volumio-pifi volumio[1056]: info: Discovery: Getting this device information Sep 29 21:50:19 volumio-pifi volumio[1056]: info: CoreCommandRouter::volumioGetState Sep 29 21:50:19 volumio-pifi volumio[1056]: info: CorePlayQueue::getTrack 0 Sep 29 21:50:19 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 29 21:50:19 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 29 21:50:19 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 29 21:50:19 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:19 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:20 volumio-pifi wireless.js[652]: WIRELESS.JS - INFO: === SNM TRANSITION === Sep 29 21:50:20 volumio-pifi wireless.js[652]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Sep 29 21:50:20 volumio-pifi wireless.js[652]: WIRELESS.JS - INFO: New ethernet state: connected Sep 29 21:50:20 volumio-pifi wireless.js[652]: WIRELESS.JS - INFO: Single Network Mode: enabled Sep 29 21:50:20 volumio-pifi wireless.js[652]: WIRELESS.JS - INFO: First start: no Sep 29 21:50:20 volumio-pifi wireless.js[652]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Sep 29 21:50:20 volumio-pifi wireless.js[652]: WIRELESS.JS - INFO: === END TRANSITION === Sep 29 21:50:20 volumio-pifi wireless.js[652]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Sep 29 21:50:20 volumio-pifi volumio5-onboarding[1343]: time=2026-09-29T21:50:20.680+01:00 level=INFO msg="service successfully established" component=discovery/localnet Sep 29 21:50:20 volumio-pifi sudo[5049]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Sep 29 21:50:20 volumio-pifi sudo[5049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 29 21:50:20 volumio-pifi dhcpcd[5050]: dhcpcd not running Sep 29 21:50:20 volumio-pifi sudo[5049]: pam_unix(sudo:session): session closed for user root Sep 29 21:50:20 volumio-pifi wireless.js[652]: dhcpcd not running Sep 29 21:50:20 volumio-pifi wireless.js[652]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Sep 29 21:50:21 volumio-pifi sudo[5058]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 29 21:50:21 volumio-pifi sudo[5058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 29 21:50:21 volumio-pifi sudo[5058]: pam_unix(sudo:session): session closed for user root Sep 29 21:50:21 volumio-pifi sudo[5060]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 29 21:50:21 volumio-pifi sudo[5060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 29 21:50:21 volumio-pifi sudo[5060]: pam_unix(sudo:session): session closed for user root Sep 29 21:50:21 volumio-pifi wireless.js[652]: WIRELESS.JS - INFO: Cleaning previous... Sep 29 21:50:22 volumio-pifi dhcpcd[735]: eth0: carrier lost Sep 29 21:50:22 volumio-pifi kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Sep 29 21:50:22 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 294. Sep 29 21:50:22 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:22 volumio-pifi ifplugd(eth0)[986]: Link beat lost. Sep 29 21:50:22 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:22 volumio-pifi go-librespot[5076]: go-librespot daemon starting... Sep 29 21:50:22 volumio-pifi go-librespot[5077]: time="2026-09-29T21:50:22+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:22 volumio-pifi go-librespot[5077]: time="2026-09-29T21:50:22+01:00" level=debug msg="app state loaded" Sep 29 21:50:22 volumio-pifi go-librespot[5077]: time="2026-09-29T21:50:22+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:22 volumio-pifi go-librespot[5077]: time="2026-09-29T21:50:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:22 volumio-pifi go-librespot[5077]: time="2026-09-29T21:50:22+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" Sep 29 21:50:22 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:22 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:22 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:22 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:22 volumio-pifi volumio[1056]: info: Received Get System Info Sep 29 21:50:22 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 29 21:50:22 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 29 21:50:22 volumio-pifi volumio[1056]: info: Discovery: Getting this device information Sep 29 21:50:22 volumio-pifi volumio[1056]: info: CoreCommandRouter::volumioGetState Sep 29 21:50:22 volumio-pifi volumio[1056]: info: CorePlayQueue::getTrack 0 Sep 29 21:50:22 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 29 21:50:23 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 29 21:50:23 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 29 21:50:23 volumio-pifi volumio5-onboarding[1343]: time=2026-09-29T21:50:23.790+01:00 level=INFO msg="service successfully established" component=discovery/localnet Sep 29 21:50:25 volumio-pifi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 16. Sep 29 21:50:25 volumio-pifi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Sep 29 21:50:25 volumio-pifi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Sep 29 21:50:25 volumio-pifi dhcpcd[735]: eth0: carrier acquired Sep 29 21:50:25 volumio-pifi kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 29 21:50:25 volumio-pifi dhcpcd[735]: eth0: IAID eb:a3:6f:a1 Sep 29 21:50:25 volumio-pifi dhcpcd[735]: eth0: probing address 192.168.1.220/24 Sep 29 21:50:25 volumio-pifi ifplugd(eth0)[986]: Link beat detected. Sep 29 21:50:25 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:25 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:25 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 295. Sep 29 21:50:25 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:26 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:26 volumio-pifi go-librespot[5093]: go-librespot daemon starting... Sep 29 21:50:26 volumio-pifi go-librespot[5094]: time="2026-09-29T21:50:26+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:26 volumio-pifi go-librespot[5094]: time="2026-09-29T21:50:26+01:00" level=debug msg="app state loaded" Sep 29 21:50:26 volumio-pifi go-librespot[5094]: time="2026-09-29T21:50:26+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:26 volumio-pifi go-librespot[5094]: time="2026-09-29T21:50:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:26 volumio-pifi go-librespot[5094]: time="2026-09-29T21:50:26+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" Sep 29 21:50:26 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:26 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:26 volumio-pifi volumio[1056]: info: Received Get System Info Sep 29 21:50:26 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 29 21:50:26 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 29 21:50:26 volumio-pifi volumio[1056]: info: Discovery: Getting this device information Sep 29 21:50:26 volumio-pifi volumio[1056]: info: CoreCommandRouter::volumioGetState Sep 29 21:50:26 volumio-pifi volumio[1056]: info: CorePlayQueue::getTrack 0 Sep 29 21:50:26 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 29 21:50:26 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 29 21:50:26 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 29 21:50:26 volumio-pifi dhcpcd[735]: eth0: soliciting an IPv6 router Sep 29 21:50:27 volumio-pifi volumio5-onboarding[1343]: time=2026-09-29T21:50:27.084+01:00 level=INFO msg="service successfully established" component=discovery/localnet Sep 29 21:50:28 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:28 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:29 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 296. Sep 29 21:50:29 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:29 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:29 volumio-pifi go-librespot[5114]: go-librespot daemon starting... Sep 29 21:50:29 volumio-pifi go-librespot[5115]: time="2026-09-29T21:50:29+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:29 volumio-pifi go-librespot[5115]: time="2026-09-29T21:50:29+01:00" level=debug msg="app state loaded" Sep 29 21:50:29 volumio-pifi go-librespot[5115]: time="2026-09-29T21:50:29+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:29 volumio-pifi go-librespot[5115]: time="2026-09-29T21:50:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:29 volumio-pifi go-librespot[5115]: time="2026-09-29T21:50:29+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" Sep 29 21:50:29 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:29 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:30 volumio-pifi dhcpcd[735]: eth0: using static address 192.168.1.220/24 Sep 29 21:50:30 volumio-pifi avahi-daemon[635]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.220. Sep 29 21:50:30 volumio-pifi avahi-daemon[635]: New relevant interface eth0.IPv4 for mDNS. Sep 29 21:50:30 volumio-pifi avahi-daemon[635]: Registering new address record for 192.168.1.220 on eth0.IPv4. Sep 29 21:50:30 volumio-pifi dhcpcd[735]: eth0: adding route to 192.168.1.0/24 Sep 29 21:50:30 volumio-pifi dhcpcd[735]: eth0: adding default route via 192.168.1.1 Sep 29 21:50:30 volumio-pifi systemd[1]: welcome.service: Deactivated successfully. Sep 29 21:50:30 volumio-pifi systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 29 21:50:30 volumio-pifi systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 29 21:50:30 volumio-pifi systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 29 21:50:30 volumio-pifi welcome[5141]: Resolved ip:[1] 192.168.1.220 Sep 29 21:50:30 volumio-pifi systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 29 21:50:30 volumio-pifi systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Sep 29 21:50:31 volumio-pifi volumio[1056]: info: Received Get System Info Sep 29 21:50:31 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 29 21:50:31 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 29 21:50:31 volumio-pifi volumio[1056]: info: Discovery: Getting this device information Sep 29 21:50:31 volumio-pifi volumio[1056]: info: CoreCommandRouter::volumioGetState Sep 29 21:50:31 volumio-pifi volumio[1056]: info: CorePlayQueue::getTrack 0 Sep 29 21:50:31 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 29 21:50:31 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 29 21:50:31 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 29 21:50:31 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:31 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:31 volumio-pifi volumio5-onboarding[1343]: time=2026-09-29T21:50:31.935+01:00 level=INFO msg="service successfully established" component=discovery/localnet Sep 29 21:50:31 volumio-pifi ifplugd(eth0)[986]: Link beat lost. Sep 29 21:50:32 volumio-pifi dhcpcd[735]: eth0: carrier lost Sep 29 21:50:32 volumio-pifi kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Sep 29 21:50:32 volumio-pifi avahi-daemon[635]: Withdrawing address record for 192.168.1.220 on eth0. Sep 29 21:50:32 volumio-pifi avahi-daemon[635]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.220. Sep 29 21:50:32 volumio-pifi avahi-daemon[635]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 29 21:50:32 volumio-pifi dhcpcd[735]: eth0: deleting route to 192.168.1.0/24 Sep 29 21:50:32 volumio-pifi dhcpcd[735]: eth0: deleting default route via 192.168.1.1 Sep 29 21:50:32 volumio-pifi volumio[1056]: info: Discovery: A device disappeared from network Sep 29 21:50:32 volumio-pifi systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Sep 29 21:50:32 volumio-pifi systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Sep 29 21:50:32 volumio-pifi systemd[1]: welcome.service: Deactivated successfully. Sep 29 21:50:32 volumio-pifi systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 29 21:50:32 volumio-pifi systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 29 21:50:32 volumio-pifi systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 29 21:50:32 volumio-pifi welcome[5172]: Resolved ip:[0] Sep 29 21:50:32 volumio-pifi systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 29 21:50:32 volumio-pifi systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Sep 29 21:50:32 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 297. Sep 29 21:50:32 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:32 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:32 volumio-pifi go-librespot[5177]: go-librespot daemon starting... Sep 29 21:50:32 volumio-pifi volumio[1056]: info: Received Get System Info Sep 29 21:50:32 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 29 21:50:32 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 29 21:50:32 volumio-pifi volumio[1056]: info: Discovery: Getting this device information Sep 29 21:50:32 volumio-pifi volumio[1056]: info: CoreCommandRouter::volumioGetState Sep 29 21:50:32 volumio-pifi volumio[1056]: info: CorePlayQueue::getTrack 0 Sep 29 21:50:32 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 29 21:50:32 volumio-pifi go-librespot[5178]: time="2026-09-29T21:50:32+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:32 volumio-pifi go-librespot[5178]: time="2026-09-29T21:50:32+01:00" level=debug msg="app state loaded" Sep 29 21:50:32 volumio-pifi go-librespot[5178]: time="2026-09-29T21:50:32+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:32 volumio-pifi go-librespot[5178]: time="2026-09-29T21:50:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:32 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 29 21:50:32 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 29 21:50:32 volumio-pifi go-librespot[5178]: time="2026-09-29T21:50:32+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" Sep 29 21:50:32 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:32 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:33 volumio-pifi volumio5-onboarding[1343]: time=2026-09-29T21:50:33.487+01:00 level=INFO msg="service successfully established" component=discovery/localnet Sep 29 21:50:33 volumio-pifi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:33 volumio-pifi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 29 21:50:34 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:34 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:35 volumio-pifi ifplugd(eth0)[986]: Link beat detected. Sep 29 21:50:35 volumio-pifi dhcpcd[735]: eth0: carrier acquired Sep 29 21:50:35 volumio-pifi kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 29 21:50:35 volumio-pifi dhcpcd[735]: eth0: IAID eb:a3:6f:a1 Sep 29 21:50:35 volumio-pifi dhcpcd[735]: eth0: probing address 192.168.1.220/24 Sep 29 21:50:35 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 298. Sep 29 21:50:35 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:35 volumio-pifi volumio[1056]: info: Received Get System Info Sep 29 21:50:35 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 29 21:50:35 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 29 21:50:35 volumio-pifi volumio[1056]: info: Discovery: Getting this device information Sep 29 21:50:35 volumio-pifi volumio[1056]: info: CoreCommandRouter::volumioGetState Sep 29 21:50:35 volumio-pifi volumio[1056]: info: CorePlayQueue::getTrack 0 Sep 29 21:50:35 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 29 21:50:35 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 29 21:50:35 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 29 21:50:35 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:35 volumio-pifi go-librespot[5186]: go-librespot daemon starting... Sep 29 21:50:35 volumio-pifi go-librespot[5187]: time="2026-09-29T21:50:35+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:35 volumio-pifi go-librespot[5187]: time="2026-09-29T21:50:35+01:00" level=debug msg="app state loaded" Sep 29 21:50:35 volumio-pifi go-librespot[5187]: time="2026-09-29T21:50:35+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:35 volumio-pifi go-librespot[5187]: time="2026-09-29T21:50:35+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:35 volumio-pifi go-librespot[5187]: time="2026-09-29T21:50:35+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" Sep 29 21:50:35 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:35 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:36 volumio-pifi dhcpcd[735]: eth0: soliciting an IPv6 router Sep 29 21:50:36 volumio-pifi volumio[1056]: info: Discovery: adding 08c86b9c-5833-4a0c-a05c-2ec33edf91f3 Sep 29 21:50:36 volumio-pifi volumio[1056]: info: Discovery: Found device Volumio PiFi Sep 29 21:50:36 volumio-pifi volumio[1056]: info: CoreCommandRouter::volumioGetState Sep 29 21:50:36 volumio-pifi volumio[1056]: info: CorePlayQueue::getTrack 0 Sep 29 21:50:36 volumio-pifi volumio5-onboarding[1343]: time=2026-09-29T21:50:36.679+01:00 level=INFO msg="service successfully established" component=discovery/localnet Sep 29 21:50:37 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:37 volumio-pifi volumio[1056]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:38 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 299. Sep 29 21:50:38 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:39 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:39 volumio-pifi go-librespot[5207]: go-librespot daemon starting... Sep 29 21:50:39 volumio-pifi go-librespot[5208]: time="2026-09-29T21:50:39+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:39 volumio-pifi go-librespot[5208]: time="2026-09-29T21:50:39+01:00" level=debug msg="app state loaded" Sep 29 21:50:39 volumio-pifi go-librespot[5208]: time="2026-09-29T21:50:39+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:39 volumio-pifi go-librespot[5208]: time="2026-09-29T21:50:39+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:39 volumio-pifi go-librespot[5208]: time="2026-09-29T21:50:39+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" Sep 29 21:50:39 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:39 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:39 volumio-pifi dhcpcd[735]: eth0: using static address 192.168.1.220/24 Sep 29 21:50:39 volumio-pifi avahi-daemon[635]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.220. Sep 29 21:50:39 volumio-pifi avahi-daemon[635]: New relevant interface eth0.IPv4 for mDNS. Sep 29 21:50:39 volumio-pifi avahi-daemon[635]: Registering new address record for 192.168.1.220 on eth0.IPv4. Sep 29 21:50:39 volumio-pifi dhcpcd[735]: eth0: adding route to 192.168.1.0/24 Sep 29 21:50:39 volumio-pifi dhcpcd[735]: eth0: adding default route via 192.168.1.1 Sep 29 21:50:40 volumio-pifi systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Sep 29 21:50:40 volumio-pifi systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Sep 29 21:50:40 volumio-pifi systemd[1]: welcome.service: Deactivated successfully. Sep 29 21:50:40 volumio-pifi systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 29 21:50:40 volumio-pifi systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 29 21:50:40 volumio-pifi systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 29 21:50:40 volumio-pifi welcome[5233]: Resolved ip:[1] 192.168.1.220 Sep 29 21:50:40 volumio-pifi systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 29 21:50:40 volumio-pifi systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Sep 29 21:50:40 volumio-pifi volumio[1056]: info: Received Get System Info Sep 29 21:50:40 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 29 21:50:40 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 29 21:50:40 volumio-pifi volumio[1056]: info: Discovery: Getting this device information Sep 29 21:50:40 volumio-pifi volumio[1056]: info: CoreCommandRouter::volumioGetState Sep 29 21:50:40 volumio-pifi volumio[1056]: info: CorePlayQueue::getTrack 0 Sep 29 21:50:40 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 29 21:50:40 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 29 21:50:40 volumio-pifi volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 29 21:50:40 volumio-pifi volumio[1056]: info: Initializing connection to go-librespot Websocket Sep 29 21:50:41 volumio-pifi ntpd[971]: IO: Listen normally on 3 eth0 192.168.1.220:123 Sep 29 21:50:41 volumio-pifi ntpd[971]: IO: new interface(s) found: waking up resolver Sep 29 21:50:41 volumio-pifi ntpd[971]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Sep 29 21:50:41 volumio-pifi volumio5-onboarding[1343]: time=2026-09-29T21:50:41.370+01:00 level=INFO msg="service successfully established" component=discovery/localnet Sep 29 21:50:42 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 300. Sep 29 21:50:42 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:42 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:42 volumio-pifi go-librespot[5240]: go-librespot daemon starting... Sep 29 21:50:42 volumio-pifi go-librespot[5241]: time="2026-09-29T21:50:42+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:42 volumio-pifi go-librespot[5241]: time="2026-09-29T21:50:42+01:00" level=debug msg="app state loaded" Sep 29 21:50:42 volumio-pifi go-librespot[5241]: time="2026-09-29T21:50:42+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:42 volumio-pifi go-librespot[5241]: time="2026-09-29T21:50:42+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:48 volumio-pifi volumio[1056]: info: Volumio Network Manager: Network status updated: 1 Sep 29 21:50:48 volumio-pifi systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 17. Sep 29 21:50:48 volumio-pifi systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Sep 29 21:50:48 volumio-pifi systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Sep 29 21:50:52 volumio-pifi ntpd[971]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Sep 29 21:50:52 volumio-pifi ntpd[971]: DNS: dns_check: DNS error: -11, System error Sep 29 21:50:52 volumio-pifi ntpd[971]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Sep 29 21:50:52 volumio-pifi go-librespot[5241]: time="2026-09-29T21:50:52+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" Sep 29 21:50:52 volumio-pifi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 29 21:50:52 volumio-pifi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 29 21:50:52 volumio-pifi volumio[1056]: info: Connection to go-librespot Websocket established Sep 29 21:50:52 volumio-pifi volumio[1056]: info: Connection to go-librespot Websocket closed Sep 29 21:50:53 volumio-pifi ntpd[971]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Sep 29 21:50:55 volumio-pifi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 301. Sep 29 21:50:55 volumio-pifi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:56 volumio-pifi volumio[1056]: info: Getting Spotify volume Sep 29 21:50:56 volumio-pifi volumio[1056]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 29 21:50:56 volumio-pifi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 29 21:50:56 volumio-pifi go-librespot[5280]: go-librespot daemon starting... Sep 29 21:50:56 volumio-pifi volumio[1056]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 29 21:50:56 volumio-pifi volumio[1056]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 29 21:50:56 volumio-pifi volumio[1056]: errno: -111, Sep 29 21:50:56 volumio-pifi volumio[1056]: code: 'ECONNREFUSED', Sep 29 21:50:56 volumio-pifi volumio[1056]: syscall: 'connect', Sep 29 21:50:56 volumio-pifi volumio[1056]: address: '127.0.0.1', Sep 29 21:50:56 volumio-pifi volumio[1056]: port: 9879, Sep 29 21:50:56 volumio-pifi volumio[1056]: response: undefined Sep 29 21:50:56 volumio-pifi volumio[1056]: } Sep 29 21:50:56 volumio-pifi volumio[1056]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 29 21:50:56 volumio-pifi go-librespot[5281]: time="2026-09-29T21:50:56+01:00" level=info msg="running go-librespot 0.4.0" Sep 29 21:50:56 volumio-pifi go-librespot[5281]: time="2026-09-29T21:50:56+01:00" level=debug msg="app state loaded" Sep 29 21:50:56 volumio-pifi go-librespot[5281]: time="2026-09-29T21:50:56+01:00" level=debug msg="stored credentials not found" Sep 29 21:50:56 volumio-pifi go-librespot[5281]: time="2026-09-29T21:50:56+01:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 29 21:50:57 volumio-pifi sudo[5301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-29 21:49' Sep 29 21:50:57 volumio-pifi sudo[5301]: 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"