Mar 24 18:24:00 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Mar 24 18:24:00 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:00 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:00 volumio-sensan go-librespot[2813]: go-librespot daemon starting... Mar 24 18:24:00 volumio-sensan go-librespot[2814]: time="2026-03-24T18:24:00+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:00 volumio-sensan go-librespot[2814]: time="2026-03-24T18:24:00+01:00" level=debug msg="app state loaded" Mar 24 18:24:00 volumio-sensan go-librespot[2814]: time="2026-03-24T18:24:00+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:00 volumio-sensan go-librespot[2814]: time="2026-03-24T18:24:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:00 volumio-sensan go-librespot[2814]: time="2026-03-24T18:24:00+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:00 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:00 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:01 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:01 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:03 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Mar 24 18:24:03 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:03 volumio-sensan go-librespot[2823]: go-librespot daemon starting... Mar 24 18:24:03 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:03 volumio-sensan go-librespot[2824]: time="2026-03-24T18:24:03+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:03 volumio-sensan go-librespot[2824]: time="2026-03-24T18:24:03+01:00" level=debug msg="app state loaded" Mar 24 18:24:03 volumio-sensan go-librespot[2824]: time="2026-03-24T18:24:03+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:03 volumio-sensan go-librespot[2824]: time="2026-03-24T18:24:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:03 volumio-sensan go-librespot[2824]: time="2026-03-24T18:24: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" Mar 24 18:24:03 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:03 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:04 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:04 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:06 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Mar 24 18:24:06 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:06 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:06 volumio-sensan go-librespot[2834]: go-librespot daemon starting... Mar 24 18:24:07 volumio-sensan go-librespot[2835]: time="2026-03-24T18:24:07+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:07 volumio-sensan go-librespot[2835]: time="2026-03-24T18:24:07+01:00" level=debug msg="app state loaded" Mar 24 18:24:07 volumio-sensan go-librespot[2835]: time="2026-03-24T18:24:07+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:07 volumio-sensan go-librespot[2835]: time="2026-03-24T18:24:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:07 volumio-sensan go-librespot[2835]: time="2026-03-24T18:24:07+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:07 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:07 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:07 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:07 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:10 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Mar 24 18:24:10 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:10 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:10 volumio-sensan go-librespot[2857]: go-librespot daemon starting... Mar 24 18:24:10 volumio-sensan go-librespot[2858]: time="2026-03-24T18:24:10+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:10 volumio-sensan go-librespot[2858]: time="2026-03-24T18:24:10+01:00" level=debug msg="app state loaded" Mar 24 18:24:10 volumio-sensan go-librespot[2858]: time="2026-03-24T18:24:10+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:10 volumio-sensan go-librespot[2858]: time="2026-03-24T18:24:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:10 volumio-sensan go-librespot[2858]: time="2026-03-24T18:24:10+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:10 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:10 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:10 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:10 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:13 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Mar 24 18:24:13 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:13 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:13 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:13 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:13 volumio-sensan go-librespot[2866]: go-librespot daemon starting... Mar 24 18:24:13 volumio-sensan go-librespot[2867]: time="2026-03-24T18:24:13+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:13 volumio-sensan go-librespot[2867]: time="2026-03-24T18:24:13+01:00" level=debug msg="app state loaded" Mar 24 18:24:13 volumio-sensan go-librespot[2867]: time="2026-03-24T18:24:13+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:13 volumio-sensan go-librespot[2867]: time="2026-03-24T18:24:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:13 volumio-sensan go-librespot[2867]: time="2026-03-24T18:24: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" Mar 24 18:24:13 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:13 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:16 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:16 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:16 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Mar 24 18:24:16 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:16 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:16 volumio-sensan go-librespot[2875]: go-librespot daemon starting... Mar 24 18:24:16 volumio-sensan go-librespot[2876]: time="2026-03-24T18:24:16+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:16 volumio-sensan go-librespot[2876]: time="2026-03-24T18:24:16+01:00" level=debug msg="app state loaded" Mar 24 18:24:16 volumio-sensan go-librespot[2876]: time="2026-03-24T18:24:16+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:16 volumio-sensan go-librespot[2876]: time="2026-03-24T18:24:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:16 volumio-sensan go-librespot[2876]: time="2026-03-24T18:24: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" Mar 24 18:24:16 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:16 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:19 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:19 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:20 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Mar 24 18:24:20 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:20 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:20 volumio-sensan go-librespot[2898]: go-librespot daemon starting... Mar 24 18:24:20 volumio-sensan go-librespot[2899]: time="2026-03-24T18:24:20+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:20 volumio-sensan go-librespot[2899]: time="2026-03-24T18:24:20+01:00" level=debug msg="app state loaded" Mar 24 18:24:20 volumio-sensan go-librespot[2899]: time="2026-03-24T18:24:20+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:20 volumio-sensan go-librespot[2899]: time="2026-03-24T18:24:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:20 volumio-sensan go-librespot[2899]: time="2026-03-24T18:24:20+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:20 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:20 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:22 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:22 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:23 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Mar 24 18:24:23 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:23 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:23 volumio-sensan go-librespot[2907]: go-librespot daemon starting... Mar 24 18:24:23 volumio-sensan go-librespot[2908]: time="2026-03-24T18:24:23+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:23 volumio-sensan go-librespot[2908]: time="2026-03-24T18:24:23+01:00" level=debug msg="app state loaded" Mar 24 18:24:23 volumio-sensan go-librespot[2908]: time="2026-03-24T18:24:23+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:23 volumio-sensan go-librespot[2908]: time="2026-03-24T18:24:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:23 volumio-sensan go-librespot[2908]: time="2026-03-24T18:24:23+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:23 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:23 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:25 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:25 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:26 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Mar 24 18:24:26 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:26 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:26 volumio-sensan go-librespot[2918]: go-librespot daemon starting... Mar 24 18:24:26 volumio-sensan go-librespot[2919]: time="2026-03-24T18:24:26+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:26 volumio-sensan go-librespot[2919]: time="2026-03-24T18:24:26+01:00" level=debug msg="app state loaded" Mar 24 18:24:26 volumio-sensan go-librespot[2919]: time="2026-03-24T18:24:26+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:27 volumio-sensan go-librespot[2919]: time="2026-03-24T18:24:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:27 volumio-sensan go-librespot[2919]: time="2026-03-24T18:24:27+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:27 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:27 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:28 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:28 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:30 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Mar 24 18:24:30 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:30 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:30 volumio-sensan go-librespot[2941]: go-librespot daemon starting... Mar 24 18:24:30 volumio-sensan go-librespot[2942]: time="2026-03-24T18:24:30+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:30 volumio-sensan go-librespot[2942]: time="2026-03-24T18:24:30+01:00" level=debug msg="app state loaded" Mar 24 18:24:30 volumio-sensan go-librespot[2942]: time="2026-03-24T18:24:30+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:30 volumio-sensan go-librespot[2942]: time="2026-03-24T18:24:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:30 volumio-sensan go-librespot[2942]: time="2026-03-24T18:24: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" Mar 24 18:24:30 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:30 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:31 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:31 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:33 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Mar 24 18:24:33 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:33 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:33 volumio-sensan go-librespot[2950]: go-librespot daemon starting... Mar 24 18:24:33 volumio-sensan go-librespot[2951]: time="2026-03-24T18:24:33+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:33 volumio-sensan go-librespot[2951]: time="2026-03-24T18:24:33+01:00" level=debug msg="app state loaded" Mar 24 18:24:33 volumio-sensan go-librespot[2951]: time="2026-03-24T18:24:33+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:33 volumio-sensan go-librespot[2951]: time="2026-03-24T18:24:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:33 volumio-sensan go-librespot[2951]: time="2026-03-24T18:24:33+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:33 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:33 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:34 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:34 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:36 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Mar 24 18:24:36 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:36 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:36 volumio-sensan go-librespot[2959]: go-librespot daemon starting... Mar 24 18:24:36 volumio-sensan go-librespot[2960]: time="2026-03-24T18:24:36+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:36 volumio-sensan go-librespot[2960]: time="2026-03-24T18:24:36+01:00" level=debug msg="app state loaded" Mar 24 18:24:36 volumio-sensan go-librespot[2960]: time="2026-03-24T18:24:36+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:36 volumio-sensan go-librespot[2960]: time="2026-03-24T18:24:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:36 volumio-sensan go-librespot[2960]: time="2026-03-24T18:24:36+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:36 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:36 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:37 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:37 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:40 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Mar 24 18:24:40 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:40 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:40 volumio-sensan go-librespot[2976]: go-librespot daemon starting... Mar 24 18:24:40 volumio-sensan go-librespot[2982]: time="2026-03-24T18:24:40+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:40 volumio-sensan go-librespot[2982]: time="2026-03-24T18:24:40+01:00" level=debug msg="app state loaded" Mar 24 18:24:40 volumio-sensan go-librespot[2982]: time="2026-03-24T18:24:40+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:40 volumio-sensan go-librespot[2982]: time="2026-03-24T18:24:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:40 volumio-sensan go-librespot[2982]: time="2026-03-24T18:24:40+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:40 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:40 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:40 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:40 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:43 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Mar 24 18:24:43 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:43 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:43 volumio-sensan go-librespot[2991]: go-librespot daemon starting... Mar 24 18:24:43 volumio-sensan go-librespot[2992]: time="2026-03-24T18:24:43+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:43 volumio-sensan go-librespot[2992]: time="2026-03-24T18:24:43+01:00" level=debug msg="app state loaded" Mar 24 18:24:43 volumio-sensan go-librespot[2992]: time="2026-03-24T18:24:43+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:43 volumio-sensan go-librespot[2992]: time="2026-03-24T18:24:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:43 volumio-sensan go-librespot[2992]: time="2026-03-24T18:24: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" Mar 24 18:24:43 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:43 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:43 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:43 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:46 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:46 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:46 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Mar 24 18:24:46 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:46 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:46 volumio-sensan go-librespot[3002]: go-librespot daemon starting... Mar 24 18:24:46 volumio-sensan go-librespot[3003]: time="2026-03-24T18:24:46+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:46 volumio-sensan go-librespot[3003]: time="2026-03-24T18:24:46+01:00" level=debug msg="app state loaded" Mar 24 18:24:46 volumio-sensan go-librespot[3003]: time="2026-03-24T18:24:46+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:47 volumio-sensan go-librespot[3003]: time="2026-03-24T18:24:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:47 volumio-sensan go-librespot[3003]: time="2026-03-24T18:24:47+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:47 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:47 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:49 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:49 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:50 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Mar 24 18:24:50 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:50 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:50 volumio-sensan go-librespot[3011]: go-librespot daemon starting... Mar 24 18:24:50 volumio-sensan go-librespot[3012]: time="2026-03-24T18:24:50+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:50 volumio-sensan go-librespot[3012]: time="2026-03-24T18:24:50+01:00" level=debug msg="app state loaded" Mar 24 18:24:50 volumio-sensan go-librespot[3012]: time="2026-03-24T18:24:50+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:50 volumio-sensan go-librespot[3012]: time="2026-03-24T18:24:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:50 volumio-sensan go-librespot[3012]: time="2026-03-24T18:24:50+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:50 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:50 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:52 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:52 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:53 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Mar 24 18:24:53 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:53 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:53 volumio-sensan go-librespot[3035]: go-librespot daemon starting... Mar 24 18:24:53 volumio-sensan go-librespot[3036]: time="2026-03-24T18:24:53+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:53 volumio-sensan go-librespot[3036]: time="2026-03-24T18:24:53+01:00" level=debug msg="app state loaded" Mar 24 18:24:53 volumio-sensan go-librespot[3036]: time="2026-03-24T18:24:53+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:53 volumio-sensan go-librespot[3036]: time="2026-03-24T18:24:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:53 volumio-sensan go-librespot[3036]: time="2026-03-24T18:24:53+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:24:53 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:53 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:55 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:55 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:24:56 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Mar 24 18:24:56 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:56 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:24:56 volumio-sensan go-librespot[3044]: go-librespot daemon starting... Mar 24 18:24:56 volumio-sensan go-librespot[3045]: time="2026-03-24T18:24:56+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:24:56 volumio-sensan go-librespot[3045]: time="2026-03-24T18:24:56+01:00" level=debug msg="app state loaded" Mar 24 18:24:56 volumio-sensan go-librespot[3045]: time="2026-03-24T18:24:56+01:00" level=debug msg="stored credentials not found" Mar 24 18:24:56 volumio-sensan go-librespot[3045]: time="2026-03-24T18:24:56+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:24:56 volumio-sensan go-librespot[3045]: time="2026-03-24T18:24: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" Mar 24 18:24:56 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:24:56 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:24:58 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:24:58 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:00 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Mar 24 18:25:00 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:00 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:00 volumio-sensan go-librespot[3053]: go-librespot daemon starting... Mar 24 18:25:00 volumio-sensan go-librespot[3054]: time="2026-03-24T18:25:00+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:00 volumio-sensan go-librespot[3054]: time="2026-03-24T18:25:00+01:00" level=debug msg="app state loaded" Mar 24 18:25:00 volumio-sensan go-librespot[3054]: time="2026-03-24T18:25:00+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:00 volumio-sensan go-librespot[3054]: time="2026-03-24T18:25:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:00 volumio-sensan go-librespot[3054]: time="2026-03-24T18:25:00+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:25:00 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:00 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:01 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:01 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:03 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Mar 24 18:25:03 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:03 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:03 volumio-sensan go-librespot[3076]: go-librespot daemon starting... Mar 24 18:25:03 volumio-sensan go-librespot[3077]: time="2026-03-24T18:25:03+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:03 volumio-sensan go-librespot[3077]: time="2026-03-24T18:25:03+01:00" level=debug msg="app state loaded" Mar 24 18:25:03 volumio-sensan go-librespot[3077]: time="2026-03-24T18:25:03+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:03 volumio-sensan go-librespot[3077]: time="2026-03-24T18:25:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:03 volumio-sensan go-librespot[3077]: time="2026-03-24T18:25: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" Mar 24 18:25:03 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:03 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:04 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:04 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:06 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Mar 24 18:25:06 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:06 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:06 volumio-sensan go-librespot[3086]: go-librespot daemon starting... Mar 24 18:25:06 volumio-sensan go-librespot[3087]: time="2026-03-24T18:25:06+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:06 volumio-sensan go-librespot[3087]: time="2026-03-24T18:25:06+01:00" level=debug msg="app state loaded" Mar 24 18:25:06 volumio-sensan go-librespot[3087]: time="2026-03-24T18:25:06+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:06 volumio-sensan go-librespot[3087]: time="2026-03-24T18:25:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:06 volumio-sensan go-librespot[3087]: time="2026-03-24T18:25: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" Mar 24 18:25:06 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:06 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:07 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:07 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:10 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Mar 24 18:25:10 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:10 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:10 volumio-sensan go-librespot[3095]: go-librespot daemon starting... Mar 24 18:25:10 volumio-sensan go-librespot[3096]: time="2026-03-24T18:25:10+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:10 volumio-sensan go-librespot[3096]: time="2026-03-24T18:25:10+01:00" level=debug msg="app state loaded" Mar 24 18:25:10 volumio-sensan go-librespot[3096]: time="2026-03-24T18:25:10+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:10 volumio-sensan go-librespot[3096]: time="2026-03-24T18:25:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:10 volumio-sensan go-librespot[3096]: time="2026-03-24T18:25:10+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:25:10 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:10 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:10 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:10 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:13 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Mar 24 18:25:13 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:13 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:13 volumio-sensan go-librespot[3118]: go-librespot daemon starting... Mar 24 18:25:13 volumio-sensan go-librespot[3119]: time="2026-03-24T18:25:13+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:13 volumio-sensan go-librespot[3119]: time="2026-03-24T18:25:13+01:00" level=debug msg="app state loaded" Mar 24 18:25:13 volumio-sensan go-librespot[3119]: time="2026-03-24T18:25:13+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:13 volumio-sensan go-librespot[3119]: time="2026-03-24T18:25:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:13 volumio-sensan go-librespot[3119]: time="2026-03-24T18:25: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" Mar 24 18:25:13 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:13 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:14 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:14 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:16 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Mar 24 18:25:16 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:16 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:16 volumio-sensan go-librespot[3128]: go-librespot daemon starting... Mar 24 18:25:16 volumio-sensan go-librespot[3129]: time="2026-03-24T18:25:16+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:16 volumio-sensan go-librespot[3129]: time="2026-03-24T18:25:16+01:00" level=debug msg="app state loaded" Mar 24 18:25:16 volumio-sensan go-librespot[3129]: time="2026-03-24T18:25:16+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:16 volumio-sensan go-librespot[3129]: time="2026-03-24T18:25:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:16 volumio-sensan go-librespot[3129]: time="2026-03-24T18:25: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" Mar 24 18:25:16 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:16 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:17 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:17 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:18 volumio-sensan kernel: hwmon hwmon1: Undervoltage detected! Mar 24 18:25:20 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Mar 24 18:25:20 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:20 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:20 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:20 volumio-sensan go-librespot[3138]: go-librespot daemon starting... Mar 24 18:25:20 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:20 volumio-sensan go-librespot[3139]: time="2026-03-24T18:25:20+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:20 volumio-sensan go-librespot[3139]: time="2026-03-24T18:25:20+01:00" level=debug msg="app state loaded" Mar 24 18:25:20 volumio-sensan go-librespot[3139]: time="2026-03-24T18:25:20+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:20 volumio-sensan go-librespot[3139]: time="2026-03-24T18:25:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:20 volumio-sensan go-librespot[3139]: time="2026-03-24T18:25:20+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:25:20 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:20 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:23 volumio-sensan kernel: hwmon hwmon1: Voltage normalised Mar 24 18:25:23 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:23 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:23 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Mar 24 18:25:23 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:23 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:23 volumio-sensan go-librespot[3162]: go-librespot daemon starting... Mar 24 18:25:23 volumio-sensan go-librespot[3163]: time="2026-03-24T18:25:23+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:23 volumio-sensan go-librespot[3163]: time="2026-03-24T18:25:23+01:00" level=debug msg="app state loaded" Mar 24 18:25:23 volumio-sensan go-librespot[3163]: time="2026-03-24T18:25:23+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:23 volumio-sensan go-librespot[3163]: time="2026-03-24T18:25:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:23 volumio-sensan go-librespot[3163]: time="2026-03-24T18:25:23+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:25:23 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:23 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:26 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:26 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:26 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Mar 24 18:25:26 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:26 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:26 volumio-sensan go-librespot[3173]: go-librespot daemon starting... Mar 24 18:25:26 volumio-sensan go-librespot[3174]: time="2026-03-24T18:25:26+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:26 volumio-sensan go-librespot[3174]: time="2026-03-24T18:25:26+01:00" level=debug msg="app state loaded" Mar 24 18:25:26 volumio-sensan go-librespot[3174]: time="2026-03-24T18:25:26+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:26 volumio-sensan go-librespot[3174]: time="2026-03-24T18:25:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:26 volumio-sensan go-librespot[3174]: time="2026-03-24T18:25: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" Mar 24 18:25:26 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:26 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:29 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:29 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:30 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Mar 24 18:25:30 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:30 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:30 volumio-sensan go-librespot[3182]: go-librespot daemon starting... Mar 24 18:25:30 volumio-sensan go-librespot[3183]: time="2026-03-24T18:25:30+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:30 volumio-sensan go-librespot[3183]: time="2026-03-24T18:25:30+01:00" level=debug msg="app state loaded" Mar 24 18:25:30 volumio-sensan go-librespot[3183]: time="2026-03-24T18:25:30+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:30 volumio-sensan go-librespot[3183]: time="2026-03-24T18:25:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:30 volumio-sensan go-librespot[3183]: time="2026-03-24T18:25: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" Mar 24 18:25:30 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:30 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:32 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:32 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:33 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Mar 24 18:25:33 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:33 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:33 volumio-sensan go-librespot[3205]: go-librespot daemon starting... Mar 24 18:25:33 volumio-sensan go-librespot[3206]: time="2026-03-24T18:25:33+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:33 volumio-sensan go-librespot[3206]: time="2026-03-24T18:25:33+01:00" level=debug msg="app state loaded" Mar 24 18:25:33 volumio-sensan go-librespot[3206]: time="2026-03-24T18:25:33+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:33 volumio-sensan go-librespot[3206]: time="2026-03-24T18:25:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:33 volumio-sensan go-librespot[3206]: time="2026-03-24T18:25:33+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:25:33 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:33 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:35 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:35 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:36 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Mar 24 18:25:36 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:36 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:36 volumio-sensan go-librespot[3217]: go-librespot daemon starting... Mar 24 18:25:36 volumio-sensan go-librespot[3218]: time="2026-03-24T18:25:36+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:36 volumio-sensan go-librespot[3218]: time="2026-03-24T18:25:36+01:00" level=debug msg="app state loaded" Mar 24 18:25:36 volumio-sensan go-librespot[3218]: time="2026-03-24T18:25:36+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:36 volumio-sensan go-librespot[3218]: time="2026-03-24T18:25:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:36 volumio-sensan go-librespot[3218]: time="2026-03-24T18:25:36+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:25:36 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:36 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:38 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:38 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:39 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Mar 24 18:25:39 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:39 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:39 volumio-sensan go-librespot[3226]: go-librespot daemon starting... Mar 24 18:25:39 volumio-sensan go-librespot[3227]: time="2026-03-24T18:25:39+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:39 volumio-sensan go-librespot[3227]: time="2026-03-24T18:25:39+01:00" level=debug msg="app state loaded" Mar 24 18:25:39 volumio-sensan go-librespot[3227]: time="2026-03-24T18:25:39+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:39 volumio-sensan go-librespot[3227]: time="2026-03-24T18:25:39+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:39 volumio-sensan go-librespot[3227]: time="2026-03-24T18:25: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" Mar 24 18:25:39 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:39 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:41 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:41 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:43 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Mar 24 18:25:43 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:43 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:43 volumio-sensan go-librespot[3249]: go-librespot daemon starting... Mar 24 18:25:43 volumio-sensan go-librespot[3250]: time="2026-03-24T18:25:43+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:43 volumio-sensan go-librespot[3250]: time="2026-03-24T18:25:43+01:00" level=debug msg="app state loaded" Mar 24 18:25:43 volumio-sensan go-librespot[3250]: time="2026-03-24T18:25:43+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:43 volumio-sensan go-librespot[3250]: time="2026-03-24T18:25:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:43 volumio-sensan go-librespot[3250]: time="2026-03-24T18:25: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" Mar 24 18:25:43 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:43 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:44 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:44 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:46 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Mar 24 18:25:46 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:46 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:46 volumio-sensan go-librespot[3259]: go-librespot daemon starting... Mar 24 18:25:46 volumio-sensan go-librespot[3260]: time="2026-03-24T18:25:46+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:46 volumio-sensan go-librespot[3260]: time="2026-03-24T18:25:46+01:00" level=debug msg="app state loaded" Mar 24 18:25:46 volumio-sensan go-librespot[3260]: time="2026-03-24T18:25:46+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:46 volumio-sensan go-librespot[3260]: time="2026-03-24T18:25:46+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:46 volumio-sensan go-librespot[3260]: time="2026-03-24T18:25:46+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:25:46 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:46 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:47 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:47 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:49 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Mar 24 18:25:49 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:49 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:49 volumio-sensan go-librespot[3268]: go-librespot daemon starting... Mar 24 18:25:49 volumio-sensan go-librespot[3270]: time="2026-03-24T18:25:49+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:49 volumio-sensan go-librespot[3270]: time="2026-03-24T18:25:49+01:00" level=debug msg="app state loaded" Mar 24 18:25:49 volumio-sensan go-librespot[3270]: time="2026-03-24T18:25:49+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:49 volumio-sensan go-librespot[3270]: time="2026-03-24T18:25:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:49 volumio-sensan go-librespot[3270]: time="2026-03-24T18:25:49+01:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Mar 24 18:25:49 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:49 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:50 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:50 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:51 volumio-sensan dhcpcd[841]: eth0: carrier acquired Mar 24 18:25:51 volumio-sensan dhcpcd[841]: eth0: IAID 32:53:e0:93 Mar 24 18:25:51 volumio-sensan kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Mar 24 18:25:51 volumio-sensan dhcpcd[841]: eth0: rebinding lease of 192.168.18.13 Mar 24 18:25:51 volumio-sensan volumio[1747]: info: Received Get System Info Mar 24 18:25:51 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 18:25:51 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 18:25:51 volumio-sensan volumio[1747]: info: Discovery: Getting this device information Mar 24 18:25:51 volumio-sensan volumio[1747]: info: CoreCommandRouter::volumioGetState Mar 24 18:25:51 volumio-sensan volumio[1747]: info: CorePlayQueue::getTrack 0 Mar 24 18:25:51 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 18:25:51 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 18:25:51 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 18:25:51 volumio-sensan ifplugd(eth0)[1023]: Link beat detected. Mar 24 18:25:51 volumio-sensan dhcpcd[841]: eth0: probing address 192.168.18.13/24 Mar 24 18:25:51 volumio-sensan ifplugd(eth0)[1023]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Mar 24 18:25:51 volumio-sensan ifplugd(eth0)[1023]: client: sending commands to dhcpcd process Mar 24 18:25:51 volumio-sensan dhcpcd[841]: control command: dhcpcd eth0 Mar 24 18:25:51 volumio-sensan dhcpcd[841]: control_free: No such file or directory Mar 24 18:25:51 volumio-sensan ifplugd(eth0)[1023]: Program executed successfully. Mar 24 18:25:51 volumio-sensan dhcpcd[841]: eth0: soliciting an IPv6 router Mar 24 18:25:52 volumio-sensan volumio5-onboarding[1503]: time=2026-03-24T18:25:52.378+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 24 18:25:52 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: === SNM TRANSITION === Mar 24 18:25:52 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Mar 24 18:25:52 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: New ethernet state: connected Mar 24 18:25:52 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: Single Network Mode: enabled Mar 24 18:25:52 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: First start: no Mar 24 18:25:52 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Mar 24 18:25:52 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: === END TRANSITION === Mar 24 18:25:52 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Mar 24 18:25:52 volumio-sensan sudo[3347]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Mar 24 18:25:52 volumio-sensan sudo[3347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 18:25:52 volumio-sensan dhcpcd[3348]: dhcpcd not running Mar 24 18:25:52 volumio-sensan sudo[3347]: pam_unix(sudo:session): session closed for user root Mar 24 18:25:52 volumio-sensan wireless.js[772]: dhcpcd not running Mar 24 18:25:52 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Mar 24 18:25:52 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Mar 24 18:25:52 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:52 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:52 volumio-sensan go-librespot[3352]: go-librespot daemon starting... Mar 24 18:25:52 volumio-sensan go-librespot[3354]: time="2026-03-24T18:25:52+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:52 volumio-sensan go-librespot[3354]: time="2026-03-24T18:25:52+01:00" level=debug msg="app state loaded" Mar 24 18:25:52 volumio-sensan go-librespot[3354]: time="2026-03-24T18:25:52+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:52 volumio-sensan go-librespot[3354]: time="2026-03-24T18:25:52+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:52 volumio-sensan go-librespot[3354]: time="2026-03-24T18:25: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" Mar 24 18:25:52 volumio-sensan systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Mar 24 18:25:52 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:52 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:52 volumio-sensan dnsmasq[1929]: exiting on receipt of SIGTERM Mar 24 18:25:52 volumio-sensan systemd[1]: dnsmasq.service: Deactivated successfully. Mar 24 18:25:52 volumio-sensan systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Mar 24 18:25:52 volumio-sensan systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Mar 24 18:25:53 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:53 volumio-sensan volumio[1747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:53 volumio-sensan dhcpcd[841]: wlan0: carrier lost Mar 24 18:25:53 volumio-sensan systemd[1]: hostapd.service: Deactivated successfully. Mar 24 18:25:53 volumio-sensan systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Mar 24 18:25:53 volumio-sensan sudo[3377]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 24 18:25:53 volumio-sensan sudo[3377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 18:25:53 volumio-sensan avahi-daemon[740]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 24 18:25:53 volumio-sensan avahi-daemon[740]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 24 18:25:53 volumio-sensan sudo[3377]: pam_unix(sudo:session): session closed for user root Mar 24 18:25:53 volumio-sensan avahi-daemon[740]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 24 18:25:53 volumio-sensan volumio[1747]: info: Discovery: A device disappeared from network Mar 24 18:25:53 volumio-sensan sudo[3383]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 24 18:25:53 volumio-sensan sudo[3383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 18:25:53 volumio-sensan systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 24 18:25:53 volumio-sensan systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Mar 24 18:25:53 volumio-sensan systemd[1]: welcome.service: Deactivated successfully. Mar 24 18:25:53 volumio-sensan systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 24 18:25:53 volumio-sensan systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 24 18:25:53 volumio-sensan systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 24 18:25:53 volumio-sensan sudo[3383]: pam_unix(sudo:session): session closed for user root Mar 24 18:25:53 volumio-sensan welcome[3385]: Resolved ip:[0] Mar 24 18:25:53 volumio-sensan volumio[1747]: info: Received Get System Info Mar 24 18:25:53 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 18:25:53 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 18:25:53 volumio-sensan volumio[1747]: info: Discovery: Getting this device information Mar 24 18:25:53 volumio-sensan volumio[1747]: info: CoreCommandRouter::volumioGetState Mar 24 18:25:53 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: Cleaning previous... Mar 24 18:25:53 volumio-sensan volumio[1747]: info: CorePlayQueue::getTrack 0 Mar 24 18:25:53 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 18:25:53 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 18:25:53 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 18:25:53 volumio-sensan sudo[3389]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 24 18:25:53 volumio-sensan systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 24 18:25:53 volumio-sensan sudo[3389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 18:25:53 volumio-sensan systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Mar 24 18:25:53 volumio-sensan sudo[3389]: pam_unix(sudo:session): session closed for user root Mar 24 18:25:53 volumio-sensan kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 24 18:25:54 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Mar 24 18:25:54 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 1ms Mar 24 18:25:54 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: dc:a6:32:53:e0:95) Mar 24 18:25:54 volumio-sensan sudo[3398]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 24 18:25:54 volumio-sensan sudo[3398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 18:25:54 volumio-sensan sudo[3398]: pam_unix(sudo:session): session closed for user root Mar 24 18:25:54 volumio-sensan sudo[3408]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 24 18:25:54 volumio-sensan sudo[3408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 18:25:54 volumio-sensan volumio[1747]: info: Received Get System Info Mar 24 18:25:54 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 18:25:54 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 18:25:54 volumio-sensan volumio[1747]: info: Discovery: Getting this device information Mar 24 18:25:54 volumio-sensan volumio[1747]: info: CoreCommandRouter::volumioGetState Mar 24 18:25:54 volumio-sensan volumio[1747]: info: CorePlayQueue::getTrack 0 Mar 24 18:25:54 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 18:25:54 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 18:25:54 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 18:25:55 volumio-sensan sudo[3408]: pam_unix(sudo:session): session closed for user root Mar 24 18:25:55 volumio-sensan wireless.js[772]: WIRELESS.JS - INFO: Regdomain already correct: CN Mar 24 18:25:55 volumio-sensan volumio[1747]: info: Received Get System Info Mar 24 18:25:55 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 18:25:55 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 18:25:55 volumio-sensan volumio[1747]: info: Discovery: Getting this device information Mar 24 18:25:55 volumio-sensan volumio[1747]: info: CoreCommandRouter::volumioGetState Mar 24 18:25:55 volumio-sensan volumio[1747]: info: CorePlayQueue::getTrack 0 Mar 24 18:25:55 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 18:25:55 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 18:25:55 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 18:25:55 volumio-sensan dhcpcd[841]: eth0: leased 192.168.18.13 for 3600 seconds Mar 24 18:25:55 volumio-sensan dhcpcd[841]: eth0: adding route to 192.168.18.0/24 Mar 24 18:25:55 volumio-sensan avahi-daemon[740]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.18.13. Mar 24 18:25:55 volumio-sensan avahi-daemon[740]: New relevant interface eth0.IPv4 for mDNS. Mar 24 18:25:55 volumio-sensan avahi-daemon[740]: Registering new address record for 192.168.18.13 on eth0.IPv4. Mar 24 18:25:55 volumio-sensan dhcpcd[841]: eth0: adding default route via 192.168.18.1 Mar 24 18:25:55 volumio-sensan systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 24 18:25:55 volumio-sensan systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 24 18:25:55 volumio-sensan systemd[1]: welcome.service: Deactivated successfully. Mar 24 18:25:55 volumio-sensan systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 24 18:25:55 volumio-sensan systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 24 18:25:55 volumio-sensan systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 24 18:25:55 volumio-sensan welcome[3429]: Resolved ip:[1] 192.168.18.13 Mar 24 18:25:55 volumio-sensan systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 24 18:25:55 volumio-sensan systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 24 18:25:56 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Mar 24 18:25:56 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:56 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:56 volumio-sensan go-librespot[3438]: go-librespot daemon starting... Mar 24 18:25:56 volumio-sensan go-librespot[3439]: time="2026-03-24T18:25:56+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:56 volumio-sensan go-librespot[3439]: time="2026-03-24T18:25:56+01:00" level=debug msg="app state loaded" Mar 24 18:25:56 volumio-sensan go-librespot[3439]: time="2026-03-24T18:25:56+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:56 volumio-sensan go-librespot[3439]: time="2026-03-24T18:25:56+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:56 volumio-sensan volumio[1747]: info: Received Get System Info Mar 24 18:25:56 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 18:25:56 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 18:25:56 volumio-sensan volumio[1747]: info: Discovery: Getting this device information Mar 24 18:25:56 volumio-sensan volumio[1747]: info: CoreCommandRouter::volumioGetState Mar 24 18:25:56 volumio-sensan volumio[1747]: info: CorePlayQueue::getTrack 0 Mar 24 18:25:56 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 18:25:56 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 18:25:56 volumio-sensan volumio[1747]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 18:25:56 volumio-sensan volumio[1747]: info: Initializing connection to go-librespot Websocket Mar 24 18:25:56 volumio-sensan go-librespot[3439]: time="2026-03-24T18:25:56+01:00" level=debug msg="new websocket client" Mar 24 18:25:56 volumio-sensan volumio[1747]: info: Connection to go-librespot Websocket established Mar 24 18:25:56 volumio-sensan go-librespot[3439]: time="2026-03-24T18:25: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\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-24T18:25:56+01:00 is before 2026-07-09T00:00:00Z" Mar 24 18:25:56 volumio-sensan systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 24 18:25:56 volumio-sensan systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 24 18:25:56 volumio-sensan volumio[1747]: info: Connection to go-librespot Websocket closed Mar 24 18:25:56 volumio-sensan volumio[1747]: info: Discovery: adding d8c82640-1f45-447a-b0ad-a88502bc528c Mar 24 18:25:56 volumio-sensan volumio[1747]: info: Discovery: Found device Volumio SenSan Mar 24 18:25:56 volumio-sensan volumio[1747]: info: CoreCommandRouter::volumioGetState Mar 24 18:25:56 volumio-sensan volumio[1747]: info: CorePlayQueue::getTrack 0 Mar 24 18:25:56 volumio-sensan ntpd[1010]: IO: Listen normally on 5 eth0 192.168.18.13:123 Mar 24 18:25:56 volumio-sensan ntpd[1010]: IO: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=258 secs Mar 24 18:25:56 volumio-sensan ntpd[1010]: IO: new interface(s) found: waking up resolver Mar 24 18:25:56 volumio-sensan ntpd[1010]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 24 18:25:57 volumio-sensan ntpd[1010]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Mar 24 18:25:57 volumio-sensan ntpd[1010]: DNS: Pool taking: 194.164.164.175 Mar 24 18:25:57 volumio-sensan ntpd[1010]: DNS: Pool taking: 82.223.222.97 Mar 24 18:25:57 volumio-sensan ntpd[1010]: DNS: Pool skipping: 162.159.200.1 Mar 24 18:25:57 volumio-sensan ntpd[1010]: DNS: Pool skipping: 172.233.111.111 Mar 24 18:25:57 volumio-sensan ntpd[1010]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Mar 24 18:25:57 volumio-sensan volumio5-onboarding[1503]: time=2026-03-24T18:25:57.824+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 24 18:25:59 volumio-sensan volumio[1747]: info: Getting Spotify volume Mar 24 18:25:59 volumio-sensan volumio[1747]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 18:25:59 volumio-sensan volumio[1747]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 24 18:25:59 volumio-sensan volumio[1747]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 24 18:25:59 volumio-sensan volumio[1747]: errno: -111, Mar 24 18:25:59 volumio-sensan volumio[1747]: code: 'ECONNREFUSED', Mar 24 18:25:59 volumio-sensan volumio[1747]: syscall: 'connect', Mar 24 18:25:59 volumio-sensan volumio[1747]: address: '127.0.0.1', Mar 24 18:25:59 volumio-sensan volumio[1747]: port: 9879, Mar 24 18:25:59 volumio-sensan volumio[1747]: response: undefined Mar 24 18:25:59 volumio-sensan volumio[1747]: } Mar 24 18:25:59 volumio-sensan volumio[1747]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 18:25:59 volumio-sensan systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Mar 24 18:25:59 volumio-sensan systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:59 volumio-sensan systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 24 18:25:59 volumio-sensan go-librespot[3461]: go-librespot daemon starting... Mar 24 18:25:59 volumio-sensan go-librespot[3462]: time="2026-03-24T18:25:59+01:00" level=info msg="running go-librespot 0.7.1" Mar 24 18:25:59 volumio-sensan go-librespot[3462]: time="2026-03-24T18:25:59+01:00" level=debug msg="app state loaded" Mar 24 18:25:59 volumio-sensan go-librespot[3462]: time="2026-03-24T18:25:59+01:00" level=debug msg="stored credentials not found" Mar 24 18:25:59 volumio-sensan go-librespot[3462]: time="2026-03-24T18:25:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 24 18:25:59 volumio-sensan sudo[3471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 18:24' Mar 24 18:25:59 volumio-sensan sudo[3471]: 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"