Feb 17 09:44:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Feb 17 09:44:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:00 volumio go-librespot[2078]: go-librespot daemon starting... Feb 17 09:44:00 volumio go-librespot[2079]: time="2026-02-17T09:44:00-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:00 volumio go-librespot[2079]: time="2026-02-17T09:44:00-05:00" level=debug msg="app state loaded" Feb 17 09:44:00 volumio go-librespot[2079]: time="2026-02-17T09:44:00-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:00 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:00 volumio go-librespot[2079]: time="2026-02-17T09:44:00-05: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" Feb 17 09:44:00 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: Error: write ECONNRESET Feb 17 09:44:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:03 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:03 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Feb 17 09:44:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:03 volumio go-librespot[2104]: go-librespot daemon starting... Feb 17 09:44:03 volumio go-librespot[2105]: time="2026-02-17T09:44:03-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:03 volumio go-librespot[2105]: time="2026-02-17T09:44:03-05:00" level=debug msg="app state loaded" Feb 17 09:44:03 volumio go-librespot[2105]: time="2026-02-17T09:44:03-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:03 volumio go-librespot[2105]: time="2026-02-17T09:44:03-05: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" Feb 17 09:44:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:06 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:06 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Feb 17 09:44:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:06 volumio go-librespot[2116]: go-librespot daemon starting... Feb 17 09:44:07 volumio go-librespot[2117]: time="2026-02-17T09:44:07-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:07 volumio go-librespot[2117]: time="2026-02-17T09:44:07-05:00" level=debug msg="app state loaded" Feb 17 09:44:07 volumio go-librespot[2117]: time="2026-02-17T09:44:07-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:07 volumio go-librespot[2117]: time="2026-02-17T09:44:07-05: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" Feb 17 09:44:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:07 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Feb 17 09:44:07 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 17 09:44:07 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 17 09:44:09 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:09 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Feb 17 09:44:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:10 volumio go-librespot[2136]: go-librespot daemon starting... Feb 17 09:44:10 volumio go-librespot[2137]: time="2026-02-17T09:44:10-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:10 volumio go-librespot[2137]: time="2026-02-17T09:44:10-05:00" level=debug msg="app state loaded" Feb 17 09:44:10 volumio go-librespot[2137]: time="2026-02-17T09:44:10-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:10 volumio go-librespot[2137]: time="2026-02-17T09:44:10-05: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" Feb 17 09:44:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:12 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:12 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Feb 17 09:44:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:13 volumio go-librespot[2163]: go-librespot daemon starting... Feb 17 09:44:13 volumio go-librespot[2164]: time="2026-02-17T09:44:13-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:13 volumio go-librespot[2164]: time="2026-02-17T09:44:13-05:00" level=debug msg="app state loaded" Feb 17 09:44:13 volumio go-librespot[2164]: time="2026-02-17T09:44:13-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:13 volumio go-librespot[2164]: time="2026-02-17T09:44:13-05: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" Feb 17 09:44:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:15 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:15 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Feb 17 09:44:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:16 volumio go-librespot[2175]: go-librespot daemon starting... Feb 17 09:44:16 volumio go-librespot[2176]: time="2026-02-17T09:44:16-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:16 volumio go-librespot[2176]: time="2026-02-17T09:44:16-05:00" level=debug msg="app state loaded" Feb 17 09:44:16 volumio go-librespot[2176]: time="2026-02-17T09:44:16-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:16 volumio go-librespot[2176]: time="2026-02-17T09:44:16-05: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" Feb 17 09:44:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:18 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:18 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Feb 17 09:44:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:19 volumio go-librespot[2187]: go-librespot daemon starting... Feb 17 09:44:19 volumio go-librespot[2188]: time="2026-02-17T09:44:19-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:19 volumio go-librespot[2188]: time="2026-02-17T09:44:19-05:00" level=debug msg="app state loaded" Feb 17 09:44:20 volumio go-librespot[2188]: time="2026-02-17T09:44:20-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:20 volumio go-librespot[2188]: time="2026-02-17T09:44:20-05: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" Feb 17 09:44:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:21 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:21 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Feb 17 09:44:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:23 volumio go-librespot[2213]: go-librespot daemon starting... Feb 17 09:44:23 volumio go-librespot[2214]: time="2026-02-17T09:44:23-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:23 volumio go-librespot[2214]: time="2026-02-17T09:44:23-05:00" level=debug msg="app state loaded" Feb 17 09:44:23 volumio go-librespot[2214]: time="2026-02-17T09:44:23-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:23 volumio go-librespot[2214]: time="2026-02-17T09:44:23-05: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" Feb 17 09:44:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:24 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:24 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Feb 17 09:44:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:26 volumio go-librespot[2225]: go-librespot daemon starting... Feb 17 09:44:26 volumio go-librespot[2226]: time="2026-02-17T09:44:26-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:26 volumio go-librespot[2226]: time="2026-02-17T09:44:26-05:00" level=debug msg="app state loaded" Feb 17 09:44:26 volumio go-librespot[2226]: time="2026-02-17T09:44:26-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:26 volumio go-librespot[2226]: time="2026-02-17T09:44:26-05: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" Feb 17 09:44:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:27 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:27 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Feb 17 09:44:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:29 volumio go-librespot[2238]: go-librespot daemon starting... Feb 17 09:44:29 volumio go-librespot[2239]: time="2026-02-17T09:44:29-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:29 volumio go-librespot[2239]: time="2026-02-17T09:44:29-05:00" level=debug msg="app state loaded" Feb 17 09:44:29 volumio go-librespot[2239]: time="2026-02-17T09:44:29-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:29 volumio go-librespot[2239]: time="2026-02-17T09:44:29-05: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" Feb 17 09:44:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:30 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:30 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Feb 17 09:44:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:32 volumio go-librespot[2264]: go-librespot daemon starting... Feb 17 09:44:32 volumio go-librespot[2265]: time="2026-02-17T09:44:32-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:32 volumio go-librespot[2265]: time="2026-02-17T09:44:32-05:00" level=debug msg="app state loaded" Feb 17 09:44:33 volumio go-librespot[2265]: time="2026-02-17T09:44:33-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:33 volumio go-librespot[2265]: time="2026-02-17T09:44:33-05: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" Feb 17 09:44:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:33 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:33 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Feb 17 09:44:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:36 volumio go-librespot[2276]: go-librespot daemon starting... Feb 17 09:44:36 volumio go-librespot[2277]: time="2026-02-17T09:44:36-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:36 volumio go-librespot[2277]: time="2026-02-17T09:44:36-05:00" level=debug msg="app state loaded" Feb 17 09:44:36 volumio go-librespot[2277]: time="2026-02-17T09:44:36-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:36 volumio go-librespot[2277]: time="2026-02-17T09:44:36-05: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" Feb 17 09:44:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:36 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:36 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Feb 17 09:44:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:39 volumio go-librespot[2288]: go-librespot daemon starting... Feb 17 09:44:39 volumio go-librespot[2289]: time="2026-02-17T09:44:39-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:39 volumio go-librespot[2289]: time="2026-02-17T09:44:39-05:00" level=debug msg="app state loaded" Feb 17 09:44:39 volumio go-librespot[2289]: time="2026-02-17T09:44:39-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:39 volumio go-librespot[2289]: time="2026-02-17T09:44:39-05: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" Feb 17 09:44:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:39 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:39 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:42 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:42 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Feb 17 09:44:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:42 volumio go-librespot[2314]: go-librespot daemon starting... Feb 17 09:44:42 volumio go-librespot[2316]: time="2026-02-17T09:44:42-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:42 volumio go-librespot[2316]: time="2026-02-17T09:44:42-05:00" level=debug msg="app state loaded" Feb 17 09:44:42 volumio go-librespot[2316]: time="2026-02-17T09:44:42-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:42 volumio go-librespot[2316]: time="2026-02-17T09:44:42-05: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" Feb 17 09:44:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:45 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:45 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Feb 17 09:44:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:45 volumio go-librespot[2327]: go-librespot daemon starting... Feb 17 09:44:45 volumio go-librespot[2328]: time="2026-02-17T09:44:45-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:45 volumio go-librespot[2328]: time="2026-02-17T09:44:45-05:00" level=debug msg="app state loaded" Feb 17 09:44:46 volumio go-librespot[2328]: time="2026-02-17T09:44:46-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:46 volumio go-librespot[2328]: time="2026-02-17T09:44:46-05: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" Feb 17 09:44:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:48 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:48 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Feb 17 09:44:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:49 volumio go-librespot[2339]: go-librespot daemon starting... Feb 17 09:44:49 volumio go-librespot[2340]: time="2026-02-17T09:44:49-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:49 volumio go-librespot[2340]: time="2026-02-17T09:44:49-05:00" level=debug msg="app state loaded" Feb 17 09:44:49 volumio go-librespot[2340]: time="2026-02-17T09:44:49-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:49 volumio go-librespot[2340]: time="2026-02-17T09:44:49-05: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" Feb 17 09:44:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:51 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:51 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Feb 17 09:44:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:52 volumio go-librespot[2365]: go-librespot daemon starting... Feb 17 09:44:52 volumio go-librespot[2366]: time="2026-02-17T09:44:52-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:52 volumio go-librespot[2366]: time="2026-02-17T09:44:52-05:00" level=debug msg="app state loaded" Feb 17 09:44:52 volumio go-librespot[2366]: time="2026-02-17T09:44:52-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:52 volumio go-librespot[2366]: time="2026-02-17T09:44:52-05: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" Feb 17 09:44:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:54 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:54 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Feb 17 09:44:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:55 volumio go-librespot[2377]: go-librespot daemon starting... Feb 17 09:44:55 volumio go-librespot[2378]: time="2026-02-17T09:44:55-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:55 volumio go-librespot[2378]: time="2026-02-17T09:44:55-05:00" level=debug msg="app state loaded" Feb 17 09:44:55 volumio go-librespot[2378]: time="2026-02-17T09:44:55-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:55 volumio go-librespot[2378]: time="2026-02-17T09:44:55-05: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" Feb 17 09:44:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:44:57 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:44:57 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:44:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Feb 17 09:44:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:44:58 volumio go-librespot[2390]: go-librespot daemon starting... Feb 17 09:44:58 volumio go-librespot[2391]: time="2026-02-17T09:44:58-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:44:58 volumio go-librespot[2391]: time="2026-02-17T09:44:58-05:00" level=debug msg="app state loaded" Feb 17 09:44:59 volumio go-librespot[2391]: time="2026-02-17T09:44:59-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:44:59 volumio go-librespot[2391]: time="2026-02-17T09:44:59-05: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" Feb 17 09:44:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:44:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:00 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:00 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Feb 17 09:45:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:02 volumio go-librespot[2416]: go-librespot daemon starting... Feb 17 09:45:02 volumio go-librespot[2417]: time="2026-02-17T09:45:02-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:02 volumio go-librespot[2417]: time="2026-02-17T09:45:02-05:00" level=debug msg="app state loaded" Feb 17 09:45:02 volumio go-librespot[2417]: time="2026-02-17T09:45:02-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:02 volumio go-librespot[2417]: time="2026-02-17T09:45:02-05: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" Feb 17 09:45:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:03 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:03 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Feb 17 09:45:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:05 volumio go-librespot[2428]: go-librespot daemon starting... Feb 17 09:45:05 volumio go-librespot[2429]: time="2026-02-17T09:45:05-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:05 volumio go-librespot[2429]: time="2026-02-17T09:45:05-05:00" level=debug msg="app state loaded" Feb 17 09:45:05 volumio go-librespot[2429]: time="2026-02-17T09:45:05-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:05 volumio go-librespot[2429]: time="2026-02-17T09:45:05-05: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" Feb 17 09:45:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:06 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:06 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:07 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:07 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 17 09:45:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Feb 17 09:45:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:08 volumio go-librespot[2440]: go-librespot daemon starting... Feb 17 09:45:08 volumio go-librespot[2441]: time="2026-02-17T09:45:08-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:08 volumio go-librespot[2441]: time="2026-02-17T09:45:08-05:00" level=debug msg="app state loaded" Feb 17 09:45:08 volumio go-librespot[2441]: time="2026-02-17T09:45:08-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:08 volumio go-librespot[2441]: time="2026-02-17T09:45:08-05: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" Feb 17 09:45:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:09 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:09 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Feb 17 09:45:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:11 volumio go-librespot[2467]: go-librespot daemon starting... Feb 17 09:45:11 volumio go-librespot[2468]: time="2026-02-17T09:45:11-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:11 volumio go-librespot[2468]: time="2026-02-17T09:45:11-05:00" level=debug msg="app state loaded" Feb 17 09:45:11 volumio go-librespot[2468]: time="2026-02-17T09:45:11-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:11 volumio go-librespot[2468]: time="2026-02-17T09:45:11-05: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" Feb 17 09:45:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:12 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:12 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Feb 17 09:45:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:15 volumio go-librespot[2479]: go-librespot daemon starting... Feb 17 09:45:15 volumio go-librespot[2480]: time="2026-02-17T09:45:15-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:15 volumio go-librespot[2480]: time="2026-02-17T09:45:15-05:00" level=debug msg="app state loaded" Feb 17 09:45:15 volumio go-librespot[2480]: time="2026-02-17T09:45:15-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:15 volumio go-librespot[2480]: time="2026-02-17T09:45:15-05: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" Feb 17 09:45:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:15 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:15 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Feb 17 09:45:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:18 volumio go-librespot[2491]: go-librespot daemon starting... Feb 17 09:45:18 volumio go-librespot[2492]: time="2026-02-17T09:45:18-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:18 volumio go-librespot[2492]: time="2026-02-17T09:45:18-05:00" level=debug msg="app state loaded" Feb 17 09:45:18 volumio go-librespot[2492]: time="2026-02-17T09:45:18-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:18 volumio go-librespot[2492]: time="2026-02-17T09:45:18-05: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" Feb 17 09:45:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:18 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:18 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Feb 17 09:45:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:21 volumio go-librespot[2517]: go-librespot daemon starting... Feb 17 09:45:21 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:21 volumio go-librespot[2518]: time="2026-02-17T09:45:21-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:21 volumio go-librespot[2518]: time="2026-02-17T09:45:21-05:00" level=debug msg="app state loaded" Feb 17 09:45:21 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:21 volumio go-librespot[2518]: time="2026-02-17T09:45:21-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:21 volumio go-librespot[2518]: time="2026-02-17T09:45:21-05: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" Feb 17 09:45:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:22 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Feb 17 09:45:22 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 17 09:45:22 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 17 09:45:24 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:24 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Feb 17 09:45:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:24 volumio go-librespot[2537]: go-librespot daemon starting... Feb 17 09:45:24 volumio go-librespot[2538]: time="2026-02-17T09:45:24-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:24 volumio go-librespot[2538]: time="2026-02-17T09:45:24-05:00" level=debug msg="app state loaded" Feb 17 09:45:25 volumio go-librespot[2538]: time="2026-02-17T09:45:25-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:25 volumio go-librespot[2538]: time="2026-02-17T09:45:25-05: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" Feb 17 09:45:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:27 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:27 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Feb 17 09:45:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:28 volumio go-librespot[2550]: go-librespot daemon starting... Feb 17 09:45:28 volumio go-librespot[2551]: time="2026-02-17T09:45:28-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:28 volumio go-librespot[2551]: time="2026-02-17T09:45:28-05:00" level=debug msg="app state loaded" Feb 17 09:45:28 volumio go-librespot[2551]: time="2026-02-17T09:45:28-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:28 volumio go-librespot[2551]: time="2026-02-17T09:45:28-05: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" Feb 17 09:45:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:30 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:30 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Feb 17 09:45:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:31 volumio go-librespot[2562]: go-librespot daemon starting... Feb 17 09:45:31 volumio go-librespot[2563]: time="2026-02-17T09:45:31-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:31 volumio go-librespot[2563]: time="2026-02-17T09:45:31-05:00" level=debug msg="app state loaded" Feb 17 09:45:31 volumio go-librespot[2563]: time="2026-02-17T09:45:31-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:31 volumio go-librespot[2563]: time="2026-02-17T09:45:31-05: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" Feb 17 09:45:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:33 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:33 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Feb 17 09:45:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:34 volumio go-librespot[2588]: go-librespot daemon starting... Feb 17 09:45:34 volumio go-librespot[2589]: time="2026-02-17T09:45:34-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:34 volumio go-librespot[2589]: time="2026-02-17T09:45:34-05:00" level=debug msg="app state loaded" Feb 17 09:45:34 volumio go-librespot[2589]: time="2026-02-17T09:45:34-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:34 volumio go-librespot[2589]: time="2026-02-17T09:45:34-05: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" Feb 17 09:45:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:36 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:36 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Feb 17 09:45:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:37 volumio go-librespot[2600]: go-librespot daemon starting... Feb 17 09:45:37 volumio go-librespot[2601]: time="2026-02-17T09:45:37-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:37 volumio go-librespot[2601]: time="2026-02-17T09:45:37-05:00" level=debug msg="app state loaded" Feb 17 09:45:38 volumio go-librespot[2601]: time="2026-02-17T09:45:38-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:38 volumio go-librespot[2601]: time="2026-02-17T09:45:38-05: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" Feb 17 09:45:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:39 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:39 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Feb 17 09:45:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:41 volumio go-librespot[2613]: go-librespot daemon starting... Feb 17 09:45:41 volumio go-librespot[2614]: time="2026-02-17T09:45:41-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:41 volumio go-librespot[2614]: time="2026-02-17T09:45:41-05:00" level=debug msg="app state loaded" Feb 17 09:45:41 volumio go-librespot[2614]: time="2026-02-17T09:45:41-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:41 volumio go-librespot[2614]: time="2026-02-17T09:45:41-05: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" Feb 17 09:45:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:42 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:42 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Feb 17 09:45:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:44 volumio go-librespot[2639]: go-librespot daemon starting... Feb 17 09:45:44 volumio go-librespot[2640]: time="2026-02-17T09:45:44-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:44 volumio go-librespot[2640]: time="2026-02-17T09:45:44-05:00" level=debug msg="app state loaded" Feb 17 09:45:44 volumio go-librespot[2640]: time="2026-02-17T09:45:44-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:44 volumio go-librespot[2640]: time="2026-02-17T09:45:44-05: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" Feb 17 09:45:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:45 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:45 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:46 volumio dhcpcd[864]: eth0: carrier acquired Feb 17 09:45:46 volumio kernel: igb 0000:05:00.0 eth0: igb: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX Feb 17 09:45:46 volumio dhcpcd[864]: eth0: IAID 90:e4:f4:84 Feb 17 09:45:46 volumio dhcpcd[864]: eth0: adding address fe80::553f:6c9b:3180:3bc Feb 17 09:45:46 volumio dhcpcd[864]: ipv6_addaddr1: Permission denied Feb 17 09:45:46 volumio dhcpcd[864]: eth0: soliciting an IPv6 router Feb 17 09:45:46 volumio ifplugd(eth0)[969]: Link beat detected. Feb 17 09:45:46 volumio volumio[1027]: info: Received Get System Info Feb 17 09:45:46 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 09:45:46 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 09:45:46 volumio volumio[1027]: info: Discovery: Getting this device information Feb 17 09:45:46 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Feb 17 09:45:46 volumio volumio[1027]: info: CorePlayQueue::getTrack 0 Feb 17 09:45:46 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 09:45:46 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 09:45:46 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 09:45:46 volumio ifplugd(eth0)[969]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 17 09:45:46 volumio ifplugd(eth0)[969]: client: command failed: No such device (-19) Feb 17 09:45:46 volumio ifplugd(eth0)[969]: client: sending commands to dhcpcd process Feb 17 09:45:46 volumio dhcpcd[864]: control command: dhcpcd eth0 Feb 17 09:45:46 volumio dhcpcd[864]: control_free: No such file or directory Feb 17 09:45:47 volumio ifplugd(eth0)[969]: Program executed successfully. Feb 17 09:45:47 volumio dhcpcd[864]: eth0: rebinding lease of 192.168.1.158 Feb 17 09:45:47 volumio dhcpcd[864]: eth0: probing address 192.168.1.158/24 Feb 17 09:45:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Feb 17 09:45:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:47 volumio go-librespot[2704]: go-librespot daemon starting... Feb 17 09:45:47 volumio go-librespot[2705]: time="2026-02-17T09:45:47-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:47 volumio go-librespot[2705]: time="2026-02-17T09:45:47-05:00" level=debug msg="app state loaded" Feb 17 09:45:47 volumio go-librespot[2705]: time="2026-02-17T09:45:47-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:47 volumio go-librespot[2705]: time="2026-02-17T09:45:47-05: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" Feb 17 09:45:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:48 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:48 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Feb 17 09:45:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:50 volumio go-librespot[2716]: go-librespot daemon starting... Feb 17 09:45:50 volumio go-librespot[2717]: time="2026-02-17T09:45:50-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:50 volumio go-librespot[2717]: time="2026-02-17T09:45:50-05:00" level=debug msg="app state loaded" Feb 17 09:45:51 volumio go-librespot[2717]: time="2026-02-17T09:45:51-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:51 volumio go-librespot[2717]: time="2026-02-17T09:45:51-05: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" Feb 17 09:45:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 09:45:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 09:45:51 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:51 volumio volumio[1027]: info: Error connecting to go-librespot Websocket: AggregateError Feb 17 09:45:52 volumio dhcpcd[864]: eth0: leased 192.168.1.158 for 7200 seconds Feb 17 09:45:52 volumio avahi-daemon[741]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.158. Feb 17 09:45:52 volumio avahi-daemon[741]: New relevant interface eth0.IPv4 for mDNS. Feb 17 09:45:52 volumio avahi-daemon[741]: Registering new address record for 192.168.1.158 on eth0.IPv4. Feb 17 09:45:52 volumio dhcpcd[864]: eth0: adding route to 192.168.1.0/24 Feb 17 09:45:52 volumio dhcpcd[864]: eth0: adding default route via 192.168.1.1 Feb 17 09:45:52 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 17 09:45:52 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 17 09:45:52 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 17 09:45:52 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 17 09:45:52 volumio welcome[2756]: Resolved ip:[1] 192.168.1.158 Feb 17 09:45:52 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 17 09:45:52 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 17 09:45:53 volumio volumio[1027]: info: Received Get System Info Feb 17 09:45:53 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 09:45:53 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 09:45:53 volumio volumio[1027]: info: Discovery: Getting this device information Feb 17 09:45:53 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Feb 17 09:45:53 volumio volumio[1027]: info: CorePlayQueue::getTrack 0 Feb 17 09:45:53 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 09:45:53 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 09:45:53 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 09:45:53 volumio volumio[1027]: info: Discovery: this is already registered, 5be8602f-b86c-44a9-a893-195a10dd9643 Feb 17 09:45:53 volumio volumio[1027]: info: Discovery: Found device Volumio Feb 17 09:45:53 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Feb 17 09:45:53 volumio volumio[1027]: info: CorePlayQueue::getTrack 0 Feb 17 09:45:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Feb 17 09:45:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 09:45:54 volumio go-librespot[2768]: go-librespot daemon starting... Feb 17 09:45:54 volumio go-librespot[2769]: time="2026-02-17T09:45:54-05:00" level=info msg="running go-librespot 0.6.2" Feb 17 09:45:54 volumio go-librespot[2769]: time="2026-02-17T09:45:54-05:00" level=debug msg="app state loaded" Feb 17 09:45:54 volumio go-librespot[2769]: time="2026-02-17T09:45:54-05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 09:45:54 volumio ntpd[929]: IO: Listen normally on 3 eth0 192.168.1.158:123 Feb 17 09:45:54 volumio ntpd[929]: IO: new interface(s) found: waking up resolver Feb 17 09:45:54 volumio ntpd[929]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 17 09:45:54 volumio ntpd[929]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 17 09:45:54 volumio ntpd[929]: DNS: Pool taking: 172.233.155.39 Feb 17 09:45:54 volumio ntpd[929]: DNS: Pool taking: 23.186.168.131 Feb 17 09:45:54 volumio ntpd[929]: DNS: Pool taking: 23.111.186.186 Feb 17 09:45:54 volumio ntpd[929]: DNS: Pool taking: 63.211.239.58 Feb 17 09:45:54 volumio ntpd[929]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Feb 17 09:45:54 volumio go-librespot[2769]: time="2026-02-17T09:45:54-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 09:45:54 volumio go-librespot[2769]: time="2026-02-17T09:45:54-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 09:45:54 volumio go-librespot[2769]: time="2026-02-17T09:45:54-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 09:45:54 volumio go-librespot[2769]: time="2026-02-17T09:45:54-05:00" level=info msg="zeroconf server listening on port 32815" Feb 17 09:45:54 volumio volumio[1027]: info: Initializing connection to go-librespot Websocket Feb 17 09:45:54 volumio go-librespot[2769]: time="2026-02-17T09:45:54-05:00" level=debug msg="new websocket client" Feb 17 09:45:54 volumio volumio[1027]: info: Connection to go-librespot Websocket established Feb 17 09:45:54 volumio go-librespot[2769]: time="2026-02-17T09:45:54-05:00" level=debug msg="obtained new client token: AAAoOK86Ueuym58+HJGVPOZHh5o74uZojtYB3a9MF3CEIO4m+AO2we7bmSlkzpQrINtFPYPvTAhodk35VzBEWfOHwzOpzkf7mPkKP5SeUmFDfY6oemni+AEFURJ7YSjcU4UTLP8z2KhuWpEVzJK3Ko1iRXt4P1aip8WSGSwL9U495sClJoeBcaPH9Mj7W7EyN245NKt6939KpKIBS3RxNp4pomo4lz2TP2hhyJM6o/iUsuGAOqCq90c=" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="completed keyexchange" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="completed challenge" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=info msg="authenticated AP" username="31************************ym" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=info msg="authenticated Login5" username="31************************ym" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="initializing zeroconf session" username="31************************ym" Feb 17 09:45:55 volumio ntpd[929]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 17 09:45:55 volumio ntpd[929]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 17 09:45:55 volumio ntpd[929]: DNS: Pool taking: 199.68.201.236 Feb 17 09:45:55 volumio ntpd[929]: DNS: Pool taking: 216.66.48.42 Feb 17 09:45:55 volumio ntpd[929]: DNS: Pool taking: 172.235.60.8 Feb 17 09:45:55 volumio ntpd[929]: DNS: Pool taking: 172.235.154.118 Feb 17 09:45:55 volumio ntpd[929]: DNS: Pool taking: 2604:4300:a:299::164 Feb 17 09:45:55 volumio ntpd[929]: DNS: Pool taking: 2604:d200::60 Feb 17 09:45:55 volumio ntpd[929]: DNS: Pool taking: 2607:7c80:54:3::32 Feb 17 09:45:55 volumio ntpd[929]: DNS: Pool taking: 2600:4040:e0eb:ea00::cbb9:201a Feb 17 09:45:55 volumio ntpd[929]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 09:45:55 volumio volumio[1027]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 09:45:55 volumio volumio[1027]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 09:45:55 volumio volumio[1027]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 09:45:55 volumio volumio[1027]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 09:45:55 volumio volumio[1027]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 09:45:55 volumio volumio[1027]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 09:45:55 volumio volumio[1027]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 09:45:55 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="dealer connection opened" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=trace msg="starting accesspoint recv loop" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=trace msg="starting dealer recv loop" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=trace msg="received accesspoint ping" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="received connection id: YmU1OGZkMzItMjQz...ODVCOTM0OEFEMg==" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=trace msg="received accesspoint pong ack" Feb 17 09:45:55 volumio go-librespot[2769]: time="2026-02-17T09:45:55-05:00" level=debug msg="put connect state because NEW_DEVICE" Feb 17 09:45:56 volumio ntpd[929]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 17 09:45:56 volumio ntpd[929]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 17 09:45:56 volumio ntpd[929]: DNS: Pool taking: 198.137.202.56 Feb 17 09:45:56 volumio ntpd[929]: DNS: Pool taking: 23.186.168.133 Feb 17 09:45:56 volumio ntpd[929]: DNS: Pool taking: 141.11.228.173 Feb 17 09:45:56 volumio ntpd[929]: DNS: Pool taking: 163.123.153.14 Feb 17 09:45:56 volumio ntpd[929]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Feb 17 09:45:57 volumio volumio[1027]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 09:45:57 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 09:45:57 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 09:45:57 volumio volumio[1027]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 09:45:57 volumio volumio[1027]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 09:45:57 volumio volumio[1027]: info: MyVolumio login type: Token Feb 17 09:45:57 volumio volumio[1027]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 09:45:57 volumio volumio[1027]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 09:45:57 volumio ntpd[929]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 17 09:45:57 volumio ntpd[929]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 17 09:45:57 volumio ntpd[929]: DNS: Pool taking: 170.187.147.56 Feb 17 09:45:57 volumio ntpd[929]: DNS: Pool taking: 185.234.20.134 Feb 17 09:45:57 volumio ntpd[929]: DNS: Pool taking: 108.61.73.244 Feb 17 09:45:57 volumio ntpd[929]: DNS: Pool taking: 74.6.168.72 Feb 17 09:45:57 volumio ntpd[929]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=debug msg="handling transfer player command from a2a2d59832c02ed8c17821794e3f482404b2444c" Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=debug msg="resolved context of track" uri="spotify:playlist:4pcMfC4L9YtxT7DsAWL7rJ" Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=trace msg="fetched new page 0 with 9 items (list: 9)" uri="spotify:playlist:4pcMfC4L9YtxT7DsAWL7rJ" Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=debug msg="loading track (paused: true, position: 130097ms)" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=trace msg="emitting websocket event: will_play" Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1376" Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=debug msg="selected format OGG_VORBIS_320 (4dcf05b8778b9050b924b35140cadcae2b0b08b3)" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=debug msg="requested aes key for file 4dcf05b8778b9050b924b35140cadcae2b0b08b3, gid: 4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=trace msg="found 2 cdn urls" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:57 volumio go-librespot[2769]: time="2026-02-17T09:45:57-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1132" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="fetched first chunk of 19, total size is 9677316 bytes" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="fetched chunk 9/18, size: 524288" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="fetched chunk 7/18, size: 524288" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=trace msg="seek to 130097ms (diff: 134ms, samples: 5737277, bytes: 4149764)" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="created new output device" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=info msg="loaded track \"Shallow The Water\" (paused: true, position: 130097ms, duration: 301666ms, prefetched: false)" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="fetched chunk 10/18, size: 524288" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="fetched chunk 8/18, size: 524288" uri="spotify:track:4eR1oWpHGyXbQigVhQJJOW" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=trace msg="emitting websocket event: metadata" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=trace msg="emitting websocket event: active" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="sending successful reply for dealer request" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 17 09:45:58 volumio volumio[1027]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 09:45:58 volumio volumio[1027]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 09:45:58 volumio volumio[1027]: info: Streaming services startup Feb 17 09:45:58 volumio volumio[1027]: info: Starting Streaming Daemon Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 17 09:45:58 volumio go-librespot[2769]: time="2026-02-17T09:45:58-05:00" level=trace msg="emitting websocket event: paused" Feb 17 09:45:58 volumio sudo[2798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 09:45:58 volumio sudo[2798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 09:45:58 volumio volumio[1027]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 09:45:58 volumio sudo[2798]: pam_unix(sudo:session): session closed for user root Feb 17 09:45:58 volumio volumio[1027]: info: Getting Spotify volume Feb 17 09:45:58 volumio volumio[1027]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:4pcMfC4L9YtxT7DsAWL7rJ","uri":"spotify:track:4eR1oWpHGyXbQigVhQJJOW","play_origin":"playlist/ondemand"}} Feb 17 09:45:58 volumio volumio[1027]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4eR1oWpHGyXbQigVhQJJOW","name":"Shallow The Water","artist_names":["Jessie Buckley","Bernard Butler"],"album_name":"For All Our Days That Tear The Heart","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02be00f294c3adc36d6f900554","position":130097,"duration":301666,"release_date":"year:2022 month:6 day:17","track_number":11,"disc_number":1}} Feb 17 09:45:58 volumio volumio[1027]: SPOTIFY: received: {"type":"active","data":null} Feb 17 09:45:58 volumio volumio[1027]: info: Aligning Spotify Volume to Volumio Volume Feb 17 09:45:58 volumio volumio[1027]: info: CoreCommandRouter::volumioGetState Feb 17 09:45:58 volumio volumio[1027]: info: CorePlayQueue::getTrack 0 Feb 17 09:45:58 volumio volumio[1027]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:4pcMfC4L9YtxT7DsAWL7rJ","uri":"spotify:track:4eR1oWpHGyXbQigVhQJJOW","play_origin":"playlist/ondemand"}} Feb 17 09:45:58 volumio volumio[1027]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 09:45:58 volumio volumio[1027]: TypeError: Cannot read properties of undefined (reading 'service') Feb 17 09:45:58 volumio volumio[1027]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Feb 17 09:45:58 volumio volumio[1027]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Feb 17 09:45:58 volumio volumio[1027]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Feb 17 09:45:58 volumio volumio[1027]: at WebSocket.emit (node:events:514:28) Feb 17 09:45:58 volumio volumio[1027]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 17 09:45:58 volumio volumio[1027]: at Receiver.emit (node:events:514:28) Feb 17 09:45:58 volumio volumio[1027]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 17 09:45:58 volumio volumio[1027]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 17 09:45:58 volumio volumio[1027]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 17 09:45:58 volumio volumio[1027]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Feb 17 09:45:58 volumio volumio[1027]: at writeOrBuffer (node:internal/streams/writable:399:12) Feb 17 09:45:58 volumio volumio[1027]: at _write (node:internal/streams/writable:340:10) Feb 17 09:45:58 volumio volumio[1027]: at Writable.write (node:internal/streams/writable:344:10) Feb 17 09:45:58 volumio volumio[1027]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Feb 17 09:45:58 volumio volumio[1027]: at Socket.emit (node:events:514:28) Feb 17 09:45:58 volumio volumio[1027]: at addChunk (node:internal/streams/readable:343:12) Feb 17 09:45:58 volumio volumio[1027]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 09:45:59 volumio sudo[2818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 09:44' Feb 17 09:45:59 volumio sudo[2818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"