Aug 29 12:37:00 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:00 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Aug 29 12:37:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:00 volumio go-librespot[2093]: go-librespot daemon starting... Aug 29 12:37:00 volumio go-librespot[2094]: time="2026-08-29T12:37:00+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:00 volumio go-librespot[2094]: time="2026-08-29T12:37:00+02:00" level=debug msg="app state loaded" Aug 29 12:37:00 volumio go-librespot[2094]: time="2026-08-29T12:37:00+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:00 volumio go-librespot[2094]: time="2026-08-29T12:37:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:00 volumio go-librespot[2094]: time="2026-08-29T12:37:00+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:02 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 29 12:37:03 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:03 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Aug 29 12:37:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:03 volumio go-librespot[2126]: go-librespot daemon starting... Aug 29 12:37:03 volumio go-librespot[2127]: time="2026-08-29T12:37:03+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:03 volumio go-librespot[2127]: time="2026-08-29T12:37:03+02:00" level=debug msg="app state loaded" Aug 29 12:37:03 volumio go-librespot[2127]: time="2026-08-29T12:37:03+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:03 volumio go-librespot[2127]: time="2026-08-29T12:37:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:03 volumio go-librespot[2127]: time="2026-08-29T12:37:03+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:06 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:06 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Aug 29 12:37:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:07 volumio go-librespot[2162]: go-librespot daemon starting... Aug 29 12:37:07 volumio go-librespot[2163]: time="2026-08-29T12:37:07+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:07 volumio go-librespot[2163]: time="2026-08-29T12:37:07+02:00" level=debug msg="app state loaded" Aug 29 12:37:07 volumio go-librespot[2163]: time="2026-08-29T12:37:07+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:07 volumio go-librespot[2163]: time="2026-08-29T12:37:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:07 volumio go-librespot[2163]: time="2026-08-29T12:37:07+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:08 volumio bash[2105]: setdatetime-helper: all HTTPS Date fallbacks failed Aug 29 12:37:08 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 29 12:37:08 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 29 12:37:09 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:09 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Aug 29 12:37:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:10 volumio go-librespot[2189]: go-librespot daemon starting... Aug 29 12:37:10 volumio go-librespot[2190]: time="2026-08-29T12:37:10+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:10 volumio go-librespot[2190]: time="2026-08-29T12:37:10+02:00" level=debug msg="app state loaded" Aug 29 12:37:10 volumio go-librespot[2190]: time="2026-08-29T12:37:10+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:10 volumio go-librespot[2190]: time="2026-08-29T12:37:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:10 volumio go-librespot[2190]: time="2026-08-29T12:37:10+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:12 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:12 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Aug 29 12:37:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:13 volumio go-librespot[2202]: go-librespot daemon starting... Aug 29 12:37:13 volumio go-librespot[2203]: time="2026-08-29T12:37:13+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:13 volumio go-librespot[2203]: time="2026-08-29T12:37:13+02:00" level=debug msg="app state loaded" Aug 29 12:37:13 volumio go-librespot[2203]: time="2026-08-29T12:37:13+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:13 volumio go-librespot[2203]: time="2026-08-29T12:37:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:13 volumio go-librespot[2203]: time="2026-08-29T12:37:13+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:15 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:15 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Aug 29 12:37:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:16 volumio go-librespot[2214]: go-librespot daemon starting... Aug 29 12:37:16 volumio go-librespot[2215]: time="2026-08-29T12:37:16+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:16 volumio go-librespot[2215]: time="2026-08-29T12:37:16+02:00" level=debug msg="app state loaded" Aug 29 12:37:16 volumio go-librespot[2215]: time="2026-08-29T12:37:16+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:16 volumio go-librespot[2215]: time="2026-08-29T12:37:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:16 volumio go-librespot[2215]: time="2026-08-29T12:37:16+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:18 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:18 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Aug 29 12:37:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:20 volumio go-librespot[2240]: go-librespot daemon starting... Aug 29 12:37:20 volumio go-librespot[2241]: time="2026-08-29T12:37:20+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:20 volumio go-librespot[2241]: time="2026-08-29T12:37:20+02:00" level=debug msg="app state loaded" Aug 29 12:37:20 volumio go-librespot[2241]: time="2026-08-29T12:37:20+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:20 volumio go-librespot[2241]: time="2026-08-29T12:37:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:20 volumio go-librespot[2241]: time="2026-08-29T12:37:20+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:21 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:21 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Aug 29 12:37:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:23 volumio go-librespot[2252]: go-librespot daemon starting... Aug 29 12:37:23 volumio go-librespot[2253]: time="2026-08-29T12:37:23+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:23 volumio go-librespot[2253]: time="2026-08-29T12:37:23+02:00" level=debug msg="app state loaded" Aug 29 12:37:23 volumio go-librespot[2253]: time="2026-08-29T12:37:23+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:23 volumio go-librespot[2253]: time="2026-08-29T12:37:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:23 volumio go-librespot[2253]: time="2026-08-29T12:37:23+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:24 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:24 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Aug 29 12:37:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:26 volumio go-librespot[2266]: go-librespot daemon starting... Aug 29 12:37:26 volumio go-librespot[2267]: time="2026-08-29T12:37:26+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:26 volumio go-librespot[2267]: time="2026-08-29T12:37:26+02:00" level=debug msg="app state loaded" Aug 29 12:37:26 volumio go-librespot[2267]: time="2026-08-29T12:37:26+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:26 volumio go-librespot[2267]: time="2026-08-29T12:37:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:26 volumio go-librespot[2267]: time="2026-08-29T12:37:26+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:27 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:27 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Aug 29 12:37:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:29 volumio go-librespot[2292]: go-librespot daemon starting... Aug 29 12:37:29 volumio go-librespot[2293]: time="2026-08-29T12:37:29+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:29 volumio go-librespot[2293]: time="2026-08-29T12:37:29+02:00" level=debug msg="app state loaded" Aug 29 12:37:29 volumio go-librespot[2293]: time="2026-08-29T12:37:29+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:29 volumio go-librespot[2293]: time="2026-08-29T12:37:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:29 volumio go-librespot[2293]: time="2026-08-29T12:37:29+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:30 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:30 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:33 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:33 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 29 12:37:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Aug 29 12:37:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:33 volumio go-librespot[2304]: go-librespot daemon starting... Aug 29 12:37:33 volumio go-librespot[2305]: time="2026-08-29T12:37:33+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:33 volumio go-librespot[2305]: time="2026-08-29T12:37:33+02:00" level=debug msg="app state loaded" Aug 29 12:37:33 volumio go-librespot[2305]: time="2026-08-29T12:37:33+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:33 volumio go-librespot[2305]: time="2026-08-29T12:37:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:33 volumio go-librespot[2305]: time="2026-08-29T12:37:33+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:33 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:33 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Aug 29 12:37:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:36 volumio go-librespot[2316]: go-librespot daemon starting... Aug 29 12:37:36 volumio go-librespot[2317]: time="2026-08-29T12:37:36+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:36 volumio go-librespot[2317]: time="2026-08-29T12:37:36+02:00" level=debug msg="app state loaded" Aug 29 12:37:36 volumio go-librespot[2317]: time="2026-08-29T12:37:36+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:36 volumio go-librespot[2317]: time="2026-08-29T12:37:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:36 volumio go-librespot[2317]: time="2026-08-29T12:37:36+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:36 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:36 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:39 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:39 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Aug 29 12:37:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:39 volumio go-librespot[2329]: go-librespot daemon starting... Aug 29 12:37:39 volumio go-librespot[2330]: time="2026-08-29T12:37:39+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:39 volumio go-librespot[2330]: time="2026-08-29T12:37:39+02:00" level=debug msg="app state loaded" Aug 29 12:37:39 volumio go-librespot[2330]: time="2026-08-29T12:37:39+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:39 volumio go-librespot[2330]: time="2026-08-29T12:37:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:39 volumio go-librespot[2330]: time="2026-08-29T12:37:39+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:42 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:42 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Aug 29 12:37:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:42 volumio go-librespot[2355]: go-librespot daemon starting... Aug 29 12:37:42 volumio go-librespot[2356]: time="2026-08-29T12:37:42+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:42 volumio go-librespot[2356]: time="2026-08-29T12:37:42+02:00" level=debug msg="app state loaded" Aug 29 12:37:42 volumio go-librespot[2356]: time="2026-08-29T12:37:42+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:42 volumio go-librespot[2356]: time="2026-08-29T12:37:42+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:42 volumio go-librespot[2356]: time="2026-08-29T12:37:42+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:45 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:45 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Aug 29 12:37:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:46 volumio go-librespot[2367]: go-librespot daemon starting... Aug 29 12:37:46 volumio go-librespot[2368]: time="2026-08-29T12:37:46+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:46 volumio go-librespot[2368]: time="2026-08-29T12:37:46+02:00" level=debug msg="app state loaded" Aug 29 12:37:46 volumio go-librespot[2368]: time="2026-08-29T12:37:46+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:46 volumio go-librespot[2368]: time="2026-08-29T12:37:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:46 volumio go-librespot[2368]: time="2026-08-29T12:37:46+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:48 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 29 12:37:48 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 12:37:48 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 29 12:37:48 volumio systemd[1]: nmbd.service: start operation timed out. Terminating. Aug 29 12:37:48 volumio systemd[1]: nmbd.service: Failed with result 'timeout'. Aug 29 12:37:48 volumio systemd[1]: Failed to start nmbd.service - Samba NMB Daemon. Aug 29 12:37:48 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Aug 29 12:37:48 volumio winbindd[2388]: [2026/08/29 12:37:48.417711, 0] ../../source3/winbindd/winbindd.c:1440(main) Aug 29 12:37:48 volumio winbindd[2388]: winbindd version 4.17.12-Debian started. Aug 29 12:37:48 volumio winbindd[2388]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 29 12:37:48 volumio winbindd[2388]: [2026/08/29 12:37:48.421683, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Aug 29 12:37:48 volumio winbindd[2388]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 29 12:37:48 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Aug 29 12:37:48 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Aug 29 12:37:48 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:48 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:48 volumio smbd[2405]: [2026/08/29 12:37:48.563923, 0] ../../source3/smbd/server.c:1741(main) Aug 29 12:37:48 volumio smbd[2405]: smbd version 4.17.12-Debian started. Aug 29 12:37:48 volumio smbd[2405]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Aug 29 12:37:48 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Aug 29 12:37:48 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Aug 29 12:37:48 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Aug 29 12:37:48 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Aug 29 12:37:48 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Aug 29 12:37:48 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Aug 29 12:37:48 volumio systemd[1]: Startup finished in 10.653s (firmware) + 3.356s (loader) + 12.975s (kernel) + 1min 33.483s (userspace) = 2min 468ms. Aug 29 12:37:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Aug 29 12:37:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:49 volumio go-librespot[2415]: go-librespot daemon starting... Aug 29 12:37:49 volumio go-librespot[2416]: time="2026-08-29T12:37:49+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:49 volumio go-librespot[2416]: time="2026-08-29T12:37:49+02:00" level=debug msg="app state loaded" Aug 29 12:37:49 volumio go-librespot[2416]: time="2026-08-29T12:37:49+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:49 volumio go-librespot[2416]: time="2026-08-29T12:37:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:49 volumio go-librespot[2416]: time="2026-08-29T12:37:49+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:51 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:51 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Aug 29 12:37:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:52 volumio go-librespot[2442]: go-librespot daemon starting... Aug 29 12:37:52 volumio go-librespot[2443]: time="2026-08-29T12:37:52+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:52 volumio go-librespot[2443]: time="2026-08-29T12:37:52+02:00" level=debug msg="app state loaded" Aug 29 12:37:52 volumio go-librespot[2443]: time="2026-08-29T12:37:52+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:52 volumio go-librespot[2443]: time="2026-08-29T12:37:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:52 volumio go-librespot[2443]: time="2026-08-29T12:37:52+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:54 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:54 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Aug 29 12:37:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:55 volumio go-librespot[2454]: go-librespot daemon starting... Aug 29 12:37:55 volumio go-librespot[2455]: time="2026-08-29T12:37:55+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:55 volumio go-librespot[2455]: time="2026-08-29T12:37:55+02:00" level=debug msg="app state loaded" Aug 29 12:37:55 volumio go-librespot[2455]: time="2026-08-29T12:37:55+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:55 volumio go-librespot[2455]: time="2026-08-29T12:37:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:55 volumio go-librespot[2455]: time="2026-08-29T12:37:55+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:37:57 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:37:57 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:37:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Aug 29 12:37:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:37:59 volumio go-librespot[2466]: go-librespot daemon starting... Aug 29 12:37:59 volumio go-librespot[2467]: time="2026-08-29T12:37:59+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:37:59 volumio go-librespot[2467]: time="2026-08-29T12:37:59+02:00" level=debug msg="app state loaded" Aug 29 12:37:59 volumio go-librespot[2467]: time="2026-08-29T12:37:59+02:00" level=debug msg="stored credentials not found" Aug 29 12:37:59 volumio go-librespot[2467]: time="2026-08-29T12:37:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:37:59 volumio go-librespot[2467]: time="2026-08-29T12:37:59+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:37:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:37:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:38:00 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:38:00 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:38:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Aug 29 12:38:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:02 volumio go-librespot[2492]: go-librespot daemon starting... Aug 29 12:38:02 volumio go-librespot[2493]: time="2026-08-29T12:38:02+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:38:02 volumio go-librespot[2493]: time="2026-08-29T12:38:02+02:00" level=debug msg="app state loaded" Aug 29 12:38:02 volumio go-librespot[2493]: time="2026-08-29T12:38:02+02:00" level=debug msg="stored credentials not found" Aug 29 12:38:02 volumio go-librespot[2493]: time="2026-08-29T12:38:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:38:02 volumio go-librespot[2493]: time="2026-08-29T12:38:02+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:38:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:38:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:38:03 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:38:03 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:38:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Aug 29 12:38:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:05 volumio go-librespot[2505]: go-librespot daemon starting... Aug 29 12:38:05 volumio go-librespot[2506]: time="2026-08-29T12:38:05+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:38:05 volumio go-librespot[2506]: time="2026-08-29T12:38:05+02:00" level=debug msg="app state loaded" Aug 29 12:38:05 volumio go-librespot[2506]: time="2026-08-29T12:38:05+02:00" level=debug msg="stored credentials not found" Aug 29 12:38:05 volumio go-librespot[2506]: time="2026-08-29T12:38:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:38:05 volumio go-librespot[2506]: time="2026-08-29T12:38:05+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:38:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:38:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:38:06 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:38:06 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:38:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Aug 29 12:38:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:08 volumio go-librespot[2517]: go-librespot daemon starting... Aug 29 12:38:08 volumio go-librespot[2518]: time="2026-08-29T12:38:08+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:38:08 volumio go-librespot[2518]: time="2026-08-29T12:38:08+02:00" level=debug msg="app state loaded" Aug 29 12:38:08 volumio go-librespot[2518]: time="2026-08-29T12:38:08+02:00" level=debug msg="stored credentials not found" Aug 29 12:38:08 volumio go-librespot[2518]: time="2026-08-29T12:38:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:38:08 volumio go-librespot[2518]: time="2026-08-29T12:38:08+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:38:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:38:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:38:09 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:38:09 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:38:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Aug 29 12:38:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:12 volumio go-librespot[2543]: go-librespot daemon starting... Aug 29 12:38:12 volumio go-librespot[2544]: time="2026-08-29T12:38:12+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:38:12 volumio go-librespot[2544]: time="2026-08-29T12:38:12+02:00" level=debug msg="app state loaded" Aug 29 12:38:12 volumio go-librespot[2544]: time="2026-08-29T12:38:12+02:00" level=debug msg="stored credentials not found" Aug 29 12:38:12 volumio go-librespot[2544]: time="2026-08-29T12:38:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:38:12 volumio go-librespot[2544]: time="2026-08-29T12:38:12+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:38:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:38:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:38:12 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:38:12 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:38:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Aug 29 12:38:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:15 volumio go-librespot[2555]: go-librespot daemon starting... Aug 29 12:38:15 volumio go-librespot[2556]: time="2026-08-29T12:38:15+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:38:15 volumio go-librespot[2556]: time="2026-08-29T12:38:15+02:00" level=debug msg="app state loaded" Aug 29 12:38:15 volumio go-librespot[2556]: time="2026-08-29T12:38:15+02:00" level=debug msg="stored credentials not found" Aug 29 12:38:15 volumio go-librespot[2556]: time="2026-08-29T12:38:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:38:15 volumio go-librespot[2556]: time="2026-08-29T12:38:15+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:38:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:38:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:38:15 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:38:15 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:38:18 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:38:18 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:38:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Aug 29 12:38:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:18 volumio go-librespot[2568]: go-librespot daemon starting... Aug 29 12:38:18 volumio go-librespot[2569]: time="2026-08-29T12:38:18+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:38:18 volumio go-librespot[2569]: time="2026-08-29T12:38:18+02:00" level=debug msg="app state loaded" Aug 29 12:38:18 volumio go-librespot[2569]: time="2026-08-29T12:38:18+02:00" level=debug msg="stored credentials not found" Aug 29 12:38:18 volumio go-librespot[2569]: time="2026-08-29T12:38:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:38:18 volumio go-librespot[2569]: time="2026-08-29T12:38:18+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:38:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:38:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:38:20 volumio dhcpcd[930]: eth0: carrier acquired Aug 29 12:38:20 volumio kernel: e1000e 0000:00:1f.6 eth0: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx Aug 29 12:38:20 volumio dhcpcd[930]: eth0: IAID 24:b3:13:18 Aug 29 12:38:20 volumio dhcpcd[930]: eth0: adding address fe80::3402:7e31:f454:1146 Aug 29 12:38:20 volumio dhcpcd[930]: ipv6_addaddr1: Permission denied Aug 29 12:38:21 volumio dhcpcd[930]: eth0: soliciting a DHCP lease Aug 29 12:38:21 volumio dhcpcd[930]: eth0: offered 192.168.0.204 from 192.168.0.1 Aug 29 12:38:21 volumio dhcpcd[930]: eth0: probing address 192.168.0.204/24 Aug 29 12:38:21 volumio ifplugd(eth0)[1042]: Link beat detected. Aug 29 12:38:21 volumio ifplugd(eth0)[1042]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Aug 29 12:38:21 volumio ifplugd(eth0)[1042]: client: command failed: No such device (-19) Aug 29 12:38:21 volumio dhcpcd[930]: control command: dhcpcd eth0 Aug 29 12:38:21 volumio ifplugd(eth0)[1042]: client: sending commands to dhcpcd process Aug 29 12:38:21 volumio dhcpcd[930]: control_free: No such file or directory Aug 29 12:38:21 volumio dhcpcd[930]: eth0: soliciting an IPv6 router Aug 29 12:38:21 volumio volumio[1096]: info: Received Get System Info Aug 29 12:38:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 12:38:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 12:38:21 volumio volumio[1096]: info: Discovery: Getting this device information Aug 29 12:38:21 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Aug 29 12:38:21 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Aug 29 12:38:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 12:38:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 12:38:21 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 12:38:21 volumio ifplugd(eth0)[1042]: Program executed successfully. Aug 29 12:38:21 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:38:21 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:38:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Aug 29 12:38:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:21 volumio go-librespot[2650]: go-librespot daemon starting... Aug 29 12:38:21 volumio go-librespot[2651]: time="2026-08-29T12:38:21+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:38:21 volumio go-librespot[2651]: time="2026-08-29T12:38:21+02:00" level=debug msg="app state loaded" Aug 29 12:38:21 volumio go-librespot[2651]: time="2026-08-29T12:38:21+02:00" level=debug msg="stored credentials not found" Aug 29 12:38:21 volumio go-librespot[2651]: time="2026-08-29T12:38:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:38:21 volumio go-librespot[2651]: time="2026-08-29T12:38:21+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:38:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:38:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:38:22 volumio volumio5-onboarding[1592]: time=2026-08-29T12:38:22.198+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 12:38:24 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:38:24 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: AggregateError Aug 29 12:38:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Aug 29 12:38:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:25 volumio go-librespot[2662]: go-librespot daemon starting... Aug 29 12:38:25 volumio go-librespot[2663]: time="2026-08-29T12:38:25+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:38:25 volumio go-librespot[2663]: time="2026-08-29T12:38:25+02:00" level=debug msg="app state loaded" Aug 29 12:38:25 volumio go-librespot[2663]: time="2026-08-29T12:38:25+02:00" level=debug msg="stored credentials not found" Aug 29 12:38:25 volumio go-librespot[2663]: time="2026-08-29T12:38:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:38:25 volumio go-librespot[2663]: time="2026-08-29T12:38:25+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 29 12:38:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 29 12:38:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 29 12:38:25 volumio dhcpcd[930]: eth0: leased 192.168.0.204 for 43200 seconds Aug 29 12:38:25 volumio avahi-daemon[889]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.204. Aug 29 12:38:25 volumio avahi-daemon[889]: New relevant interface eth0.IPv4 for mDNS. Aug 29 12:38:25 volumio avahi-daemon[889]: Registering new address record for 192.168.0.204 on eth0.IPv4. Aug 29 12:38:25 volumio dhcpcd[930]: eth0: adding route to 192.168.0.0/24 Aug 29 12:38:25 volumio dhcpcd[930]: eth0: adding default route via 192.168.0.1 Aug 29 12:38:25 volumio systemd[1]: welcome.service: Deactivated successfully. Aug 29 12:38:25 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 29 12:38:25 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 29 12:38:26 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 29 12:38:26 volumio welcome[2684]: Resolved ip:[1] 192.168.0.204 Aug 29 12:38:26 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 29 12:38:26 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Aug 29 12:38:26 volumio volumio[1096]: info: Received Get System Info Aug 29 12:38:26 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 12:38:26 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 12:38:26 volumio volumio[1096]: info: Discovery: Getting this device information Aug 29 12:38:26 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Aug 29 12:38:26 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Aug 29 12:38:26 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 12:38:26 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 12:38:26 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 12:38:26 volumio volumio[1096]: info: Discovery: this is already registered, a7a94d0c-fb28-4f1e-b590-af0237022916 Aug 29 12:38:26 volumio volumio[1096]: info: Discovery: Found device Volumio Aug 29 12:38:26 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Aug 29 12:38:26 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Aug 29 12:38:27 volumio volumio5-onboarding[1592]: time=2026-08-29T12:38:27.374+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 12:38:27 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:38:27 volumio volumio[1096]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 29 12:38:27 volumio ntpd[1032]: IO: Listen normally on 3 eth0 192.168.0.204:123 Aug 29 12:38:27 volumio ntpd[1032]: IO: new interface(s) found: waking up resolver Aug 29 12:38:27 volumio ntpd[1032]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 12:38:27 volumio ntpd[1032]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 29 12:38:27 volumio ntpd[1032]: DNS: Pool taking: 81.0.124.200 Aug 29 12:38:27 volumio ntpd[1032]: DNS: Pool taking: 193.6.222.47 Aug 29 12:38:27 volumio ntpd[1032]: DNS: Pool taking: 188.156.114.7 Aug 29 12:38:27 volumio ntpd[1032]: DNS: Pool taking: 193.41.47.84 Aug 29 12:38:27 volumio ntpd[1032]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Aug 29 12:38:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Aug 29 12:38:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 29 12:38:28 volumio go-librespot[2711]: go-librespot daemon starting... Aug 29 12:38:28 volumio go-librespot[2712]: time="2026-08-29T12:38:28+02:00" level=info msg="running go-librespot 0.7.1" Aug 29 12:38:28 volumio go-librespot[2712]: time="2026-08-29T12:38:28+02:00" level=debug msg="app state loaded" Aug 29 12:38:28 volumio go-librespot[2712]: time="2026-08-29T12:38:28+02:00" level=debug msg="stored credentials not found" Aug 29 12:38:28 volumio go-librespot[2712]: time="2026-08-29T12:38:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 29 12:38:28 volumio go-librespot[2712]: time="2026-08-29T12:38:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 12:38:28 volumio go-librespot[2712]: time="2026-08-29T12:38:28+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 12:38:28 volumio go-librespot[2712]: time="2026-08-29T12:38:28+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 12:38:28 volumio go-librespot[2712]: time="2026-08-29T12:38:28+02:00" level=info msg="zeroconf server listening on port 44679" Aug 29 12:38:28 volumio go-librespot[2712]: time="2026-08-29T12:38:28+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 29 12:38:28 volumio upmpdcli[2725]: writing RSA key Aug 29 12:38:28 volumio ntpd[1032]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 12:38:28 volumio ntpd[1032]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 29 12:38:28 volumio ntpd[1032]: DNS: Pool taking: 193.41.47.85 Aug 29 12:38:28 volumio ntpd[1032]: DNS: Pool taking: 62.112.195.26 Aug 29 12:38:28 volumio ntpd[1032]: DNS: Pool taking: 91.82.109.180 Aug 29 12:38:28 volumio ntpd[1032]: DNS: Pool taking: 62.112.195.56 Aug 29 12:38:28 volumio ntpd[1032]: DNS: Pool taking: 2001:ac8:26:62:0:1:0:14 Aug 29 12:38:28 volumio ntpd[1032]: DNS: Pool taking: 2a12:bec4:1da0:3e::123 Aug 29 12:38:28 volumio ntpd[1032]: DNS: Pool taking: 2606:4700:f1::123 Aug 29 12:38:28 volumio ntpd[1032]: DNS: Pool taking: 2606:4700:f1::1 Aug 29 12:38:28 volumio ntpd[1032]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Aug 29 12:38:29 volumio volumio[1096]: info: Volumio Network Manager: Network status updated: 1 Aug 29 12:38:29 volumio ntpd[1032]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 12:38:29 volumio ntpd[1032]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 29 12:38:29 volumio ntpd[1032]: DNS: Pool taking: 195.228.75.149 Aug 29 12:38:29 volumio ntpd[1032]: DNS: Pool skipping: 188.156.114.7 Aug 29 12:38:29 volumio ntpd[1032]: DNS: Pool taking: 193.224.163.77 Aug 29 12:38:29 volumio ntpd[1032]: DNS: Pool skipping: 81.0.124.200 Aug 29 12:38:29 volumio ntpd[1032]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Aug 29 12:38:30 volumio volumio[1096]: info: Initializing connection to go-librespot Websocket Aug 29 12:38:30 volumio go-librespot[2712]: time="2026-08-29T12:38:30+02:00" level=debug msg="new websocket client" Aug 29 12:38:30 volumio volumio[1096]: info: Connection to go-librespot Websocket established Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 29 12:38:30 volumio volumio[1096]: info: Adding plugin bluetooth to MyMusic Plugins Aug 29 12:38:30 volumio volumio[1096]: info: Adding plugin multiroom to MyMusic Plugins Aug 29 12:38:30 volumio volumio[1096]: info: Adding plugin metavolumio to MyMusic Plugins Aug 29 12:38:30 volumio volumio[1096]: info: Adding plugin cd_controller to MyMusic Plugins Aug 29 12:38:30 volumio volumio[1096]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 29 12:38:30 volumio volumio[1096]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 29 12:38:30 volumio volumio[1096]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 29 12:38:30 volumio volumio[1096]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 29 12:38:30 volumio ntpd[1032]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 29 12:38:30 volumio ntpd[1032]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Aug 29 12:38:30 volumio ntpd[1032]: DNS: Pool skipping: 188.156.114.7 Aug 29 12:38:30 volumio ntpd[1032]: DNS: Pool skipping: 62.112.195.26 Aug 29 12:38:30 volumio ntpd[1032]: DNS: Pool taking: 37.221.209.57 Aug 29 12:38:30 volumio ntpd[1032]: DNS: Pool taking: 37.221.209.24 Aug 29 12:38:30 volumio ntpd[1032]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Aug 29 12:38:31 volumio volumio[1096]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 29 12:38:31 volumio volumio[1096]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 29 12:38:31 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 12:38:31 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 12:38:31 volumio volumio[1096]: info: Starting MyVolumio Remote Streaming Endpoints Aug 29 12:38:31 volumio volumio[1096]: info: MyVolumio login type: Token Aug 29 12:38:31 volumio volumio[1096]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 29 12:38:31 volumio volumio[1096]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 29 12:38:31 volumio volumio[1096]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 29 12:38:31 volumio volumio[1096]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 29 12:38:31 volumio volumio[1096]: info: Streaming services startup Aug 29 12:38:31 volumio volumio[1096]: info: Starting Streaming Daemon Aug 29 12:38:31 volumio volumio[1096]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 29 12:38:31 volumio sudo[2754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 12:38:31 volumio sudo[2754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 12:38:31 volumio sudo[2754]: pam_unix(sudo:session): session closed for user root Aug 29 12:38:31 volumio volumio[1096]: error: Cannot start Volumio Streaming Daemon Aug 29 12:38:31 volumio volumio[1096]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 12:38:31 volumio volumio[1096]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 12:38:31 volumio volumio[1096]: error: MyVolumio Custom Token format not valid, refreshing it Aug 29 12:38:32 volumio volumio[1096]: info: MyVolumio login type: Token Aug 29 12:38:32 volumio volumio[1096]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 29 12:38:33 volumio volumio[1096]: info: MyVolumio token set successfully Aug 29 12:38:33 volumio volumio[1096]: info: MYVOLUMIO: Adding device Aug 29 12:38:33 volumio volumio[1096]: info: MYVOLUMIO: Evaluating Server Aug 29 12:38:33 volumio volumio[1096]: info: MyVolumio Plan changed: premium Aug 29 12:38:33 volumio volumio[1096]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Aug 29 12:38:33 volumio volumio[1096]: info: Removing browser output: myVolumio user plan is not superstar Aug 29 12:38:33 volumio volumio[1096]: info: Removing audio output: Aug 29 12:38:33 volumio volumio[1096]: info: MYVOLUMIO: Adding device Aug 29 12:38:33 volumio volumio[1096]: info: MYVOLUMIO: Evaluating Server Aug 29 12:38:33 volumio volumio[1096]: info: Remote config written successfully Aug 29 12:38:33 volumio volumio[1096]: info: Starting Tunnel 1 Aug 29 12:38:33 volumio volumio[1096]: info: Starting Tunnel Connection Checker Aug 29 12:38:33 volumio volumio[1096]: info: Getting Spotify volume Aug 29 12:38:33 volumio volumio[1096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 29 12:38:33 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Aug 29 12:38:33 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Aug 29 12:38:33 volumio volumio[1096]: info: Aligning Spotify Volume to Volumio Volume Aug 29 12:38:33 volumio volumio[1096]: info: CoreCommandRouter::volumioGetState Aug 29 12:38:33 volumio volumio[1096]: info: CorePlayQueue::getTrack 0 Aug 29 12:38:33 volumio volumio[1096]: info: Setting Spotify Volume from Volumio: 46 Aug 29 12:38:33 volumio volumio[1096]: info: MYVolumio Device enabled Aug 29 12:38:33 volumio volumio[1096]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 29 12:38:33 volumio volumio[1096]: info: MyVolumio status changed Aug 29 12:38:33 volumio volumio[1096]: info: Streaming services startup Aug 29 12:38:33 volumio volumio[1096]: info: Starting Streaming Daemon Aug 29 12:38:33 volumio volumio[1096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 29 12:38:33 volumio sudo[2803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 12:38:33 volumio volumio[1096]: error: [MyVolumio PluginManager] Cache data is invalid! Aug 29 12:38:33 volumio sudo[2803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 12:38:33 volumio sudo[2803]: pam_unix(sudo:session): session closed for user root Aug 29 12:38:33 volumio volumio[1096]: error: Cannot start Volumio Streaming Daemon Aug 29 12:38:33 volumio volumio[1096]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 12:38:33 volumio volumio[1096]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 12:38:33 volumio ntpd[1032]: CLOCK: time stepped by -0.831744 Aug 29 12:38:33 volumio ntpd[1032]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Aug 29 12:38:33 volumio systemd-journald[586]: Time jumped backwards, rotating. Aug 29 12:38:33 volumio volumio[1096]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Aug 29 12:38:33 volumio volumio[1096]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 12:38:33 volumio volumio[1096]: Error: Unable to resolve or reject the same promise twice Aug 29 12:38:33 volumio volumio[1096]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 29 12:38:33 volumio volumio[1096]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32090) Aug 29 12:38:33 volumio volumio[1096]: at Socket.emit (node:events:526:35) Aug 29 12:38:33 volumio volumio[1096]: at endReadableNT (node:internal/streams/readable:1376:12) Aug 29 12:38:33 volumio volumio[1096]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Aug 29 12:38:33 volumio volumio[1096]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 12:38:33 volumio sudo[2821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 12:37' Aug 29 12:38:33 volumio sudo[2821]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"