Dec 08 16:02:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 08 16:02:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:00 volumio go-librespot[1697]: go-librespot daemon starting... Dec 08 16:02:00 volumio go-librespot[1698]: time="2025-12-08T16:02:00+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:00 volumio go-librespot[1698]: time="2025-12-08T16:02:00+02:00" level=debug msg="app state loaded" Dec 08 16:02:00 volumio go-librespot[1698]: time="2025-12-08T16:02:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:00 volumio go-librespot[1698]: time="2025-12-08T16:02:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:02 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:02 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 08 16:02:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:04 volumio go-librespot[1705]: go-librespot daemon starting... Dec 08 16:02:04 volumio go-librespot[1706]: time="2025-12-08T16:02:04+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:04 volumio go-librespot[1706]: time="2025-12-08T16:02:04+02:00" level=debug msg="app state loaded" Dec 08 16:02:04 volumio go-librespot[1706]: time="2025-12-08T16:02:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:04 volumio go-librespot[1706]: time="2025-12-08T16:02:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:05 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:05 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 08 16:02:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:07 volumio go-librespot[1730]: go-librespot daemon starting... Dec 08 16:02:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:07 volumio go-librespot[1731]: time="2025-12-08T16:02:07+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:07 volumio go-librespot[1731]: time="2025-12-08T16:02:07+02:00" level=debug msg="app state loaded" Dec 08 16:02:07 volumio go-librespot[1731]: time="2025-12-08T16:02:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:07 volumio go-librespot[1731]: time="2025-12-08T16:02:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:08 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:08 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 08 16:02:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:10 volumio go-librespot[1737]: go-librespot daemon starting... Dec 08 16:02:10 volumio go-librespot[1738]: time="2025-12-08T16:02:10+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:10 volumio go-librespot[1738]: time="2025-12-08T16:02:10+02:00" level=debug msg="app state loaded" Dec 08 16:02:10 volumio go-librespot[1738]: time="2025-12-08T16:02:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:10 volumio go-librespot[1738]: time="2025-12-08T16:02:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:11 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:11 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 08 16:02:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:13 volumio go-librespot[1744]: go-librespot daemon starting... Dec 08 16:02:13 volumio go-librespot[1745]: time="2025-12-08T16:02:13+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:13 volumio go-librespot[1745]: time="2025-12-08T16:02:13+02:00" level=debug msg="app state loaded" Dec 08 16:02:13 volumio go-librespot[1745]: time="2025-12-08T16:02:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:13 volumio go-librespot[1745]: time="2025-12-08T16:02:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:14 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:14 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 08 16:02:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:17 volumio go-librespot[1765]: go-librespot daemon starting... Dec 08 16:02:17 volumio go-librespot[1766]: time="2025-12-08T16:02:17+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:17 volumio go-librespot[1766]: time="2025-12-08T16:02:17+02:00" level=debug msg="app state loaded" Dec 08 16:02:17 volumio go-librespot[1766]: time="2025-12-08T16:02:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:17 volumio go-librespot[1766]: time="2025-12-08T16:02:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:17 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:17 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Dec 08 16:02:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:20 volumio go-librespot[1772]: go-librespot daemon starting... Dec 08 16:02:20 volumio go-librespot[1773]: time="2025-12-08T16:02:20+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:20 volumio go-librespot[1773]: time="2025-12-08T16:02:20+02:00" level=debug msg="app state loaded" Dec 08 16:02:20 volumio go-librespot[1773]: time="2025-12-08T16:02:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:20 volumio go-librespot[1773]: time="2025-12-08T16:02:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:20 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:20 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:21 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:21 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 08 16:02:23 volumio systemd[1]: nmbd.service: start operation timed out. Terminating. Dec 08 16:02:23 volumio systemd[1]: nmbd.service: Failed with result 'timeout'. Dec 08 16:02:23 volumio systemd[1]: Failed to start nmbd.service - Samba NMB Daemon. Dec 08 16:02:23 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Dec 08 16:02:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Dec 08 16:02:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:23 volumio go-librespot[1783]: go-librespot daemon starting... Dec 08 16:02:23 volumio go-librespot[1784]: time="2025-12-08T16:02:23+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:23 volumio go-librespot[1784]: time="2025-12-08T16:02:23+02:00" level=debug msg="app state loaded" Dec 08 16:02:23 volumio go-librespot[1784]: time="2025-12-08T16:02:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:23 volumio go-librespot[1784]: time="2025-12-08T16:02:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:23 volumio winbindd[1782]: [2025/12/08 16:02:23.752436, 0] ../../source3/winbindd/winbindd.c:1440(main) Dec 08 16:02:23 volumio winbindd[1782]: winbindd version 4.17.8-Raspbian started. Dec 08 16:02:23 volumio winbindd[1782]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 08 16:02:23 volumio winbindd[1782]: [2025/12/08 16:02:23.765958, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Dec 08 16:02:23 volumio winbindd[1782]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 08 16:02:23 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Dec 08 16:02:23 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 08 16:02:23 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:23 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:24 volumio smbd[1797]: [2025/12/08 16:02:24.154577, 0] ../../source3/smbd/server.c:1741(main) Dec 08 16:02:24 volumio smbd[1797]: smbd version 4.17.8-Raspbian started. Dec 08 16:02:24 volumio smbd[1797]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 08 16:02:25 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 08 16:02:25 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Dec 08 16:02:25 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Dec 08 16:02:25 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 08 16:02:25 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 08 16:02:25 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 08 16:02:25 volumio systemd[1]: Startup finished in 13.933s (kernel) + 1min 38.507s (userspace) = 1min 52.441s. Dec 08 16:02:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Dec 08 16:02:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:26 volumio go-librespot[1834]: go-librespot daemon starting... Dec 08 16:02:26 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:26 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:26 volumio go-librespot[1835]: time="2025-12-08T16:02:26+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:26 volumio go-librespot[1835]: time="2025-12-08T16:02:26+02:00" level=debug msg="app state loaded" Dec 08 16:02:26 volumio go-librespot[1835]: time="2025-12-08T16:02:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:26 volumio go-librespot[1835]: time="2025-12-08T16:02:26+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:29 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:29 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Dec 08 16:02:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:30 volumio go-librespot[1842]: go-librespot daemon starting... Dec 08 16:02:30 volumio go-librespot[1843]: time="2025-12-08T16:02:30+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:30 volumio go-librespot[1843]: time="2025-12-08T16:02:30+02:00" level=debug msg="app state loaded" Dec 08 16:02:30 volumio go-librespot[1843]: time="2025-12-08T16:02:30+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:30 volumio go-librespot[1843]: time="2025-12-08T16:02:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:32 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:32 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Dec 08 16:02:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:33 volumio go-librespot[1849]: go-librespot daemon starting... Dec 08 16:02:33 volumio go-librespot[1850]: time="2025-12-08T16:02:33+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:33 volumio go-librespot[1850]: time="2025-12-08T16:02:33+02:00" level=debug msg="app state loaded" Dec 08 16:02:33 volumio go-librespot[1850]: time="2025-12-08T16:02:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:33 volumio go-librespot[1850]: time="2025-12-08T16:02:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:36 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:36 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Dec 08 16:02:36 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 08 16:02:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:36 volumio go-librespot[1870]: go-librespot daemon starting... Dec 08 16:02:36 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 08 16:02:36 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 08 16:02:36 volumio go-librespot[1871]: time="2025-12-08T16:02:36+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:36 volumio go-librespot[1871]: time="2025-12-08T16:02:36+02:00" level=debug msg="app state loaded" Dec 08 16:02:36 volumio go-librespot[1871]: time="2025-12-08T16:02:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:36 volumio go-librespot[1871]: time="2025-12-08T16:02:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:39 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:39 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Dec 08 16:02:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:39 volumio go-librespot[1885]: go-librespot daemon starting... Dec 08 16:02:39 volumio go-librespot[1886]: time="2025-12-08T16:02:39+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:39 volumio go-librespot[1886]: time="2025-12-08T16:02:39+02:00" level=debug msg="app state loaded" Dec 08 16:02:39 volumio go-librespot[1886]: time="2025-12-08T16:02:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:39 volumio go-librespot[1886]: time="2025-12-08T16:02:39+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:42 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:42 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Dec 08 16:02:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:43 volumio go-librespot[1892]: go-librespot daemon starting... Dec 08 16:02:43 volumio go-librespot[1893]: time="2025-12-08T16:02:43+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:43 volumio go-librespot[1893]: time="2025-12-08T16:02:43+02:00" level=debug msg="app state loaded" Dec 08 16:02:43 volumio go-librespot[1893]: time="2025-12-08T16:02:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:43 volumio go-librespot[1893]: time="2025-12-08T16:02:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:45 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:45 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Dec 08 16:02:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:46 volumio go-librespot[1913]: go-librespot daemon starting... Dec 08 16:02:46 volumio go-librespot[1914]: time="2025-12-08T16:02:46+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:46 volumio go-librespot[1914]: time="2025-12-08T16:02:46+02:00" level=debug msg="app state loaded" Dec 08 16:02:46 volumio go-librespot[1914]: time="2025-12-08T16:02:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:46 volumio go-librespot[1914]: time="2025-12-08T16:02:46+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:48 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:48 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:48 volumio dhcpcd[864]: eth0: carrier acquired Dec 08 16:02:48 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx Dec 08 16:02:48 volumio dhcpcd[864]: eth0: IAID 01:15:5e:bd Dec 08 16:02:48 volumio dhcpcd[864]: eth0: adding address fe80::4f06:38e7:22a7:7585 Dec 08 16:02:48 volumio dhcpcd[864]: ipv6_addaddr1: Permission denied Dec 08 16:02:48 volumio dhcpcd[864]: eth0: soliciting an IPv6 router Dec 08 16:02:48 volumio ifplugd(eth0)[1026]: Link beat detected. Dec 08 16:02:49 volumio ifplugd(eth0)[1026]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 08 16:02:49 volumio ifplugd(eth0)[1026]: client: sending commands to dhcpcd process Dec 08 16:02:49 volumio dhcpcd[864]: control_free: No such file or directory Dec 08 16:02:49 volumio dhcpcd[864]: control command: dhcpcd eth0 Dec 08 16:02:49 volumio ifplugd(eth0)[1026]: Program executed successfully. Dec 08 16:02:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Dec 08 16:02:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:49 volumio go-librespot[1972]: go-librespot daemon starting... Dec 08 16:02:49 volumio go-librespot[1973]: time="2025-12-08T16:02:49+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:49 volumio go-librespot[1973]: time="2025-12-08T16:02:49+02:00" level=debug msg="app state loaded" Dec 08 16:02:49 volumio go-librespot[1973]: time="2025-12-08T16:02:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:49 volumio go-librespot[1973]: time="2025-12-08T16:02:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:49 volumio dhcpcd[864]: eth0: rebinding lease of 192.168.1.152 Dec 08 16:02:49 volumio dhcpcd[864]: eth0: probing address 192.168.1.152/24 Dec 08 16:02:50 volumio dhcpcd[864]: eth0: carrier lost Dec 08 16:02:50 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 08 16:02:51 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:51 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:51 volumio ifplugd(eth0)[1026]: Link beat lost. Dec 08 16:02:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Dec 08 16:02:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:52 volumio go-librespot[1995]: go-librespot daemon starting... Dec 08 16:02:52 volumio go-librespot[1996]: time="2025-12-08T16:02:52+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:52 volumio go-librespot[1996]: time="2025-12-08T16:02:52+02:00" level=debug msg="app state loaded" Dec 08 16:02:52 volumio go-librespot[1996]: time="2025-12-08T16:02:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:52 volumio go-librespot[1996]: time="2025-12-08T16:02:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:54 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:54 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Dec 08 16:02:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:56 volumio go-librespot[2016]: go-librespot daemon starting... Dec 08 16:02:56 volumio go-librespot[2017]: time="2025-12-08T16:02:56+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:56 volumio go-librespot[2017]: time="2025-12-08T16:02:56+02:00" level=debug msg="app state loaded" Dec 08 16:02:56 volumio go-librespot[2017]: time="2025-12-08T16:02:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:56 volumio go-librespot[2017]: time="2025-12-08T16:02:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:02:57 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:02:57 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:02:57 volumio dhcpcd[864]: eth0: carrier acquired Dec 08 16:02:57 volumio dhcpcd[864]: eth0: IAID 01:15:5e:bd Dec 08 16:02:57 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx Dec 08 16:02:58 volumio ifplugd(eth0)[1026]: Link beat detected. Dec 08 16:02:58 volumio dhcpcd[864]: eth0: soliciting an IPv6 router Dec 08 16:02:59 volumio dhcpcd[864]: eth0: rebinding lease of 192.168.1.152 Dec 08 16:02:59 volumio dhcpcd[864]: eth0: NAK: wrong network from 192.168.0.254 Dec 08 16:02:59 volumio dhcpcd[864]: eth0: message: wrong network Dec 08 16:02:59 volumio dhcpcd[864]: eth0: soliciting a DHCP lease Dec 08 16:02:59 volumio dhcpcd[864]: eth0: offered 192.168.1.152 from 192.168.1.1 Dec 08 16:02:59 volumio dhcpcd[864]: eth0: probing address 192.168.1.152/24 Dec 08 16:02:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Dec 08 16:02:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:02:59 volumio go-librespot[2034]: go-librespot daemon starting... Dec 08 16:02:59 volumio go-librespot[2035]: time="2025-12-08T16:02:59+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:02:59 volumio go-librespot[2035]: time="2025-12-08T16:02:59+02:00" level=debug msg="app state loaded" Dec 08 16:02:59 volumio go-librespot[2035]: time="2025-12-08T16:02:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:02:59 volumio go-librespot[2035]: time="2025-12-08T16:02:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:02:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:02:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:03:00 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:03:00 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:03:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Dec 08 16:03:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:03:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:03:02 volumio go-librespot[2041]: go-librespot daemon starting... Dec 08 16:03:02 volumio go-librespot[2042]: time="2025-12-08T16:03:02+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:03:02 volumio go-librespot[2042]: time="2025-12-08T16:03:02+02:00" level=debug msg="app state loaded" Dec 08 16:03:02 volumio go-librespot[2042]: time="2025-12-08T16:03:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:03:02 volumio go-librespot[2042]: time="2025-12-08T16:03:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 08 16:03:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:03:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:03:03 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:03:03 volumio volumio[1162]: info: Error connecting to go-librespot Websocket: AggregateError Dec 08 16:03:03 volumio dhcpcd[864]: eth0: leased 192.168.1.152 for 43200 seconds Dec 08 16:03:03 volumio avahi-daemon[785]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.152. Dec 08 16:03:03 volumio avahi-daemon[785]: New relevant interface eth0.IPv4 for mDNS. Dec 08 16:03:03 volumio avahi-daemon[785]: Registering new address record for 192.168.1.152 on eth0.IPv4. Dec 08 16:03:03 volumio dhcpcd[864]: eth0: adding route to 192.168.1.0/24 Dec 08 16:03:03 volumio dhcpcd[864]: eth0: adding default route via 192.168.1.1 Dec 08 16:03:03 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 08 16:03:03 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 08 16:03:03 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 08 16:03:03 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 08 16:03:03 volumio welcome[2066]: Resolved ip:[1] 192.168.1.152 Dec 08 16:03:04 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 08 16:03:04 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 08 16:03:04 volumio volumio[1162]: info: Discovery: this is already registered, 419fa586-d011-41c7-90ac-af217a02a2d6 Dec 08 16:03:04 volumio volumio[1162]: info: Discovery: Found device Volumio Dec 08 16:03:04 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Dec 08 16:03:04 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Dec 08 16:03:05 volumio ntpd[1027]: IO: Listen normally on 3 eth0 192.168.1.152:123 Dec 08 16:03:05 volumio ntpd[1027]: IO: new interface(s) found: waking up resolver Dec 08 16:03:05 volumio ntpd[1027]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 08 16:03:05 volumio ntpd[1027]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 08 16:03:05 volumio ntpd[1027]: DNS: Pool taking: 65.109.231.188 Dec 08 16:03:05 volumio ntpd[1027]: DNS: Pool taking: 95.217.220.251 Dec 08 16:03:05 volumio ntpd[1027]: DNS: Pool taking: 162.159.200.123 Dec 08 16:03:05 volumio ntpd[1027]: DNS: Pool taking: 95.217.251.167 Dec 08 16:03:05 volumio ntpd[1027]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 08 16:03:05 volumio volumio[1162]: info: Volumio Network Manager: Network status updated: 1 Dec 08 16:03:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Dec 08 16:03:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:03:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:03:05 volumio go-librespot[2090]: go-librespot daemon starting... Dec 08 16:03:05 volumio go-librespot[2091]: time="2025-12-08T16:03:05+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:03:05 volumio go-librespot[2091]: time="2025-12-08T16:03:05+02:00" level=debug msg="app state loaded" Dec 08 16:03:05 volumio go-librespot[2091]: time="2025-12-08T16:03:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:03:06 volumio ntpd[1027]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 08 16:03:06 volumio ntpd[1027]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 08 16:03:06 volumio ntpd[1027]: DNS: Pool taking: 194.100.49.147 Dec 08 16:03:06 volumio ntpd[1027]: DNS: Pool taking: 62.241.198.253 Dec 08 16:03:06 volumio ntpd[1027]: DNS: Pool taking: 95.216.78.223 Dec 08 16:03:06 volumio ntpd[1027]: DNS: Pool taking: 95.216.154.135 Dec 08 16:03:06 volumio ntpd[1027]: DNS: Pool taking: 2a01:4f9:c010:1eba::1 Dec 08 16:03:06 volumio ntpd[1027]: DNS: Pool taking: 2a01:4f9:c012:46b2::123 Dec 08 16:03:06 volumio ntpd[1027]: DNS: Pool taking: 2a01:4f9:4b:f32e::123 Dec 08 16:03:06 volumio ntpd[1027]: DNS: Pool taking: 2a01:4f9:c012:963::1 Dec 08 16:03:06 volumio ntpd[1027]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 08 16:03:06 volumio volumio[1162]: info: Initializing connection to go-librespot Websocket Dec 08 16:03:06 volumio volumio[1162]: info: Connection to go-librespot Websocket established Dec 08 16:03:06 volumio go-librespot[2091]: time="2025-12-08T16:03:06+02:00" level=debug msg="new websocket client" Dec 08 16:03:06 volumio go-librespot[2091]: time="2025-12-08T16:03:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 08 16:03:06 volumio go-librespot[2091]: time="2025-12-08T16:03:06+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 08 16:03:06 volumio go-librespot[2091]: time="2025-12-08T16:03:06+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 08 16:03:06 volumio go-librespot[2091]: time="2025-12-08T16:03:06+02:00" level=info msg="zeroconf server listening on port 35405" Dec 08 16:03:06 volumio go-librespot[2091]: time="2025-12-08T16:03:06+02:00" level=debug msg="obtained new client token: AAA5swVIk+fJBXh5UT9E2Z9/sKKI011RC5p0YZHGIORH559Wa3bENffNNH+KlbZgFV9zeyvs29STU50WOg71Y9VadYiq97B8kq6kdEjJgqLAo9LinQCnOCG6GuAwntmKE/qXsooWQrBtqKCifqDVJv6cbtC9ZQP/ZcVOf8eYTJUSwvXoQIfhMfH82Hlck9K5GF6Emyb60gfuShxRoxhs7xbUxfakk7WoKZVYYayvlWfLsJYNjCMOX4irag==" Dec 08 16:03:06 volumio go-librespot[2091]: time="2025-12-08T16:03:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 08 16:03:07 volumio ntpd[1027]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 08 16:03:07 volumio dhcpcd[864]: eth0: carrier lost Dec 08 16:03:07 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 08 16:03:07 volumio avahi-daemon[785]: Withdrawing address record for 192.168.1.152 on eth0. Dec 08 16:03:07 volumio avahi-daemon[785]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.152. Dec 08 16:03:07 volumio avahi-daemon[785]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 08 16:03:07 volumio dhcpcd[864]: eth0: deleting route to 192.168.1.0/24 Dec 08 16:03:07 volumio dhcpcd[864]: eth0: deleting default route via 192.168.1.1 Dec 08 16:03:07 volumio volumio[1162]: info: Discovery: A device disappeared from network Dec 08 16:03:07 volumio volumio[1162]: info: Discovery: Device volumio disappeared from network Dec 08 16:03:07 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 08 16:03:07 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 08 16:03:07 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 08 16:03:07 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 08 16:03:07 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 08 16:03:07 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 08 16:03:07 volumio welcome[2138]: Resolved ip:[0] Dec 08 16:03:07 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 08 16:03:07 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 08 16:03:07 volumio ifplugd(eth0)[1026]: Link beat lost. Dec 08 16:03:09 volumio ntpd[1027]: IO: Deleting interface #3 eth0, 192.168.1.152#123, interface stats: received=8, sent=12, dropped=8, active_time=4 secs Dec 08 16:03:09 volumio ntpd[1027]: PROTO: 95.216.154.135 unlink local addr 192.168.1.152 -> Dec 08 16:03:09 volumio ntpd[1027]: PROTO: 95.216.78.223 unlink local addr 192.168.1.152 -> Dec 08 16:03:09 volumio ntpd[1027]: PROTO: 62.241.198.253 unlink local addr 192.168.1.152 -> Dec 08 16:03:09 volumio ntpd[1027]: PROTO: 194.100.49.147 unlink local addr 192.168.1.152 -> Dec 08 16:03:09 volumio ntpd[1027]: PROTO: 95.217.251.167 unlink local addr 192.168.1.152 -> Dec 08 16:03:09 volumio ntpd[1027]: PROTO: 162.159.200.123 unlink local addr 192.168.1.152 -> Dec 08 16:03:09 volumio ntpd[1027]: PROTO: 95.217.220.251 unlink local addr 192.168.1.152 -> Dec 08 16:03:09 volumio ntpd[1027]: PROTO: 65.109.231.188 unlink local addr 192.168.1.152 -> Dec 08 16:03:09 volumio volumio[1162]: info: Getting Spotify volume Dec 08 16:03:09 volumio volumio[1162]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 08 16:03:09 volumio volumio[1162]: info: CoreCommandRouter::volumioGetState Dec 08 16:03:09 volumio volumio[1162]: info: CorePlayQueue::getTrack 0 Dec 08 16:03:12 volumio ntpd[1027]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 08 16:03:12 volumio ntpd[1027]: DNS: dns_check: DNS error: -11, System error Dec 08 16:03:12 volumio ntpd[1027]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Dec 08 16:03:13 volumio ntpd[1027]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 08 16:03:13 volumio ntpd[1027]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 08 16:03:13 volumio ntpd[1027]: DNS: dns_check: DNS error: -11, System error Dec 08 16:03:13 volumio ntpd[1027]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 08 16:03:14 volumio dhcpcd[864]: eth0: carrier acquired Dec 08 16:03:14 volumio dhcpcd[864]: eth0: IAID 01:15:5e:bd Dec 08 16:03:14 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx Dec 08 16:03:14 volumio dhcpcd[864]: eth0: soliciting an IPv6 router Dec 08 16:03:14 volumio ifplugd(eth0)[1026]: Link beat detected. Dec 08 16:03:15 volumio volumio[1162]: info: Volumio Network Manager: Network status updated: 0 Dec 08 16:03:16 volumio dhcpcd[864]: eth0: rebinding lease of 192.168.1.152 Dec 08 16:03:16 volumio dhcpcd[864]: eth0: probing address 192.168.1.152/24 Dec 08 16:03:20 volumio dhcpcd[864]: eth0: leased 192.168.1.152 for 43200 seconds Dec 08 16:03:20 volumio avahi-daemon[785]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.152. Dec 08 16:03:20 volumio avahi-daemon[785]: New relevant interface eth0.IPv4 for mDNS. Dec 08 16:03:20 volumio avahi-daemon[785]: Registering new address record for 192.168.1.152 on eth0.IPv4. Dec 08 16:03:20 volumio dhcpcd[864]: eth0: adding route to 192.168.1.0/24 Dec 08 16:03:20 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 08 16:03:20 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 08 16:03:20 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 08 16:03:20 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 08 16:03:20 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 08 16:03:21 volumio dhcpcd[864]: eth0: adding default route via 192.168.1.1 Dec 08 16:03:21 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 08 16:03:21 volumio welcome[2170]: Resolved ip:[1] 192.168.1.152 Dec 08 16:03:21 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 08 16:03:21 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 08 16:03:21 volumio go-librespot[2091]: time="2025-12-08T16:03:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.1.152:50186->34.158.1.133:4070: read: connection reset by peer" Dec 08 16:03:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 16:03:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 16:03:21 volumio volumio[1162]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 16:03:21 volumio volumio[1162]: Error: socket hang up Dec 08 16:03:21 volumio volumio[1162]: at connResetException (node:internal/errors:720:14) Dec 08 16:03:21 volumio volumio[1162]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 08 16:03:21 volumio volumio[1162]: at Socket.emit (node:events:526:35) Dec 08 16:03:21 volumio volumio[1162]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 08 16:03:21 volumio volumio[1162]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 08 16:03:21 volumio volumio[1162]: code: 'ECONNRESET', Dec 08 16:03:21 volumio volumio[1162]: response: undefined Dec 08 16:03:21 volumio volumio[1162]: } Dec 08 16:03:21 volumio volumio[1162]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 16:03:22 volumio ntpd[1027]: IO: Listen normally on 4 eth0 192.168.1.152:123 Dec 08 16:03:22 volumio ntpd[1027]: IO: new interface(s) found: waking up resolver Dec 08 16:03:22 volumio ntpd[1027]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 08 16:03:22 volumio ntpd[1027]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 08 16:03:22 volumio ntpd[1027]: DNS: Pool skipping: 95.217.251.167 Dec 08 16:03:22 volumio ntpd[1027]: DNS: Pool skipping: 162.159.200.123 Dec 08 16:03:22 volumio ntpd[1027]: DNS: Pool skipping: 95.217.220.251 Dec 08 16:03:22 volumio ntpd[1027]: DNS: Pool skipping: 65.109.231.188 Dec 08 16:03:22 volumio ntpd[1027]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 08 16:03:23 volumio ntpd[1027]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 08 16:03:23 volumio ntpd[1027]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 08 16:03:23 volumio ntpd[1027]: DNS: Pool skipping: 194.100.49.147 Dec 08 16:03:23 volumio ntpd[1027]: DNS: Pool skipping: 95.216.154.135 Dec 08 16:03:23 volumio ntpd[1027]: DNS: Pool skipping: 95.216.78.223 Dec 08 16:03:23 volumio ntpd[1027]: DNS: Pool skipping: 62.241.198.253 Dec 08 16:03:23 volumio ntpd[1027]: DNS: Pool skipping: 2a01:4f9:c012:963::1 Dec 08 16:03:23 volumio ntpd[1027]: DNS: Pool skipping: 2a01:4f9:4b:f32e::123 Dec 08 16:03:23 volumio ntpd[1027]: DNS: Pool skipping: 2a01:4f9:c012:46b2::123 Dec 08 16:03:23 volumio ntpd[1027]: DNS: Pool skipping: 2a01:4f9:c010:1eba::1 Dec 08 16:03:23 volumio ntpd[1027]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 08 16:03:24 volumio ntpd[1027]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 08 16:03:24 volumio ntpd[1027]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 08 16:03:24 volumio ntpd[1027]: DNS: Pool taking: 185.251.115.30 Dec 08 16:03:24 volumio ntpd[1027]: DNS: Pool taking: 65.109.9.242 Dec 08 16:03:24 volumio ntpd[1027]: DNS: Pool taking: 95.216.100.21 Dec 08 16:03:24 volumio ntpd[1027]: DNS: Pool taking: 77.42.45.179 Dec 08 16:03:24 volumio ntpd[1027]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 08 16:03:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Dec 08 16:03:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:03:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 16:03:24 volumio go-librespot[2203]: go-librespot daemon starting... Dec 08 16:03:24 volumio go-librespot[2204]: time="2025-12-08T16:03:24+02:00" level=info msg="running go-librespot 0.4.0" Dec 08 16:03:24 volumio go-librespot[2204]: time="2025-12-08T16:03:24+02:00" level=debug msg="app state loaded" Dec 08 16:03:24 volumio go-librespot[2204]: time="2025-12-08T16:03:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 16:03:25 volumio ntpd[1027]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 08 16:03:25 volumio ntpd[1027]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 08 16:03:25 volumio ntpd[1027]: DNS: Pool taking: 195.148.70.12 Dec 08 16:03:25 volumio ntpd[1027]: DNS: Pool taking: 135.181.151.153 Dec 08 16:03:25 volumio ntpd[1027]: DNS: Pool taking: 95.216.138.141 Dec 08 16:03:25 volumio ntpd[1027]: DNS: Pool taking: 162.159.200.1 Dec 08 16:03:25 volumio ntpd[1027]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 08 16:03:25 volumio upmpdcli[2213]: writing RSA key Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=info msg="zeroconf server listening on port 33963" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="obtained new client token: AAB4GaG1wha6TYtuMxqFmxKr99u4u2AOdlIZF9CczEewefJCYhzsRw2DIKdS49RXpiB95ME1hHM6S8BDQycsqYyLj9Mxb9znPQke/5+TAW0zlxTibukYLm1PEAXCdD7/sN8ERS79n3cfE+MD3GKJI7CF5nSilyMc3YsmWKZoPZC6h1QMiTAvBB0vZmjvQ++XU4MMCgirqCoBus3rorYiPv7mjTJOhHzIAu+r3E/FRJ2ol8dfIz6gNP2iDA==" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="completed keyexchange" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="completed challenge" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=info msg="authenticated AP" username="po******uu" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=info msg="authenticated Login5" username="po******uu" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="initializing zeroconf session" username="po******uu" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="dealer connection opened" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=trace msg="starting accesspoint recv loop" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=trace msg="starting dealer recv loop" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=trace msg="received accesspoint ping" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="received connection id: YWI1NDYxYjktNmMx...MTI5MjhBQkY1QQ==" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=trace msg="received accesspoint pong ack" Dec 08 16:03:25 volumio go-librespot[2204]: time="2025-12-08T16:03:25+02:00" level=debug msg="put connect state because NEW_DEVICE" Dec 08 16:03:26 volumio sudo[2234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-08 16:02' Dec 08 16:03:26 volumio sudo[2234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"