Dec 21 00:07:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 465. Dec 21 00:07:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:00 volumio go-librespot[9182]: go-librespot daemon starting... Dec 21 00:07:00 volumio go-librespot[9183]: time="2025-12-21T00:07:00+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:00 volumio go-librespot[9183]: time="2025-12-21T00:07:00+02:00" level=debug msg="app state loaded" Dec 21 00:07:00 volumio go-librespot[9183]: time="2025-12-21T00:07:00+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:00 volumio go-librespot[9183]: time="2025-12-21T00:07:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:00 volumio go-librespot[9183]: time="2025-12-21T00:07:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:01 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:01 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 466. Dec 21 00:07:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:04 volumio go-librespot[9190]: go-librespot daemon starting... Dec 21 00:07:04 volumio go-librespot[9191]: time="2025-12-21T00:07:04+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:04 volumio go-librespot[9191]: time="2025-12-21T00:07:04+02:00" level=debug msg="app state loaded" Dec 21 00:07:04 volumio go-librespot[9191]: time="2025-12-21T00:07:04+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:04 volumio go-librespot[9191]: time="2025-12-21T00:07:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:04 volumio go-librespot[9191]: time="2025-12-21T00:07:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:04 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:04 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 467. Dec 21 00:07:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:07 volumio go-librespot[9211]: go-librespot daemon starting... Dec 21 00:07:07 volumio go-librespot[9212]: time="2025-12-21T00:07:07+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:07 volumio go-librespot[9212]: time="2025-12-21T00:07:07+02:00" level=debug msg="app state loaded" Dec 21 00:07:07 volumio go-librespot[9212]: time="2025-12-21T00:07:07+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:07 volumio go-librespot[9212]: time="2025-12-21T00:07:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:07 volumio go-librespot[9212]: time="2025-12-21T00:07:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:07 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:07 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:10 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:10 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 468. Dec 21 00:07:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:10 volumio go-librespot[9220]: go-librespot daemon starting... Dec 21 00:07:10 volumio go-librespot[9221]: time="2025-12-21T00:07:10+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:10 volumio go-librespot[9221]: time="2025-12-21T00:07:10+02:00" level=debug msg="app state loaded" Dec 21 00:07:10 volumio go-librespot[9221]: time="2025-12-21T00:07:10+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:10 volumio go-librespot[9221]: time="2025-12-21T00:07:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:10 volumio go-librespot[9221]: time="2025-12-21T00:07:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:13 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:13 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 469. Dec 21 00:07:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:13 volumio go-librespot[9227]: go-librespot daemon starting... Dec 21 00:07:13 volumio go-librespot[9228]: time="2025-12-21T00:07:13+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:13 volumio go-librespot[9228]: time="2025-12-21T00:07:13+02:00" level=debug msg="app state loaded" Dec 21 00:07:13 volumio go-librespot[9228]: time="2025-12-21T00:07:13+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:13 volumio go-librespot[9228]: time="2025-12-21T00:07:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:13 volumio go-librespot[9228]: time="2025-12-21T00:07:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:16 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:16 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 470. Dec 21 00:07:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:17 volumio go-librespot[9251]: go-librespot daemon starting... Dec 21 00:07:17 volumio go-librespot[9252]: time="2025-12-21T00:07:17+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:17 volumio go-librespot[9252]: time="2025-12-21T00:07:17+02:00" level=debug msg="app state loaded" Dec 21 00:07:17 volumio go-librespot[9252]: time="2025-12-21T00:07:17+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:17 volumio go-librespot[9252]: time="2025-12-21T00:07:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:17 volumio go-librespot[9252]: time="2025-12-21T00:07:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:19 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:19 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 471. Dec 21 00:07:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:20 volumio go-librespot[9258]: go-librespot daemon starting... Dec 21 00:07:20 volumio go-librespot[9259]: time="2025-12-21T00:07:20+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:20 volumio go-librespot[9259]: time="2025-12-21T00:07:20+02:00" level=debug msg="app state loaded" Dec 21 00:07:20 volumio go-librespot[9259]: time="2025-12-21T00:07:20+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:20 volumio go-librespot[9259]: time="2025-12-21T00:07:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:20 volumio go-librespot[9259]: time="2025-12-21T00:07:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:22 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:22 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 472. Dec 21 00:07:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:23 volumio go-librespot[9265]: go-librespot daemon starting... Dec 21 00:07:23 volumio go-librespot[9266]: time="2025-12-21T00:07:23+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:23 volumio go-librespot[9266]: time="2025-12-21T00:07:23+02:00" level=debug msg="app state loaded" Dec 21 00:07:23 volumio go-librespot[9266]: time="2025-12-21T00:07:23+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:23 volumio go-librespot[9266]: time="2025-12-21T00:07:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:23 volumio go-librespot[9266]: time="2025-12-21T00:07:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:25 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:25 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 473. Dec 21 00:07:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:26 volumio go-librespot[9275]: go-librespot daemon starting... Dec 21 00:07:26 volumio go-librespot[9276]: time="2025-12-21T00:07:26+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:26 volumio go-librespot[9276]: time="2025-12-21T00:07:26+02:00" level=debug msg="app state loaded" Dec 21 00:07:26 volumio go-librespot[9276]: time="2025-12-21T00:07:26+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:26 volumio go-librespot[9276]: time="2025-12-21T00:07:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:26 volumio go-librespot[9276]: time="2025-12-21T00:07:26+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:28 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:28 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 474. Dec 21 00:07:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:30 volumio go-librespot[9296]: go-librespot daemon starting... Dec 21 00:07:30 volumio go-librespot[9297]: time="2025-12-21T00:07:30+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:30 volumio go-librespot[9297]: time="2025-12-21T00:07:30+02:00" level=debug msg="app state loaded" Dec 21 00:07:30 volumio go-librespot[9297]: time="2025-12-21T00:07:30+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:30 volumio go-librespot[9297]: time="2025-12-21T00:07:30+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:30 volumio go-librespot[9297]: time="2025-12-21T00:07:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:31 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:31 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 475. Dec 21 00:07:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:33 volumio go-librespot[9303]: go-librespot daemon starting... Dec 21 00:07:33 volumio go-librespot[9304]: time="2025-12-21T00:07:33+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:33 volumio go-librespot[9304]: time="2025-12-21T00:07:33+02:00" level=debug msg="app state loaded" Dec 21 00:07:33 volumio go-librespot[9304]: time="2025-12-21T00:07:33+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:33 volumio go-librespot[9304]: time="2025-12-21T00:07:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:33 volumio go-librespot[9304]: time="2025-12-21T00:07:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:34 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:34 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 476. Dec 21 00:07:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:36 volumio go-librespot[9313]: go-librespot daemon starting... Dec 21 00:07:36 volumio go-librespot[9314]: time="2025-12-21T00:07:36+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:36 volumio go-librespot[9314]: time="2025-12-21T00:07:36+02:00" level=debug msg="app state loaded" Dec 21 00:07:36 volumio go-librespot[9314]: time="2025-12-21T00:07:36+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:36 volumio go-librespot[9314]: time="2025-12-21T00:07:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:36 volumio go-librespot[9314]: time="2025-12-21T00:07:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:37 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:37 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 477. Dec 21 00:07:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:39 volumio go-librespot[9335]: go-librespot daemon starting... Dec 21 00:07:39 volumio go-librespot[9336]: time="2025-12-21T00:07:39+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:39 volumio go-librespot[9336]: time="2025-12-21T00:07:39+02:00" level=debug msg="app state loaded" Dec 21 00:07:39 volumio go-librespot[9336]: time="2025-12-21T00:07:39+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:39 volumio go-librespot[9336]: time="2025-12-21T00:07:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:39 volumio go-librespot[9336]: time="2025-12-21T00:07:39+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:40 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:40 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 478. Dec 21 00:07:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:43 volumio go-librespot[9342]: go-librespot daemon starting... Dec 21 00:07:43 volumio go-librespot[9343]: time="2025-12-21T00:07:43+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:43 volumio go-librespot[9343]: time="2025-12-21T00:07:43+02:00" level=debug msg="app state loaded" Dec 21 00:07:43 volumio go-librespot[9343]: time="2025-12-21T00:07:43+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:43 volumio go-librespot[9343]: time="2025-12-21T00:07:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:43 volumio go-librespot[9343]: time="2025-12-21T00:07:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:43 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:43 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 479. Dec 21 00:07:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:46 volumio go-librespot[9350]: go-librespot daemon starting... Dec 21 00:07:46 volumio go-librespot[9351]: time="2025-12-21T00:07:46+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:46 volumio go-librespot[9351]: time="2025-12-21T00:07:46+02:00" level=debug msg="app state loaded" Dec 21 00:07:46 volumio go-librespot[9351]: time="2025-12-21T00:07:46+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:46 volumio go-librespot[9351]: time="2025-12-21T00:07:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:46 volumio go-librespot[9351]: time="2025-12-21T00:07:46+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:46 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:46 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:49 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:49 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 480. Dec 21 00:07:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:49 volumio go-librespot[9371]: go-librespot daemon starting... Dec 21 00:07:49 volumio go-librespot[9372]: time="2025-12-21T00:07:49+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:49 volumio go-librespot[9372]: time="2025-12-21T00:07:49+02:00" level=debug msg="app state loaded" Dec 21 00:07:49 volumio go-librespot[9372]: time="2025-12-21T00:07:49+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:49 volumio go-librespot[9372]: time="2025-12-21T00:07:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:49 volumio go-librespot[9372]: time="2025-12-21T00:07:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:50 volumio volumio[5997]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.183 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 21 00:07:50 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 00:07:50 volumio volumio[5997]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 00:07:50 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 00:07:50 volumio volumio[5997]: info: CoreCommandRouter::volumioGetState Dec 21 00:07:50 volumio volumio[5997]: info: CorePlayQueue::getTrack 0 Dec 21 00:07:50 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 21 00:07:50 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 21 00:07:50 volumio volumio[5997]: info: Received Get System Info Dec 21 00:07:50 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 00:07:50 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 00:07:50 volumio volumio[5997]: info: Discovery: Getting this device information Dec 21 00:07:50 volumio volumio[5997]: info: CoreCommandRouter::volumioGetState Dec 21 00:07:50 volumio volumio[5997]: info: CorePlayQueue::getTrack 0 Dec 21 00:07:50 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 00:07:50 volumio volumio[5997]: info: CoreCommandRouter::volumioGetState Dec 21 00:07:50 volumio volumio[5997]: info: CorePlayQueue::getTrack 0 Dec 21 00:07:50 volumio volumio[5997]: info: Listing playlists Dec 21 00:07:52 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:52 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 481. Dec 21 00:07:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:52 volumio go-librespot[9379]: go-librespot daemon starting... Dec 21 00:07:52 volumio go-librespot[9380]: time="2025-12-21T00:07:52+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:52 volumio go-librespot[9380]: time="2025-12-21T00:07:52+02:00" level=debug msg="app state loaded" Dec 21 00:07:52 volumio go-librespot[9380]: time="2025-12-21T00:07:52+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:52 volumio go-librespot[9380]: time="2025-12-21T00:07:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:52 volumio go-librespot[9380]: time="2025-12-21T00:07:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:54 volumio volumio[5997]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 00:07:54 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 21 00:07:54 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 21 00:07:54 volumio sudo[9390]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 21 00:07:54 volumio sudo[9390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:07:54 volumio sudo[9395]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 21 00:07:54 volumio sudo[9395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:07:54 volumio sudo[9395]: pam_unix(sudo:session): session closed for user root Dec 21 00:07:54 volumio sudo[9402]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 21 00:07:54 volumio sudo[9402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:07:54 volumio sudo[9390]: pam_unix(sudo:session): session closed for user root Dec 21 00:07:54 volumio sudo[9402]: pam_unix(sudo:session): session closed for user root Dec 21 00:07:54 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 21 00:07:54 volumio sudo[9408]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 21 00:07:54 volumio sudo[9408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:07:54 volumio sudo[9416]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 00:07:54 volumio sudo[9416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:07:54 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 21 00:07:54 volumio sudo[9408]: pam_unix(sudo:session): session closed for user root Dec 21 00:07:54 volumio sudo[9416]: pam_unix(sudo:session): session closed for user root Dec 21 00:07:54 volumio sudo[9418]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:07:54 volumio sudo[9418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:07:54 volumio sudo[9418]: pam_unix(sudo:session): session closed for user root Dec 21 00:07:54 volumio sudo[9422]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 21 00:07:54 volumio sudo[9422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:07:55 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:55 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 482. Dec 21 00:07:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:56 volumio go-librespot[9425]: go-librespot daemon starting... Dec 21 00:07:56 volumio go-librespot[9426]: time="2025-12-21T00:07:56+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:56 volumio go-librespot[9426]: time="2025-12-21T00:07:56+02:00" level=debug msg="app state loaded" Dec 21 00:07:56 volumio go-librespot[9426]: time="2025-12-21T00:07:56+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:56 volumio go-librespot[9426]: time="2025-12-21T00:07:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:56 volumio go-librespot[9426]: time="2025-12-21T00:07:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:07:58 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:07:58 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:07:58 volumio sudo[9422]: pam_unix(sudo:session): session closed for user root Dec 21 00:07:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 483. Dec 21 00:07:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:07:59 volumio go-librespot[9449]: go-librespot daemon starting... Dec 21 00:07:59 volumio go-librespot[9450]: time="2025-12-21T00:07:59+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:07:59 volumio go-librespot[9450]: time="2025-12-21T00:07:59+02:00" level=debug msg="app state loaded" Dec 21 00:07:59 volumio go-librespot[9450]: time="2025-12-21T00:07:59+02:00" level=debug msg="stored credentials not found" Dec 21 00:07:59 volumio go-librespot[9450]: time="2025-12-21T00:07:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:07:59 volumio go-librespot[9450]: time="2025-12-21T00:07:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:07:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:07:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:01 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:01 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:08:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 484. Dec 21 00:08:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:02 volumio go-librespot[9456]: go-librespot daemon starting... Dec 21 00:08:02 volumio go-librespot[9457]: time="2025-12-21T00:08:02+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:08:02 volumio go-librespot[9457]: time="2025-12-21T00:08:02+02:00" level=debug msg="app state loaded" Dec 21 00:08:02 volumio go-librespot[9457]: time="2025-12-21T00:08:02+02:00" level=debug msg="stored credentials not found" Dec 21 00:08:02 volumio go-librespot[9457]: time="2025-12-21T00:08:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:08:02 volumio go-librespot[9457]: time="2025-12-21T00:08:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:08:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:08:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:04 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:04 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:08:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 485. Dec 21 00:08:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:05 volumio go-librespot[9464]: go-librespot daemon starting... Dec 21 00:08:05 volumio go-librespot[9465]: time="2025-12-21T00:08:05+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:08:05 volumio go-librespot[9465]: time="2025-12-21T00:08:05+02:00" level=debug msg="app state loaded" Dec 21 00:08:05 volumio go-librespot[9465]: time="2025-12-21T00:08:05+02:00" level=debug msg="stored credentials not found" Dec 21 00:08:05 volumio go-librespot[9465]: time="2025-12-21T00:08:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:08:05 volumio go-librespot[9465]: time="2025-12-21T00:08:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:08:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:08:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:07 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:07 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:08:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 486. Dec 21 00:08:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:09 volumio go-librespot[9485]: go-librespot daemon starting... Dec 21 00:08:09 volumio go-librespot[9486]: time="2025-12-21T00:08:09+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:08:09 volumio go-librespot[9486]: time="2025-12-21T00:08:09+02:00" level=debug msg="app state loaded" Dec 21 00:08:09 volumio go-librespot[9486]: time="2025-12-21T00:08:09+02:00" level=debug msg="stored credentials not found" Dec 21 00:08:09 volumio go-librespot[9486]: time="2025-12-21T00:08:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:08:09 volumio go-librespot[9486]: time="2025-12-21T00:08:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:08:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:08:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:10 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:10 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:08:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 487. Dec 21 00:08:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:12 volumio go-librespot[9493]: go-librespot daemon starting... Dec 21 00:08:12 volumio go-librespot[9494]: time="2025-12-21T00:08:12+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:08:12 volumio go-librespot[9494]: time="2025-12-21T00:08:12+02:00" level=debug msg="app state loaded" Dec 21 00:08:12 volumio go-librespot[9494]: time="2025-12-21T00:08:12+02:00" level=debug msg="stored credentials not found" Dec 21 00:08:12 volumio go-librespot[9494]: time="2025-12-21T00:08:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:08:12 volumio go-librespot[9494]: time="2025-12-21T00:08:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:08:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:08:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:13 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:13 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:08:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 488. Dec 21 00:08:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:15 volumio go-librespot[9500]: go-librespot daemon starting... Dec 21 00:08:15 volumio go-librespot[9501]: time="2025-12-21T00:08:15+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:08:15 volumio go-librespot[9501]: time="2025-12-21T00:08:15+02:00" level=debug msg="app state loaded" Dec 21 00:08:15 volumio go-librespot[9501]: time="2025-12-21T00:08:15+02:00" level=debug msg="stored credentials not found" Dec 21 00:08:15 volumio go-librespot[9501]: time="2025-12-21T00:08:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:08:15 volumio go-librespot[9501]: time="2025-12-21T00:08:15+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:08:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:08:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:16 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:16 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:08:16 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Dec 21 00:08:16 volumio volumio[5997]: info: Saving new wireless network Dec 21 00:08:16 volumio sudo[9508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Dec 21 00:08:16 volumio sudo[9508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:16 volumio sudo[9508]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:16 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 21 00:08:16 volumio volumio[5997]: info: Discovery: Restarting Advertising due to device name change Dec 21 00:08:16 volumio sudo[9511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 21 00:08:16 volumio sudo[9511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:16 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 21 00:08:16 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 21 00:08:16 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 21 00:08:16 volumio systemd[1]: wireless.service: Consumed 1.466s CPU time. Dec 21 00:08:16 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 21 00:08:16 volumio wireless.js[9520]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 21 00:08:16 volumio wireless.js[9520]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 21 00:08:16 volumio wireless.js[9520]: WIRELESS.JS: Cleaning previous... Dec 21 00:08:16 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Dec 21 00:08:16 volumio dnsmasq[5952]: exiting on receipt of SIGTERM Dec 21 00:08:16 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Dec 21 00:08:16 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Dec 21 00:08:16 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Dec 21 00:08:17 volumio dhcpcd[1150]: wlan0: carrier lost Dec 21 00:08:17 volumio kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Dec 21 00:08:17 volumio systemd[1]: hostapd.service: Deactivated successfully. Dec 21 00:08:17 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Dec 21 00:08:17 volumio sudo[9568]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 21 00:08:17 volumio sudo[9568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 00:08:17 volumio avahi-daemon[1070]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 21 00:08:17 volumio avahi-daemon[1070]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 21 00:08:17 volumio avahi-daemon[1070]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 21 00:08:17 volumio sudo[9568]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:17 volumio volumio[5997]: info: Discovery: A device disappeared from network Dec 21 00:08:17 volumio volumio[5997]: info: Discovery: Device volumio disappeared from network Dec 21 00:08:17 volumio sudo[9571]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 21 00:08:17 volumio sudo[9571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 00:08:17 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 21 00:08:17 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 21 00:08:17 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 21 00:08:17 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 21 00:08:17 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 21 00:08:17 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 21 00:08:17 volumio volumio[5997]: info: Discovery: A device disappeared from network Dec 21 00:08:17 volumio welcome[9573]: Resolved ip:[0] Dec 21 00:08:17 volumio sudo[9571]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:17 volumio sudo[9576]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:17 volumio sudo[9576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:17 volumio sudo[9576]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:17 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 21 00:08:17 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 21 00:08:17 volumio wireless.js[9520]: WIRELESS.JS: Stopped aP Dec 21 00:08:17 volumio sudo[9587]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 21 00:08:17 volumio sudo[9587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:17 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 21 00:08:17 volumio sudo[9587]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:17 volumio sudo[9589]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 21 00:08:17 volumio sudo[9589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:17 volumio sudo[9589]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:17 volumio sudo[9597]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 21 00:08:17 volumio sudo[9597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:17 volumio sudo[9597]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:17 volumio sudo[9599]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 21 00:08:17 volumio sudo[9599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:18 volumio sudo[9604]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:18 volumio sudo[9604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:18 volumio sudo[9604]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 489. Dec 21 00:08:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:18 volumio go-librespot[9606]: go-librespot daemon starting... Dec 21 00:08:18 volumio go-librespot[9607]: time="2025-12-21T00:08:18+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:08:18 volumio go-librespot[9607]: time="2025-12-21T00:08:18+02:00" level=debug msg="app state loaded" Dec 21 00:08:18 volumio go-librespot[9607]: time="2025-12-21T00:08:18+02:00" level=debug msg="stored credentials not found" Dec 21 00:08:18 volumio go-librespot[9607]: time="2025-12-21T00:08:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:08:18 volumio go-librespot[9607]: time="2025-12-21T00:08:18+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:08:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:08:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:18 volumio ntpd[1291]: IO: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=793 secs Dec 21 00:08:19 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:19 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: AggregateError Dec 21 00:08:19 volumio sudo[9614]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:19 volumio sudo[9614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:19 volumio sudo[9614]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:20 volumio sudo[9617]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:20 volumio sudo[9617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:20 volumio sudo[9617]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:20 volumio sudo[9599]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:20 volumio wireless.js[9520]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: UA Dec 21 00:08:20 volumio sudo[9621]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 21 00:08:20 volumio sudo[9621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:20 volumio sudo[9621]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:20 volumio sudo[9623]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set UA Dec 21 00:08:20 volumio sudo[9623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:20 volumio sudo[9623]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:20 volumio wireless.js[9520]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: UA Dec 21 00:08:20 volumio wireless.js[9520]: WIRELESS.JS: Start wireless flow Dec 21 00:08:20 volumio wireless.js[9520]: WIRELESS.JS: Stopped hotspot (if there).. Dec 21 00:08:20 volumio sudo[9631]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 21 00:08:20 volumio sudo[9631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 00:08:20 volumio sudo[9631]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:20 volumio sudo[9633]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 21 00:08:20 volumio sudo[9633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 00:08:21 volumio sudo[9633]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:21 volumio wireless.js[9520]: WIRELESS.JS: DHCP IP fallback Dec 21 00:08:21 volumio wireless.js[9520]: WIRELESS.JS: Start ap Dec 21 00:08:21 volumio wpa_supplicant[9638]: Successfully initialized wpa_supplicant Dec 21 00:08:21 volumio sudo[9639]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 21 00:08:21 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 21 00:08:21 volumio sudo[9639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 00:08:21 volumio dhcpcd[1150]: ps_ctl_dispatch: cannot handle another client Dec 21 00:08:21 volumio dhcpcd[1150]: control_free: No such file or directory Dec 21 00:08:21 volumio sudo[9639]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:21 volumio wpa_supplicant[9638]: nl80211: kernel reports: Registration to specific type not supported Dec 21 00:08:21 volumio volumio[5997]: info: Discovery: Started advertising with name: Volumio Dec 21 00:08:21 volumio sudo[9646]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:21 volumio sudo[9646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:21 volumio sudo[9646]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 490. Dec 21 00:08:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:22 volumio go-librespot[9648]: go-librespot daemon starting... Dec 21 00:08:22 volumio go-librespot[9649]: time="2025-12-21T00:08:22+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:08:22 volumio go-librespot[9649]: time="2025-12-21T00:08:22+02:00" level=debug msg="app state loaded" Dec 21 00:08:22 volumio go-librespot[9649]: time="2025-12-21T00:08:22+02:00" level=debug msg="stored credentials not found" Dec 21 00:08:22 volumio go-librespot[9649]: time="2025-12-21T00:08:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:08:22 volumio go-librespot[9649]: time="2025-12-21T00:08:22+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:08:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:08:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:22 volumio wireless.js[9520]: WIRELESS.JS: trying... Dec 21 00:08:22 volumio sudo[9657]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 21 00:08:22 volumio sudo[9657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:22 volumio sudo[9657]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:22 volumio volumio[5997]: info: Discovery: adding ee63f2fc-13d1-4972-889c-8cfc8b299775 Dec 21 00:08:22 volumio volumio[5997]: info: Discovery: Found device Volumio Dec 21 00:08:22 volumio volumio[5997]: info: CoreCommandRouter::volumioGetState Dec 21 00:08:22 volumio volumio[5997]: info: CorePlayQueue::getTrack 0 Dec 21 00:08:22 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:22 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: AggregateError Dec 21 00:08:22 volumio sudo[9661]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:22 volumio sudo[9661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:22 volumio sudo[9661]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:23 volumio wireless.js[9520]: WIRELESS.JS: trying... Dec 21 00:08:23 volumio sudo[9665]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 21 00:08:23 volumio sudo[9665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:23 volumio sudo[9665]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:23 volumio sudo[9668]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:23 volumio sudo[9668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:23 volumio sudo[9668]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:24 volumio wpa_supplicant[9643]: wlan0: Trying to associate with 50:ff:20:34:87:83 (SSID='Keenetic-9973' freq=5180 MHz) Dec 21 00:08:24 volumio wpa_supplicant[9643]: FT: Invalid key management type (2) Dec 21 00:08:24 volumio wireless.js[9520]: WIRELESS.JS: trying... Dec 21 00:08:24 volumio sudo[9672]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 21 00:08:24 volumio sudo[9672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:24 volumio sudo[9672]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:24 volumio wpa_supplicant[9643]: wlan0: Associated with 50:ff:20:34:87:83 Dec 21 00:08:24 volumio wpa_supplicant[9643]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 21 00:08:24 volumio wpa_supplicant[9643]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=UA Dec 21 00:08:24 volumio kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Dec 21 00:08:24 volumio sudo[9675]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:24 volumio sudo[9675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:24 volumio sudo[9675]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:24 volumio wpa_supplicant[9643]: wlan0: WPA: Key negotiation completed with 50:ff:20:34:87:83 [PTK=CCMP GTK=CCMP] Dec 21 00:08:24 volumio wpa_supplicant[9643]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:ff:20:34:87:83 completed [id=0 id_str=] Dec 21 00:08:24 volumio dhcpcd[1150]: wlan0: carrier acquired Dec 21 00:08:24 volumio dhcpcd[1150]: wlan0: connected to Access Point: Keenetic-9973 Dec 21 00:08:24 volumio dhcpcd[1150]: wlan0: IAID 67:45:83:37 Dec 21 00:08:25 volumio dhcpcd[1150]: wlan0: soliciting an IPv6 router Dec 21 00:08:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 491. Dec 21 00:08:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:25 volumio go-librespot[9679]: go-librespot daemon starting... Dec 21 00:08:25 volumio go-librespot[9680]: time="2025-12-21T00:08:25+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:08:25 volumio go-librespot[9680]: time="2025-12-21T00:08:25+02:00" level=debug msg="app state loaded" Dec 21 00:08:25 volumio go-librespot[9680]: time="2025-12-21T00:08:25+02:00" level=debug msg="stored credentials not found" Dec 21 00:08:25 volumio go-librespot[9680]: time="2025-12-21T00:08:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:08:25 volumio go-librespot[9680]: time="2025-12-21T00:08:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:08:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:08:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:25 volumio wireless.js[9520]: WIRELESS.JS: trying... Dec 21 00:08:25 volumio sudo[9688]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 21 00:08:25 volumio sudo[9688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:25 volumio sudo[9688]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:25 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:25 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: AggregateError Dec 21 00:08:25 volumio sudo[9691]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:25 volumio sudo[9691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:25 volumio sudo[9691]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:26 volumio dhcpcd[1150]: wlan0: rebinding lease of 192.168.2.112 Dec 21 00:08:26 volumio dhcpcd[1150]: wlan0: probing address 192.168.2.112/24 Dec 21 00:08:26 volumio wireless.js[9520]: WIRELESS.JS: trying... Dec 21 00:08:26 volumio sudo[9697]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 21 00:08:26 volumio sudo[9697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:26 volumio sudo[9697]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:26 volumio sudo[9700]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:26 volumio sudo[9700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:26 volumio sudo[9700]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:27 volumio wireless.js[9520]: WIRELESS.JS: trying... Dec 21 00:08:27 volumio sudo[9718]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 21 00:08:27 volumio sudo[9718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:27 volumio sudo[9718]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:27 volumio sudo[9721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:27 volumio sudo[9721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:27 volumio sudo[9721]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:28 volumio wireless.js[9520]: WIRELESS.JS: trying... Dec 21 00:08:28 volumio sudo[9725]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 21 00:08:28 volumio sudo[9725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:28 volumio sudo[9725]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:28 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:28 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: AggregateError Dec 21 00:08:28 volumio sudo[9728]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:28 volumio sudo[9728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:28 volumio sudo[9728]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 492. Dec 21 00:08:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:28 volumio go-librespot[9730]: go-librespot daemon starting... Dec 21 00:08:28 volumio go-librespot[9731]: time="2025-12-21T00:08:28+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:08:28 volumio go-librespot[9731]: time="2025-12-21T00:08:28+02:00" level=debug msg="app state loaded" Dec 21 00:08:28 volumio go-librespot[9731]: time="2025-12-21T00:08:28+02:00" level=debug msg="stored credentials not found" Dec 21 00:08:28 volumio go-librespot[9731]: time="2025-12-21T00:08:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:08:28 volumio go-librespot[9731]: time="2025-12-21T00:08:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 21 00:08:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:08:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:29 volumio wireless.js[9520]: WIRELESS.JS: trying... Dec 21 00:08:29 volumio sudo[9740]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 21 00:08:29 volumio sudo[9740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:29 volumio sudo[9740]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:29 volumio sudo[9743]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:29 volumio sudo[9743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:29 volumio sudo[9743]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:30 volumio wireless.js[9520]: WIRELESS.JS: trying... Dec 21 00:08:30 volumio sudo[9747]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 21 00:08:30 volumio sudo[9747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:30 volumio sudo[9747]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:30 volumio sudo[9750]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:30 volumio sudo[9750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:30 volumio sudo[9750]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:31 volumio dhcpcd[1150]: wlan0: leased 192.168.2.112 for 25200 seconds Dec 21 00:08:31 volumio avahi-daemon[1070]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.112. Dec 21 00:08:31 volumio avahi-daemon[1070]: New relevant interface wlan0.IPv4 for mDNS. Dec 21 00:08:31 volumio avahi-daemon[1070]: Registering new address record for 192.168.2.112 on wlan0.IPv4. Dec 21 00:08:31 volumio dhcpcd[1150]: wlan0: adding route to 192.168.2.0/24 Dec 21 00:08:31 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 21 00:08:31 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 21 00:08:31 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 21 00:08:31 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 21 00:08:31 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 21 00:08:31 volumio dhcpcd[1150]: wlan0: adding default route via 192.168.2.1 Dec 21 00:08:31 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 21 00:08:31 volumio welcome[9771]: Resolved ip:[1] 192.168.2.112 Dec 21 00:08:31 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 21 00:08:31 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 21 00:08:31 volumio wireless.js[9520]: WIRELESS.JS: trying... Dec 21 00:08:31 volumio sudo[9778]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 21 00:08:31 volumio sudo[9778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:31 volumio sudo[9778]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:31 volumio wireless.js[9520]: WIRELESS.JS: Connected to: ----Keenetic-9973---- Dec 21 00:08:31 volumio volumio[5997]: verbose: New Socket.io Connection to 192.168.2.112 from 192.168.2.91 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 21 00:08:31 volumio volumio[5997]: verbose: New Socket.io Connection to 192.168.2.112 from 192.168.2.91 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 21 00:08:31 volumio sudo[9781]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:31 volumio sudo[9781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 00:08:31 volumio volumio[5997]: verbose: New Socket.io Connection to 192.168.2.112 from 192.168.2.91 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 21 00:08:31 volumio sudo[9781]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:31 volumio wireless.js[9520]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 192.168.2.112, ipV6 is undefined Dec 21 00:08:31 volumio wireless.js[9520]: WIRELESS.JS: It's done! AP Dec 21 00:08:31 volumio wireless.js[9520]: WIRELESS.JS: Restarting avahi-daemon... Dec 21 00:08:31 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:31 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:08:31 volumio wireless.js[9520]: WIRELESS.JS: Notified systemd about wireless ready Dec 21 00:08:31 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 21 00:08:31 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 21 00:08:31 volumio sudo[9511]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:31 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 21 00:08:31 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 21 00:08:31 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 00:08:31 volumio systemd[1]: shairport-sync.service: Consumed 2.521s CPU time. Dec 21 00:08:31 volumio avahi-daemon[1070]: Got SIGTERM, quitting. Dec 21 00:08:31 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 21 00:08:31 volumio avahi-daemon[1070]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.112. Dec 21 00:08:31 volumio avahi-daemon[1070]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 21 00:08:31 volumio avahi-daemon[1070]: avahi-daemon 0.8 exiting. Dec 21 00:08:31 volumio volumio[5997]: info: Discovery: Browse raised the following error Error: dns service error: unknown Dec 21 00:08:31 volumio volumio[5997]: error: Discovery: Advertisement error: Error: dns service error: unknown Dec 21 00:08:31 volumio volumio[5997]: error: Discovery: advertisement error: Error: dns service error: unknown Dec 21 00:08:31 volumio volumio[5997]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Dec 21 00:08:31 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Dec 21 00:08:31 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 21 00:08:31 volumio dbus-daemon[1072]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.36' (uid=0 pid=1663 comm="/usr/sbin/smbd --foreground --no-process-group") Dec 21 00:08:31 volumio volumio[5997]: info: Discovery: Browse raised the following error Error: dns service error: unknown Dec 21 00:08:31 volumio avahi-daemon[9792]: Process 1070 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Dec 21 00:08:31 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 21 00:08:31 volumio sudo[9791]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:31 volumio sudo[9791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:31 volumio avahi-daemon[9792]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Dec 21 00:08:31 volumio avahi-daemon[9792]: Successfully dropped root privileges. Dec 21 00:08:31 volumio avahi-daemon[9792]: avahi-daemon 0.8 starting up. Dec 21 00:08:31 volumio dbus-daemon[1072]: [system] Successfully activated service 'org.freedesktop.Avahi' Dec 21 00:08:31 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 21 00:08:31 volumio sudo[9791]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:31 volumio avahi-daemon[9792]: Successfully called chroot(). Dec 21 00:08:31 volumio avahi-daemon[9792]: Successfully dropped remaining capabilities. Dec 21 00:08:31 volumio avahi-daemon[9792]: No service file found in /etc/avahi/services. Dec 21 00:08:31 volumio avahi-daemon[9792]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.112. Dec 21 00:08:31 volumio avahi-daemon[9792]: New relevant interface wlan0.IPv4 for mDNS. Dec 21 00:08:31 volumio avahi-daemon[9792]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 21 00:08:31 volumio avahi-daemon[9792]: New relevant interface lo.IPv4 for mDNS. Dec 21 00:08:31 volumio avahi-daemon[9792]: Network interface enumeration completed. Dec 21 00:08:31 volumio avahi-daemon[9792]: Registering new address record for 192.168.2.112 on wlan0.IPv4. Dec 21 00:08:31 volumio avahi-daemon[9792]: Registering new address record for 127.0.0.1 on lo.IPv4. Dec 21 00:08:31 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 21 00:08:31 volumio sudo[9798]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 21 00:08:31 volumio sudo[9798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:31 volumio sudo[9798]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 493. Dec 21 00:08:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:31 volumio go-librespot[9817]: go-librespot daemon starting... Dec 21 00:08:31 volumio go-librespot[9818]: time="2025-12-21T00:08:31+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:08:31 volumio go-librespot[9818]: time="2025-12-21T00:08:31+02:00" level=debug msg="app state loaded" Dec 21 00:08:31 volumio go-librespot[9818]: time="2025-12-21T00:08:31+02:00" level=debug msg="stored credentials not found" Dec 21 00:08:31 volumio go-librespot[9818]: time="2025-12-21T00:08:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:08:32 volumio go-librespot[9818]: time="2025-12-21T00:08:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 00:08:32 volumio go-librespot[9818]: time="2025-12-21T00:08:32+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 00:08:32 volumio go-librespot[9818]: time="2025-12-21T00:08:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 00:08:32 volumio go-librespot[9818]: time="2025-12-21T00:08:32+02:00" level=info msg="zeroconf server listening on port 46091" Dec 21 00:08:32 volumio go-librespot[9818]: time="2025-12-21T00:08:32+02:00" level=debug msg="obtained new client token: AAAFed8CjsP1xhCli/KM/QI23OZEHJcWwD3BVpbZVLyaJa7mdN3uoNjJ9Pd/yoFh6ocLzdObaTnXOVm9qy5nCUbMf0iPwvdOvh67sViVW2nKhLsxg9qt+lIRJdKyWj+3/OCQxYKQUBiXctCGKxy82rmJGyf2w1Isytt9DPVvQrkdaTL/QTIrt2e92BcbEd1NXWeIDK86OyAnuSabQsE4lnOBiuGvSoqqtyDC/4cqu9WMTOdyVJV+G1B7cFMV" Dec 21 00:08:32 volumio avahi-daemon[9792]: Server startup complete. Host name is volumio.local. Local service cookie is 1859881400. Dec 21 00:08:32 volumio go-librespot[9818]: time="2025-12-21T00:08:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 00:08:32 volumio go-librespot[9818]: time="2025-12-21T00:08:32+02:00" level=debug msg="completed keyexchange" Dec 21 00:08:32 volumio go-librespot[9818]: time="2025-12-21T00:08:32+02:00" level=debug msg="completed challenge" Dec 21 00:08:32 volumio go-librespot[9818]: time="2025-12-21T00:08:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 00:08:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:08:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:32 volumio ntpd[1291]: IO: Listen normally on 5 wlan0 192.168.2.112:123 Dec 21 00:08:32 volumio ntpd[1291]: IO: new interface(s) found: waking up resolver Dec 21 00:08:32 volumio ntpd[1291]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 21 00:08:32 volumio ntpd[1291]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 21 00:08:32 volumio ntpd[1291]: DNS: Pool taking: 79.142.192.130 Dec 21 00:08:32 volumio ntpd[1291]: DNS: Pool taking: 91.236.251.234 Dec 21 00:08:32 volumio ntpd[1291]: DNS: Pool taking: 94.158.46.150 Dec 21 00:08:32 volumio ntpd[1291]: DNS: Pool taking: 46.173.175.211 Dec 21 00:08:32 volumio ntpd[1291]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 21 00:08:33 volumio volumio[5997]: info: Adding plugin bluetooth to MyMusic Plugins Dec 21 00:08:33 volumio volumio[5997]: info: Adding plugin multiroom to MyMusic Plugins Dec 21 00:08:33 volumio volumio[5997]: info: Adding plugin metavolumio to MyMusic Plugins Dec 21 00:08:33 volumio volumio[5997]: info: Adding plugin cd_controller to MyMusic Plugins Dec 21 00:08:33 volumio volumio[5997]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 21 00:08:33 volumio volumio[5997]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 21 00:08:33 volumio volumio[5997]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 21 00:08:33 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 00:08:33 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 00:08:33 volumio volumio[5997]: info: Starting MyVolumio Remote Streaming Endpoints Dec 21 00:08:33 volumio volumio[5997]: info: MyVolumio login type: Token Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 21 00:08:33 volumio volumio[5997]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 21 00:08:33 volumio ntpd[1291]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 21 00:08:34 volumio ntpd[1291]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 21 00:08:34 volumio ntpd[1291]: DNS: Pool taking: 213.231.2.124 Dec 21 00:08:34 volumio ntpd[1291]: DNS: Pool taking: 31.28.161.68 Dec 21 00:08:34 volumio ntpd[1291]: DNS: Pool taking: 162.159.200.123 Dec 21 00:08:34 volumio ntpd[1291]: DNS: Pool taking: 109.110.82.19 Dec 21 00:08:34 volumio ntpd[1291]: DNS: Pool taking: 2a02:c6c1:c:207::13 Dec 21 00:08:34 volumio ntpd[1291]: DNS: Pool taking: 2001:67c:440:f620:91:236:251:34 Dec 21 00:08:34 volumio ntpd[1291]: DNS: Pool taking: 2001:67c:440:400::5 Dec 21 00:08:34 volumio ntpd[1291]: DNS: Pool taking: 2a05:b00::52:466a:507b Dec 21 00:08:34 volumio ntpd[1291]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 21 00:08:34 volumio volumio[5997]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 21 00:08:34 volumio volumio[5997]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 21 00:08:34 volumio volumio[5997]: info: Streaming services startup Dec 21 00:08:34 volumio volumio[5997]: info: Starting Streaming Daemon Dec 21 00:08:34 volumio sudo[9837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 21 00:08:34 volumio sudo[9837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:34 volumio volumio[5997]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 21 00:08:34 volumio sudo[9837]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:34 volumio volumio[5997]: error: Cannot start Volumio Streaming Daemon Dec 21 00:08:34 volumio volumio[5997]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 21 00:08:34 volumio volumio[5997]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 21 00:08:34 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:34 volumio volumio[5997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 00:08:34 volumio wpa_supplicant[9643]: wlan0: WNM: Preferred List Available Dec 21 00:08:34 volumio wpa_supplicant[9643]: wlan0: Trying to associate with 50:ff:20:34:87:81 (SSID='Keenetic-9973' freq=2442 MHz) Dec 21 00:08:34 volumio wpa_supplicant[9643]: FT: Invalid key management type (2) Dec 21 00:08:34 volumio dhcpcd[1150]: wlan0: carrier lost - roaming Dec 21 00:08:34 volumio dhcpcd[1150]: wlan0: changing route to 192.168.2.0/24 Dec 21 00:08:34 volumio kernel: ieee80211 phy0: brcmf_p2p_send_action_frame: Unknown Frame: category 0xa, action 0x8 Dec 21 00:08:34 volumio dhcpcd[1150]: wlan0: changing default route via 192.168.2.1 Dec 21 00:08:34 volumio ntpd[1291]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 21 00:08:35 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 00:08:35 volumio volumio[5997]: info: Received Get System Info Dec 21 00:08:35 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 00:08:35 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 00:08:35 volumio volumio[5997]: info: Discovery: Getting this device information Dec 21 00:08:35 volumio volumio[5997]: info: CoreCommandRouter::volumioGetState Dec 21 00:08:35 volumio volumio[5997]: info: CorePlayQueue::getTrack 0 Dec 21 00:08:35 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 00:08:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 494. Dec 21 00:08:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 00:08:35 volumio go-librespot[9859]: go-librespot daemon starting... Dec 21 00:08:35 volumio go-librespot[9860]: time="2025-12-21T00:08:35+02:00" level=info msg="running go-librespot 0.4.0" Dec 21 00:08:35 volumio go-librespot[9860]: time="2025-12-21T00:08:35+02:00" level=debug msg="app state loaded" Dec 21 00:08:35 volumio go-librespot[9860]: time="2025-12-21T00:08:35+02:00" level=debug msg="stored credentials not found" Dec 21 00:08:35 volumio go-librespot[9860]: time="2025-12-21T00:08:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 00:08:35 volumio ntpd[1291]: IO: Deleting interface #5 wlan0, 192.168.2.112#123, interface stats: received=8, sent=16, dropped=0, active_time=3 secs Dec 21 00:08:35 volumio ntpd[1291]: PROTO: 109.110.82.19 unlink local addr 192.168.2.112 -> Dec 21 00:08:35 volumio ntpd[1291]: PROTO: 162.159.200.123 unlink local addr 192.168.2.112 -> Dec 21 00:08:35 volumio ntpd[1291]: PROTO: 31.28.161.68 unlink local addr 192.168.2.112 -> Dec 21 00:08:35 volumio ntpd[1291]: PROTO: 213.231.2.124 unlink local addr 192.168.2.112 -> Dec 21 00:08:35 volumio ntpd[1291]: PROTO: 46.173.175.211 unlink local addr 192.168.2.112 -> Dec 21 00:08:35 volumio ntpd[1291]: PROTO: 94.158.46.150 unlink local addr 192.168.2.112 -> Dec 21 00:08:35 volumio ntpd[1291]: PROTO: 91.236.251.234 unlink local addr 192.168.2.112 -> Dec 21 00:08:35 volumio ntpd[1291]: PROTO: 79.142.192.130 unlink local addr 192.168.2.112 -> Dec 21 00:08:36 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 21 00:08:36 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 21 00:08:36 volumio sudo[9867]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:36 volumio volumio[5997]: info: Discovery: Started advertising with name: Volumio Dec 21 00:08:36 volumio sudo[9867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:36 volumio sudo[9867]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:36 volumio sudo[9872]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 21 00:08:36 volumio sudo[9872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:36 volumio sudo[9872]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:36 volumio volumio[5997]: info: Volumio Network Manager: Network status updated: 2 Dec 21 00:08:37 volumio volumio[5997]: info: Initializing connection to go-librespot Websocket Dec 21 00:08:37 volumio volumio[5997]: info: Connection to go-librespot Websocket established Dec 21 00:08:37 volumio go-librespot[9860]: time="2025-12-21T00:08:37+02:00" level=debug msg="new websocket client" Dec 21 00:08:40 volumio volumio[5997]: info: Getting Spotify volume Dec 21 00:08:40 volumio volumio[5997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 21 00:08:40 volumio volumio[5997]: info: CoreCommandRouter::volumioGetState Dec 21 00:08:40 volumio volumio[5997]: info: CorePlayQueue::getTrack 0 Dec 21 00:08:41 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 21 00:08:41 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 21 00:08:41 volumio volumio[5997]: info: Refreshing Cached IP Addresses Dec 21 00:08:41 volumio sudo[9897]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 21 00:08:41 volumio sudo[9897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:41 volumio sudo[9899]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 00:08:41 volumio sudo[9899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:41 volumio sudo[9899]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:41 volumio sudo[9901]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 00:08:41 volumio sudo[9901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 00:08:41 volumio sudo[9897]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:41 volumio sudo[9901]: pam_unix(sudo:session): session closed for user root Dec 21 00:08:41 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Dec 21 00:08:41 volumio volumio[5997]: Upnp client error: Error: read ECONNRESET Dec 21 00:08:41 volumio systemd[1]: upmpdcli.service: Consumed 3.958s CPU time. Dec 21 00:08:44 volumio wpa_supplicant[9643]: wlan0: Authentication with 50:ff:20:34:87:81 timed out. Dec 21 00:08:44 volumio wpa_supplicant[9643]: wlan0: CTRL-EVENT-DISCONNECTED bssid=50:ff:20:34:87:81 reason=3 locally_generated=1 Dec 21 00:08:44 volumio wpa_supplicant[9643]: BSSID 50:ff:20:34:87:81 ignore list count incremented to 2, ignoring for 10 seconds Dec 21 00:08:44 volumio wpa_supplicant[9643]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Dec 21 00:08:44 volumio kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Dec 21 00:08:44 volumio wpa_supplicant[9643]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Dec 21 00:08:44 volumio wpa_supplicant[9643]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=UA Dec 21 00:08:48 volumio wpa_supplicant[9643]: wlan0: Trying to associate with 50:ff:20:34:87:83 (SSID='Keenetic-9973' freq=5180 MHz) Dec 21 00:08:48 volumio wpa_supplicant[9643]: FT: Invalid key management type (2) Dec 21 00:08:48 volumio wpa_supplicant[9643]: wlan0: Associated with 50:ff:20:34:87:83 Dec 21 00:08:48 volumio wpa_supplicant[9643]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 21 00:08:48 volumio wpa_supplicant[9643]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=UA Dec 21 00:08:48 volumio kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Dec 21 00:08:48 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 00:08:48 volumio volumio[5997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 21 00:08:48 volumio wpa_supplicant[9643]: wlan0: WPA: Key negotiation completed with 50:ff:20:34:87:83 [PTK=CCMP GTK=CCMP] Dec 21 00:08:48 volumio wpa_supplicant[9643]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:ff:20:34:87:83 completed [id=0 id_str=] Dec 21 00:08:48 volumio dhcpcd[1150]: wlan0: carrier acquired Dec 21 00:08:48 volumio volumio[5997]: info: CoreCommandRouter::volumioGetState Dec 21 00:08:48 volumio volumio[5997]: info: CorePlayQueue::getTrack 0 Dec 21 00:08:48 volumio dhcpcd[1150]: wlan0: IAID 67:45:83:37 Dec 21 00:08:49 volumio dhcpcd[1150]: wlan0: soliciting an IPv6 router Dec 21 00:08:49 volumio ntpd[1291]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 21 00:08:49 volumio ntpd[1291]: DNS: Pool taking: 193.106.144.7 Dec 21 00:08:49 volumio ntpd[1291]: DNS: Pool taking: 91.236.251.13 Dec 21 00:08:49 volumio ntpd[1291]: DNS: Pool taking: 31.28.161.71 Dec 21 00:08:49 volumio ntpd[1291]: DNS: Pool taking: 188.191.239.24 Dec 21 00:08:49 volumio ntpd[1291]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 21 00:08:49 volumio dhcpcd[1150]: wlan0: rebinding lease of 192.168.2.112 Dec 21 00:08:49 volumio dhcpcd[1150]: wlan0: leased 192.168.2.112 for 25200 seconds Dec 21 00:08:49 volumio dhcpcd[1150]: wlan0: changing route to 192.168.2.0/24 Dec 21 00:08:49 volumio dhcpcd[1150]: wlan0: changing default route via 192.168.2.1 Dec 21 00:08:49 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 21 00:08:49 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 21 00:08:49 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 21 00:08:49 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 21 00:08:49 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 21 00:08:49 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 21 00:08:49 volumio welcome[9941]: Resolved ip:[1] 192.168.2.112 Dec 21 00:08:49 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 21 00:08:49 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 21 00:08:49 volumio go-librespot[9860]: time="2025-12-21T00:08:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 21 00:08:49 volumio go-librespot[9860]: time="2025-12-21T00:08:49+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 21 00:08:49 volumio go-librespot[9860]: time="2025-12-21T00:08:49+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 21 00:08:49 volumio go-librespot[9860]: time="2025-12-21T00:08:49+02:00" level=info msg="zeroconf server listening on port 46549" Dec 21 00:08:49 volumio volumio[5997]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 21 00:08:49 volumio volumio[5997]: info: MyVolumio login type: Token Dec 21 00:08:49 volumio ntpd[1291]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 21 00:08:49 volumio go-librespot[9860]: time="2025-12-21T00:08:49+02:00" level=debug msg="obtained new client token: AAAuv1fzTOofQG14006+x2mO6ajQXtyYsxVNZk+ET7mpedHbSdHRlSfwmBjLCRzyX9TGLBzYQODp545v5yXkwCIvAOqfKLpNk57QaaD4fZ1x3sBwfuBl4gC1ROZ0hhD9fVUKZjaHrGYkGS0Y4EbDqnW+Y8xl9ynZhULRE3QMOYGN16qvD9DVQFtO9qczgnVoBjoDZa+kDzEF7VVaIIamw//FAUgyO7EAkY+vvBsyckmrMfXWrIcXyKeGDgej" Dec 21 00:08:49 volumio ntpd[1291]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 21 00:08:49 volumio ntpd[1291]: DNS: Pool taking: 193.84.22.254 Dec 21 00:08:49 volumio ntpd[1291]: DNS: Pool taking: 62.149.2.7 Dec 21 00:08:49 volumio ntpd[1291]: DNS: Pool taking: 91.231.182.17 Dec 21 00:08:49 volumio ntpd[1291]: DNS: Pool taking: 130.255.135.221 Dec 21 00:08:49 volumio ntpd[1291]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 21 00:08:49 volumio go-librespot[9860]: time="2025-12-21T00:08:49+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 00:08:50 volumio go-librespot[9860]: time="2025-12-21T00:08:50+02:00" level=debug msg="completed keyexchange" Dec 21 00:08:50 volumio go-librespot[9860]: time="2025-12-21T00:08:50+02:00" level=debug msg="completed challenge" Dec 21 00:08:50 volumio go-librespot[9860]: time="2025-12-21T00:08:50+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 00:08:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 00:08:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 00:08:50 volumio volumio[5997]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 00:08:50 volumio volumio[5997]: Error: socket hang up Dec 21 00:08:50 volumio volumio[5997]: at connResetException (node:internal/errors:720:14) Dec 21 00:08:50 volumio volumio[5997]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 21 00:08:50 volumio volumio[5997]: at Socket.emit (node:events:526:35) Dec 21 00:08:50 volumio volumio[5997]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 21 00:08:50 volumio volumio[5997]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 21 00:08:50 volumio volumio[5997]: code: 'ECONNRESET', Dec 21 00:08:50 volumio volumio[5997]: response: undefined Dec 21 00:08:50 volumio volumio[5997]: } Dec 21 00:08:50 volumio volumio[5997]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 00:08:50 volumio sudo[9963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-21 00:07' Dec 21 00:08:50 volumio sudo[9963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"