Feb 06 23:52:01 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:01 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:02 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 95. Feb 06 23:52:02 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 06 23:52:02 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 06 23:52:02 volumio upmpdcli[9682]: Could not open config: /tmp/upmpdcli.conf Feb 06 23:52:02 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:02 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 06 23:52:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 442. Feb 06 23:52:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:02 volumio go-librespot[9683]: go-librespot daemon starting... Feb 06 23:52:02 volumio go-librespot[9684]: time="2026-02-06T23:52:02-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:02 volumio go-librespot[9684]: time="2026-02-06T23:52:02-06:00" level=debug msg="app state loaded" Feb 06 23:52:02 volumio go-librespot[9684]: time="2026-02-06T23:52:02-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:02 volumio go-librespot[9684]: time="2026-02-06T23:52:02-06: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 06 23:52:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:04 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:04 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 443. Feb 06 23:52:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:05 volumio go-librespot[9709]: go-librespot daemon starting... Feb 06 23:52:05 volumio go-librespot[9710]: time="2026-02-06T23:52:05-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:05 volumio go-librespot[9710]: time="2026-02-06T23:52:05-06:00" level=debug msg="app state loaded" Feb 06 23:52:05 volumio go-librespot[9710]: time="2026-02-06T23:52:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:05 volumio go-librespot[9710]: time="2026-02-06T23:52:05-06: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 06 23:52:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:07 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:07 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 444. Feb 06 23:52:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:08 volumio go-librespot[9721]: go-librespot daemon starting... Feb 06 23:52:08 volumio go-librespot[9722]: time="2026-02-06T23:52:08-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:08 volumio go-librespot[9722]: time="2026-02-06T23:52:08-06:00" level=debug msg="app state loaded" Feb 06 23:52:08 volumio go-librespot[9722]: time="2026-02-06T23:52:08-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:08 volumio go-librespot[9722]: time="2026-02-06T23:52:08-06: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 06 23:52:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:10 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:10 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 445. Feb 06 23:52:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:12 volumio go-librespot[9734]: go-librespot daemon starting... Feb 06 23:52:12 volumio go-librespot[9735]: time="2026-02-06T23:52:12-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:12 volumio go-librespot[9735]: time="2026-02-06T23:52:12-06:00" level=debug msg="app state loaded" Feb 06 23:52:12 volumio go-librespot[9735]: time="2026-02-06T23:52:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:12 volumio go-librespot[9735]: time="2026-02-06T23:52:12-06: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 06 23:52:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:13 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:13 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 446. Feb 06 23:52:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:15 volumio go-librespot[9760]: go-librespot daemon starting... Feb 06 23:52:15 volumio go-librespot[9761]: time="2026-02-06T23:52:15-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:15 volumio go-librespot[9761]: time="2026-02-06T23:52:15-06:00" level=debug msg="app state loaded" Feb 06 23:52:15 volumio go-librespot[9761]: time="2026-02-06T23:52:15-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:15 volumio go-librespot[9761]: time="2026-02-06T23:52:15-06: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 06 23:52:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:16 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:16 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:17 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 96. Feb 06 23:52:17 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 06 23:52:17 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 06 23:52:17 volumio upmpdcli[9771]: Could not open config: /tmp/upmpdcli.conf Feb 06 23:52:17 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:17 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 06 23:52:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 447. Feb 06 23:52:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:18 volumio go-librespot[9773]: go-librespot daemon starting... Feb 06 23:52:18 volumio go-librespot[9774]: time="2026-02-06T23:52:18-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:18 volumio go-librespot[9774]: time="2026-02-06T23:52:18-06:00" level=debug msg="app state loaded" Feb 06 23:52:18 volumio go-librespot[9774]: time="2026-02-06T23:52:18-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:18 volumio go-librespot[9774]: time="2026-02-06T23:52:18-06: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 06 23:52:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:19 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:19 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 448. Feb 06 23:52:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:21 volumio go-librespot[9785]: go-librespot daemon starting... Feb 06 23:52:21 volumio go-librespot[9786]: time="2026-02-06T23:52:21-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:21 volumio go-librespot[9786]: time="2026-02-06T23:52:21-06:00" level=debug msg="app state loaded" Feb 06 23:52:21 volumio go-librespot[9786]: time="2026-02-06T23:52:21-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:21 volumio go-librespot[9786]: time="2026-02-06T23:52:21-06: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 06 23:52:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:22 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:22 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 449. Feb 06 23:52:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:25 volumio go-librespot[9797]: go-librespot daemon starting... Feb 06 23:52:25 volumio go-librespot[9799]: time="2026-02-06T23:52:25-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:25 volumio go-librespot[9799]: time="2026-02-06T23:52:25-06:00" level=debug msg="app state loaded" Feb 06 23:52:25 volumio go-librespot[9799]: time="2026-02-06T23:52:25-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:25 volumio go-librespot[9799]: time="2026-02-06T23:52:25-06: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 06 23:52:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:25 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:25 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 450. Feb 06 23:52:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:28 volumio go-librespot[9824]: go-librespot daemon starting... Feb 06 23:52:28 volumio go-librespot[9825]: time="2026-02-06T23:52:28-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:28 volumio go-librespot[9825]: time="2026-02-06T23:52:28-06:00" level=debug msg="app state loaded" Feb 06 23:52:28 volumio go-librespot[9825]: time="2026-02-06T23:52:28-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:28 volumio go-librespot[9825]: time="2026-02-06T23:52:28-06: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 06 23:52:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:28 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:28 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:31 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:31 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 451. Feb 06 23:52:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:31 volumio go-librespot[9836]: go-librespot daemon starting... Feb 06 23:52:31 volumio go-librespot[9837]: time="2026-02-06T23:52:31-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:31 volumio go-librespot[9837]: time="2026-02-06T23:52:31-06:00" level=debug msg="app state loaded" Feb 06 23:52:31 volumio go-librespot[9837]: time="2026-02-06T23:52:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:31 volumio go-librespot[9837]: time="2026-02-06T23:52:31-06: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 06 23:52:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:32 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 97. Feb 06 23:52:32 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 06 23:52:32 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 06 23:52:32 volumio upmpdcli[9846]: Could not open config: /tmp/upmpdcli.conf Feb 06 23:52:32 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:32 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 06 23:52:34 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:34 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 452. Feb 06 23:52:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:34 volumio go-librespot[9849]: go-librespot daemon starting... Feb 06 23:52:34 volumio go-librespot[9850]: time="2026-02-06T23:52:34-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:34 volumio go-librespot[9850]: time="2026-02-06T23:52:34-06:00" level=debug msg="app state loaded" Feb 06 23:52:34 volumio go-librespot[9850]: time="2026-02-06T23:52:34-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:34 volumio go-librespot[9850]: time="2026-02-06T23:52:34-06: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 06 23:52:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:37 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:37 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 453. Feb 06 23:52:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:38 volumio go-librespot[9875]: go-librespot daemon starting... Feb 06 23:52:38 volumio go-librespot[9876]: time="2026-02-06T23:52:38-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:38 volumio go-librespot[9876]: time="2026-02-06T23:52:38-06:00" level=debug msg="app state loaded" Feb 06 23:52:38 volumio go-librespot[9876]: time="2026-02-06T23:52:38-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:38 volumio go-librespot[9876]: time="2026-02-06T23:52:38-06: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 06 23:52:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:40 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:40 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 454. Feb 06 23:52:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:41 volumio go-librespot[9888]: go-librespot daemon starting... Feb 06 23:52:41 volumio go-librespot[9889]: time="2026-02-06T23:52:41-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:41 volumio go-librespot[9889]: time="2026-02-06T23:52:41-06:00" level=debug msg="app state loaded" Feb 06 23:52:41 volumio go-librespot[9889]: time="2026-02-06T23:52:41-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:41 volumio go-librespot[9889]: time="2026-02-06T23:52:41-06: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 06 23:52:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:43 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:43 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 455. Feb 06 23:52:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:44 volumio go-librespot[9900]: go-librespot daemon starting... Feb 06 23:52:44 volumio go-librespot[9901]: time="2026-02-06T23:52:44-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:44 volumio go-librespot[9901]: time="2026-02-06T23:52:44-06:00" level=debug msg="app state loaded" Feb 06 23:52:44 volumio go-librespot[9901]: time="2026-02-06T23:52:44-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:44 volumio go-librespot[9901]: time="2026-02-06T23:52:44-06: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 06 23:52:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:46 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:46 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 456. Feb 06 23:52:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:47 volumio go-librespot[9927]: go-librespot daemon starting... Feb 06 23:52:47 volumio go-librespot[9928]: time="2026-02-06T23:52:47-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:47 volumio go-librespot[9928]: time="2026-02-06T23:52:47-06:00" level=debug msg="app state loaded" Feb 06 23:52:47 volumio go-librespot[9928]: time="2026-02-06T23:52:47-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:47 volumio go-librespot[9928]: time="2026-02-06T23:52:47-06: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 06 23:52:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:47 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 98. Feb 06 23:52:47 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 06 23:52:47 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 06 23:52:47 volumio upmpdcli[9936]: Could not open config: /tmp/upmpdcli.conf Feb 06 23:52:47 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:47 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 06 23:52:49 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:49 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 457. Feb 06 23:52:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:51 volumio go-librespot[9940]: go-librespot daemon starting... Feb 06 23:52:51 volumio go-librespot[9941]: time="2026-02-06T23:52:51-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:51 volumio go-librespot[9941]: time="2026-02-06T23:52:51-06:00" level=debug msg="app state loaded" Feb 06 23:52:51 volumio go-librespot[9941]: time="2026-02-06T23:52:51-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:51 volumio go-librespot[9941]: time="2026-02-06T23:52:51-06: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 06 23:52:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:52 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:52 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 458. Feb 06 23:52:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:54 volumio go-librespot[9953]: go-librespot daemon starting... Feb 06 23:52:54 volumio go-librespot[9954]: time="2026-02-06T23:52:54-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:54 volumio go-librespot[9954]: time="2026-02-06T23:52:54-06:00" level=debug msg="app state loaded" Feb 06 23:52:54 volumio go-librespot[9954]: time="2026-02-06T23:52:54-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:54 volumio go-librespot[9954]: time="2026-02-06T23:52:54-06: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 06 23:52:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:55 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:55 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 459. Feb 06 23:52:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:52:57 volumio go-librespot[9979]: go-librespot daemon starting... Feb 06 23:52:57 volumio go-librespot[9980]: time="2026-02-06T23:52:57-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:52:57 volumio go-librespot[9980]: time="2026-02-06T23:52:57-06:00" level=debug msg="app state loaded" Feb 06 23:52:57 volumio go-librespot[9980]: time="2026-02-06T23:52:57-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:52:57 volumio go-librespot[9980]: time="2026-02-06T23:52:57-06: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 06 23:52:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:52:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:52:58 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:52:58 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:52:58 volumio dhcpcd[900]: eth0: offered 192.168.100.240 from 192.168.100.1 Feb 06 23:52:58 volumio dhcpcd[900]: eth0: probing address 192.168.100.240/24 Feb 06 23:53:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 460. Feb 06 23:53:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:53:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:53:00 volumio go-librespot[9992]: go-librespot daemon starting... Feb 06 23:53:00 volumio go-librespot[9993]: time="2026-02-06T23:53:00-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:53:00 volumio go-librespot[9993]: time="2026-02-06T23:53:00-06:00" level=debug msg="app state loaded" Feb 06 23:53:00 volumio go-librespot[9993]: time="2026-02-06T23:53:00-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:53:00 volumio go-librespot[9993]: time="2026-02-06T23:53:00-06: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 06 23:53:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:53:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 06 23:53:01 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:53:01 volumio volumio[1105]: info: Error connecting to go-librespot Websocket: AggregateError Feb 06 23:53:02 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 99. Feb 06 23:53:02 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 06 23:53:03 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 06 23:53:03 volumio upmpdcli[10003]: Could not open config: /tmp/upmpdcli.conf Feb 06 23:53:03 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 06 23:53:03 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 06 23:53:03 volumio dhcpcd[900]: eth0: leased 192.168.100.240 for 259200 seconds Feb 06 23:53:03 volumio avahi-daemon[809]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.100.240. Feb 06 23:53:03 volumio avahi-daemon[809]: New relevant interface eth0.IPv4 for mDNS. Feb 06 23:53:03 volumio avahi-daemon[809]: Registering new address record for 192.168.100.240 on eth0.IPv4. Feb 06 23:53:03 volumio dhcpcd[900]: eth0: adding route to 192.168.100.0/24 Feb 06 23:53:03 volumio dhcpcd[900]: eth0: adding default route via 192.168.100.1 Feb 06 23:53:03 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 06 23:53:03 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 06 23:53:03 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 06 23:53:03 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 06 23:53:03 volumio welcome[10017]: Resolved ip:[1] 192.168.100.240 Feb 06 23:53:03 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 06 23:53:03 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 06 23:53:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 461. Feb 06 23:53:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:53:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 06 23:53:03 volumio go-librespot[10030]: go-librespot daemon starting... Feb 06 23:53:04 volumio go-librespot[10031]: time="2026-02-06T23:53:04-06:00" level=info msg="running go-librespot 0.6.2" Feb 06 23:53:04 volumio go-librespot[10031]: time="2026-02-06T23:53:04-06:00" level=debug msg="app state loaded" Feb 06 23:53:04 volumio go-librespot[10031]: time="2026-02-06T23:53:04-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 06 23:53:04 volumio volumio[1105]: info: Discovery: this is already registered, 184a9c36-0421-4147-b167-1dd34152dfd9 Feb 06 23:53:04 volumio volumio[1105]: info: Discovery: Found device Volumio Feb 06 23:53:04 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Feb 06 23:53:04 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Feb 06 23:53:04 volumio volumio[1105]: info: Initializing connection to go-librespot Websocket Feb 06 23:53:04 volumio go-librespot[10031]: time="2026-02-06T23:53:04-06:00" level=debug msg="new websocket client" Feb 06 23:53:04 volumio volumio[1105]: info: Connection to go-librespot Websocket established Feb 06 23:53:05 volumio ntpd[1022]: IO: Listen normally on 3 eth0 192.168.100.240:123 Feb 06 23:53:05 volumio ntpd[1022]: IO: new interface(s) found: waking up resolver Feb 06 23:53:05 volumio ntpd[1022]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 06 23:53:05 volumio volumio[1105]: info: Volumio Network Manager: Network status updated: 1 Feb 06 23:53:05 volumio ntpd[1022]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 06 23:53:05 volumio ntpd[1022]: DNS: Pool taking: 132.248.30.3 Feb 06 23:53:05 volumio ntpd[1022]: DNS: Pool taking: 192.36.143.130 Feb 06 23:53:05 volumio ntpd[1022]: DNS: Pool taking: 193.182.111.13 Feb 06 23:53:05 volumio ntpd[1022]: DNS: Pool taking: 45.231.168.6 Feb 06 23:53:05 volumio ntpd[1022]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Feb 06 23:53:05 volumio go-librespot[10031]: time="2026-02-06T23:53:05-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 06 23:53:05 volumio go-librespot[10031]: time="2026-02-06T23:53:05-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 06 23:53:05 volumio go-librespot[10031]: time="2026-02-06T23:53:05-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 06 23:53:05 volumio go-librespot[10031]: time="2026-02-06T23:53:05-06:00" level=info msg="zeroconf server listening on port 32975" Feb 06 23:53:05 volumio go-librespot[10031]: time="2026-02-06T23:53:05-06:00" level=debug msg="obtained new client token: AAAirzt36eGz3Tt/HLFhpUZNANOF/zKNcQ7HgYZeSk8RJ1E8KI2Cl1rsN5EnvOpl0gwh4DO6cuIGh1jtLZiXI02hfXFpJPhzkatOtFw5/HMtoez0OHJnGlvagvjdDedOLT2ipvnqLlBFDyH5Z3XcflcevPeeTnPlKSt1Cozt27b1mQNF4Q0fdnSeKzI5sfD1LC8bGNfjp/vILF7xc2tSLx9xF5/HxtTtkH6yIyfasfbTvbOCSGFwyNk=" Feb 06 23:53:05 volumio go-librespot[10031]: time="2026-02-06T23:53:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 06 23:53:05 volumio go-librespot[10031]: time="2026-02-06T23:53:05-06:00" level=debug msg="completed keyexchange" Feb 06 23:53:05 volumio go-librespot[10031]: time="2026-02-06T23:53:05-06:00" level=debug msg="completed challenge" Feb 06 23:53:05 volumio go-librespot[10031]: time="2026-02-06T23:53:05-06:00" level=info msg="authenticated AP" username="je*********o3" Feb 06 23:53:05 volumio go-librespot[10031]: time="2026-02-06T23:53:05-06:00" level=info msg="authenticated Login5" username="je*********o3" Feb 06 23:53:05 volumio go-librespot[10031]: time="2026-02-06T23:53:05-06:00" level=debug msg="initializing zeroconf session" username="je*********o3" Feb 06 23:53:06 volumio ntpd[1022]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=debug msg="dealer connection opened" Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=trace msg="starting accesspoint recv loop" Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=trace msg="starting dealer recv loop" Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=trace msg="received accesspoint ping" Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=debug msg="received connection id: YjU1NjI4MGQtMjE2...NEU1MEE4RTkxQg==" Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=trace msg="received accesspoint pong ack" Feb 06 23:53:06 volumio ntpd[1022]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 06 23:53:06 volumio ntpd[1022]: DNS: Pool taking: 132.248.3.29 Feb 06 23:53:06 volumio ntpd[1022]: DNS: Pool skipping: 45.231.168.6 Feb 06 23:53:06 volumio ntpd[1022]: DNS: Pool skipping: 192.36.143.130 Feb 06 23:53:06 volumio ntpd[1022]: DNS: Pool skipping: 193.182.111.13 Feb 06 23:53:06 volumio ntpd[1022]: DNS: Pool taking: 2a03:8600::aa Feb 06 23:53:06 volumio ntpd[1022]: DNS: Pool taking: 240b:4013:6:1500:4424:e971:3cba:f1d4 Feb 06 23:53:06 volumio ntpd[1022]: DNS: Pool taking: 2a03:8600::dd Feb 06 23:53:06 volumio ntpd[1022]: DNS: Pool taking: 2001:19f0:b400:2ffd:5400:5ff:fe63:33ba Feb 06 23:53:06 volumio ntpd[1022]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Feb 06 23:53:06 volumio go-librespot[10031]: time="2026-02-06T23:53:06-06:00" level=debug msg="put connect state because NEW_DEVICE" Feb 06 23:53:07 volumio ntpd[1022]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 06 23:53:07 volumio ntpd[1022]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 06 23:53:07 volumio ntpd[1022]: DNS: Pool skipping: 193.182.111.13 Feb 06 23:53:07 volumio ntpd[1022]: DNS: Pool skipping: 192.36.143.130 Feb 06 23:53:07 volumio ntpd[1022]: DNS: Pool skipping: 132.248.3.29 Feb 06 23:53:07 volumio ntpd[1022]: DNS: Pool skipping: 45.231.168.6 Feb 06 23:53:07 volumio ntpd[1022]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Feb 06 23:53:07 volumio volumio[1105]: info: Getting Spotify volume Feb 06 23:53:07 volumio volumio[1105]: info: Spotify volume: 100 Feb 06 23:53:07 volumio volumio[1105]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 06 23:53:07 volumio volumio[1105]: info: Adding plugin bluetooth to MyMusic Plugins Feb 06 23:53:07 volumio volumio[1105]: info: Adding plugin multiroom to MyMusic Plugins Feb 06 23:53:07 volumio volumio[1105]: info: Adding plugin metavolumio to MyMusic Plugins Feb 06 23:53:07 volumio volumio[1105]: info: Adding plugin cd_controller to MyMusic Plugins Feb 06 23:53:07 volumio volumio[1105]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 06 23:53:07 volumio volumio[1105]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 06 23:53:07 volumio volumio[1105]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 06 23:53:07 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 06 23:53:08 volumio ntpd[1022]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 06 23:53:08 volumio ntpd[1022]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 06 23:53:08 volumio ntpd[1022]: DNS: Pool skipping: 45.231.168.6 Feb 06 23:53:08 volumio ntpd[1022]: DNS: Pool skipping: 193.182.111.13 Feb 06 23:53:08 volumio ntpd[1022]: DNS: Pool skipping: 192.36.143.130 Feb 06 23:53:08 volumio ntpd[1022]: DNS: Pool skipping: 132.248.30.3 Feb 06 23:53:08 volumio ntpd[1022]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 06 23:53:08 volumio volumio[1105]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 06 23:53:08 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 06 23:53:08 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 06 23:53:08 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 06 23:53:08 volumio volumio[1105]: info: Starting MyVolumio Remote Streaming Endpoints Feb 06 23:53:08 volumio volumio[1105]: info: MyVolumio login type: Token Feb 06 23:53:08 volumio volumio[1105]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 06 23:53:08 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 06 23:53:08 volumio volumio[1105]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 06 23:53:08 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 06 23:53:08 volumio volumio[1105]: info: Streaming services startup Feb 06 23:53:08 volumio volumio[1105]: info: Starting Streaming Daemon Feb 06 23:53:08 volumio volumio[1105]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 06 23:53:08 volumio sudo[10065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 06 23:53:08 volumio sudo[10065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 23:53:08 volumio sudo[10065]: pam_unix(sudo:session): session closed for user root Feb 06 23:53:09 volumio volumio[1105]: error: Cannot start Volumio Streaming Daemon Feb 06 23:53:09 volumio volumio[1105]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 06 23:53:09 volumio volumio[1105]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 06 23:53:09 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Feb 06 23:53:09 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Feb 06 23:53:09 volumio volumio[1105]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Feb 06 23:53:09 volumio volumio[1105]: SPOTIFY: SPOTIFY VOLUME 100 Feb 06 23:53:09 volumio volumio[1105]: SPOTIFY: VOLUMIO VOLUME 48 Feb 06 23:53:09 volumio volumio[1105]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 06 23:53:09 volumio volumio[1105]: info: Setting Spotify Volume from Volumio: 48 Feb 06 23:53:09 volumio volumio[1105]: error: MyVolumio Custom Token format not valid, refreshing it Feb 06 23:53:09 volumio volumio[1105]: info: MyVolumio login type: Token Feb 06 23:53:10 volumio volumio[1105]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 06 23:53:10 volumio volumio[1105]: SPOTIFY: SETTING SPOTIFY VOLUME 48 Feb 06 23:53:10 volumio volumio[1105]: info: Sending Spotify command with payload to local API: /player/volume Feb 06 23:53:10 volumio go-librespot[10031]: time="2026-02-06T23:53:10-06:00" level=debug msg="update volume requested to 31456/65535" Feb 06 23:53:10 volumio volumio[1105]: info: MyVolumio token set successfully Feb 06 23:53:10 volumio volumio[1105]: info: MYVOLUMIO: Adding device Feb 06 23:53:10 volumio volumio[1105]: info: MYVOLUMIO: Evaluating Server Feb 06 23:53:10 volumio go-librespot[10031]: time="2026-02-06T23:53:10-06:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 06 23:53:10 volumio go-librespot[10031]: time="2026-02-06T23:53:10-06:00" level=trace msg="emitting websocket event: volume" Feb 06 23:53:10 volumio volumio[1105]: info: MyVolumio status changed Feb 06 23:53:10 volumio volumio[1105]: info: Streaming services startup Feb 06 23:53:10 volumio volumio[1105]: info: Starting Streaming Daemon Feb 06 23:53:10 volumio volumio[1105]: info: Removing browser output: myVolumio user plan is not superstar Feb 06 23:53:10 volumio volumio[1105]: info: Removing audio output: Feb 06 23:53:10 volumio volumio[1105]: info: Stoppping Tunnel 1 Feb 06 23:53:10 volumio sudo[10096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 06 23:53:10 volumio sudo[10096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 23:53:10 volumio volumio[1105]: SPOTIFY: received: {"type":"volume","data":{"value":48,"max":100}} Feb 06 23:53:10 volumio volumio[1105]: SPOTIFY: RECEIVED SPOTIFY VOLUME 48 Feb 06 23:53:10 volumio sudo[10098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 06 23:53:10 volumio sudo[10098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 23:53:10 volumio sudo[10096]: pam_unix(sudo:session): session closed for user root Feb 06 23:53:10 volumio volumio[1105]: error: Cannot start Volumio Streaming Daemon Feb 06 23:53:10 volumio volumio[1105]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 06 23:53:10 volumio volumio[1105]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 06 23:53:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 06 23:53:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 06 23:53:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 06 23:53:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 06 23:53:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 06 23:53:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 06 23:53:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 06 23:53:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 06 23:53:10 volumio sudo[10098]: pam_unix(sudo:session): session closed for user root Feb 06 23:53:10 volumio volumio[1105]: info: Remote SSH Stopped Feb 06 23:53:00 volumio ntpd[1022]: CLOCK: time stepped by -10.851688 Feb 06 23:53:00 volumio systemd-journald[538]: Time jumped backwards, rotating. Feb 06 23:53:00 volumio volumio[1105]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Feb 06 23:53:00 volumio volumio[1105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 06 23:53:00 volumio ntpd[1022]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Feb 06 23:53:00 volumio volumio[1105]: Error: Unable to resolve or reject the same promise twice Feb 06 23:53:00 volumio volumio[1105]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 06 23:53:00 volumio volumio[1105]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32090) Feb 06 23:53:00 volumio volumio[1105]: at Socket.emit (node:events:526:35) Feb 06 23:53:00 volumio volumio[1105]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 06 23:53:00 volumio volumio[1105]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Feb 06 23:53:00 volumio volumio[1105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 06 23:53:00 volumio systemd[1]: Starting apt-daily.service - Daily apt download activities... Feb 06 23:53:00 volumio sudo[10127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-06 23:52' Feb 06 23:53:00 volumio sudo[10127]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"