-- Logs begin at Wed 2025-07-02 12:26:37 UTC, end at Wed 2025-07-02 13:30:07 UTC. -- Jul 02 13:27:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1244. Jul 02 13:27:01 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:01 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:01 volumio go-librespot[14918]: Librespot-go daemon starting... Jul 02 13:27:01 volumio go-librespot[14918]: time="2025-07-02T13:27:01Z" level=info msg="generated new device id: 64f87a805db919c5feea697c8bc5d49a2c50cf03" Jul 02 13:27:01 volumio go-librespot[14918]: time="2025-07-02T13:27:01Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:02 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:02 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1245. Jul 02 13:27:04 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:04 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:04 volumio go-librespot[14925]: Librespot-go daemon starting... Jul 02 13:27:04 volumio go-librespot[14925]: time="2025-07-02T13:27:04Z" level=info msg="generated new device id: ca47c958546db94ae4b39a0d80d3fd9e132c23c1" Jul 02 13:27:04 volumio go-librespot[14925]: time="2025-07-02T13:27:04Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:05 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:05 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1246. Jul 02 13:27:07 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:07 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:07 volumio go-librespot[14932]: Librespot-go daemon starting... Jul 02 13:27:07 volumio go-librespot[14932]: time="2025-07-02T13:27:07Z" level=info msg="generated new device id: d2f9ddff7c4e3a298f7670f1343ebc0d6aa3a1e5" Jul 02 13:27:07 volumio go-librespot[14932]: time="2025-07-02T13:27:07Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:08 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:08 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1247. Jul 02 13:27:10 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:10 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:10 volumio go-librespot[14939]: Librespot-go daemon starting... Jul 02 13:27:10 volumio go-librespot[14939]: time="2025-07-02T13:27:10Z" level=info msg="generated new device id: f96892f04f18fa51f06b32ee556f04ee8084e08c" Jul 02 13:27:10 volumio go-librespot[14939]: time="2025-07-02T13:27:10Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:11 volumio ntpd[990]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 02 13:27:11 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:11 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1248. Jul 02 13:27:14 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:14 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:14 volumio go-librespot[14946]: Librespot-go daemon starting... Jul 02 13:27:14 volumio go-librespot[14946]: time="2025-07-02T13:27:14Z" level=info msg="generated new device id: 0691366806f4cf95b40a2c80992bc6b4783eaba2" Jul 02 13:27:14 volumio go-librespot[14946]: time="2025-07-02T13:27:14Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:14 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:14 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:16 volumio ntpd[990]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 02 13:27:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1249. Jul 02 13:27:17 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:17 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:17 volumio go-librespot[14953]: Librespot-go daemon starting... Jul 02 13:27:17 volumio go-librespot[14953]: time="2025-07-02T13:27:17Z" level=info msg="generated new device id: 1d4aa67c7c5cb15fd337a816a5cb6616c3ea7ee7" Jul 02 13:27:17 volumio go-librespot[14953]: time="2025-07-02T13:27:17Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:17 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:17 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:19 volumio ntpd[990]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 02 13:27:20 volumio ntpd[990]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 02 13:27:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1250. Jul 02 13:27:20 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:20 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:20 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:20 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:20 volumio go-librespot[14960]: Librespot-go daemon starting... Jul 02 13:27:20 volumio go-librespot[14960]: time="2025-07-02T13:27:20Z" level=info msg="generated new device id: 5db291b0746f780fc484552209ccd2315346ed96" Jul 02 13:27:20 volumio go-librespot[14960]: time="2025-07-02T13:27:20Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:23 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:23 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1251. Jul 02 13:27:23 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:23 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:23 volumio go-librespot[14967]: Librespot-go daemon starting... Jul 02 13:27:23 volumio go-librespot[14967]: time="2025-07-02T13:27:23Z" level=info msg="generated new device id: 3fbeedd194d7c3e43fa354641a927aed5887374d" Jul 02 13:27:23 volumio go-librespot[14967]: time="2025-07-02T13:27:23Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:26 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:26 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1252. Jul 02 13:27:27 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:27 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:27 volumio go-librespot[14974]: Librespot-go daemon starting... Jul 02 13:27:27 volumio go-librespot[14974]: time="2025-07-02T13:27:27Z" level=info msg="generated new device id: e2958f740ca0af4276c6ac4ec2dd13a95ef345b0" Jul 02 13:27:27 volumio go-librespot[14974]: time="2025-07-02T13:27:27Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:29 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:29 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1253. Jul 02 13:27:30 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:30 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:30 volumio go-librespot[14981]: Librespot-go daemon starting... Jul 02 13:27:30 volumio go-librespot[14981]: time="2025-07-02T13:27:30Z" level=info msg="generated new device id: ef608e60d4ed95256debed743e42daa95c3917c9" Jul 02 13:27:30 volumio go-librespot[14981]: time="2025-07-02T13:27:30Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:32 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:32 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1254. Jul 02 13:27:33 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:33 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:33 volumio go-librespot[14988]: Librespot-go daemon starting... Jul 02 13:27:33 volumio go-librespot[14988]: time="2025-07-02T13:27:33Z" level=info msg="generated new device id: 62a5c175662c93b491ac4984123a4df3be2f8e66" Jul 02 13:27:33 volumio go-librespot[14988]: time="2025-07-02T13:27:33Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:35 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:35 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1255. Jul 02 13:27:36 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:36 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:36 volumio go-librespot[15040]: Librespot-go daemon starting... Jul 02 13:27:36 volumio go-librespot[15040]: time="2025-07-02T13:27:36Z" level=info msg="generated new device id: 7a26f524aedeb79d5232e3f28178440904889b45" Jul 02 13:27:36 volumio go-librespot[15040]: time="2025-07-02T13:27:36Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:38 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:38 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1256. Jul 02 13:27:40 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:40 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:40 volumio go-librespot[15047]: Librespot-go daemon starting... Jul 02 13:27:40 volumio go-librespot[15047]: time="2025-07-02T13:27:40Z" level=info msg="generated new device id: af22e70a30e368a7d63fc43c836a53cf703e43c4" Jul 02 13:27:40 volumio go-librespot[15047]: time="2025-07-02T13:27:40Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:41 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:41 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1257. Jul 02 13:27:43 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:43 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:43 volumio go-librespot[15054]: Librespot-go daemon starting... Jul 02 13:27:43 volumio go-librespot[15054]: time="2025-07-02T13:27:43Z" level=info msg="generated new device id: 18018b42fdc5d5bce3fbc00a013aee80b6df3ffb" Jul 02 13:27:43 volumio go-librespot[15054]: time="2025-07-02T13:27:43Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:44 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:44 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1258. Jul 02 13:27:46 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:46 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:46 volumio go-librespot[15061]: Librespot-go daemon starting... Jul 02 13:27:46 volumio go-librespot[15061]: time="2025-07-02T13:27:46Z" level=info msg="generated new device id: df224778a0d81e26864d441b006b2fcb849a4d87" Jul 02 13:27:46 volumio go-librespot[15061]: time="2025-07-02T13:27:46Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:47 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:47 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1259. Jul 02 13:27:49 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:49 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:49 volumio go-librespot[15068]: Librespot-go daemon starting... Jul 02 13:27:49 volumio go-librespot[15068]: time="2025-07-02T13:27:49Z" level=info msg="generated new device id: 703de6f94ef2e75179ff6a604a1f06091a0030f7" Jul 02 13:27:49 volumio go-librespot[15068]: time="2025-07-02T13:27:49Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:50 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:50 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1260. Jul 02 13:27:53 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:53 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:53 volumio go-librespot[15075]: Librespot-go daemon starting... Jul 02 13:27:53 volumio go-librespot[15075]: time="2025-07-02T13:27:53Z" level=info msg="generated new device id: 84507935b4220f680e3b0acdafa6db250bd47a21" Jul 02 13:27:53 volumio go-librespot[15075]: time="2025-07-02T13:27:53Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:53 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:53 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1261. Jul 02 13:27:56 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:56 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:56 volumio go-librespot[15082]: Librespot-go daemon starting... Jul 02 13:27:56 volumio go-librespot[15082]: time="2025-07-02T13:27:56Z" level=info msg="generated new device id: 47bd269d26e319fb75ecb05af87f8bda39d87ffb" Jul 02 13:27:56 volumio go-librespot[15082]: time="2025-07-02T13:27:56Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:56 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:56 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:27:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:27:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1262. Jul 02 13:27:59 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:27:59 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:27:59 volumio go-librespot[15089]: Librespot-go daemon starting... Jul 02 13:27:59 volumio go-librespot[15089]: time="2025-07-02T13:27:59Z" level=info msg="generated new device id: 5546e5ce069ced25d9d8554f21c39acf2cf2affa" Jul 02 13:27:59 volumio go-librespot[15089]: time="2025-07-02T13:27:59Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:27:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:27:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:27:59 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:27:59 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:02 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:02 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1263. Jul 02 13:28:02 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:02 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:02 volumio go-librespot[15096]: Librespot-go daemon starting... Jul 02 13:28:02 volumio go-librespot[15096]: time="2025-07-02T13:28:02Z" level=info msg="generated new device id: 493d0d0c55ba892fe2e555188225256ffecc481c" Jul 02 13:28:02 volumio go-librespot[15096]: time="2025-07-02T13:28:02Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:05 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:05 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1264. Jul 02 13:28:06 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:06 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:06 volumio go-librespot[15103]: Librespot-go daemon starting... Jul 02 13:28:06 volumio go-librespot[15103]: time="2025-07-02T13:28:06Z" level=info msg="generated new device id: fec5c30e96eaceabb23374337c999cc01919ca94" Jul 02 13:28:06 volumio go-librespot[15103]: time="2025-07-02T13:28:06Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:08 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:08 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1265. Jul 02 13:28:09 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:09 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:09 volumio go-librespot[15110]: Librespot-go daemon starting... Jul 02 13:28:09 volumio go-librespot[15110]: time="2025-07-02T13:28:09Z" level=info msg="generated new device id: bc3a3ed9876f25902649b0e115058e70d5167def" Jul 02 13:28:09 volumio go-librespot[15110]: time="2025-07-02T13:28:09Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:11 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:11 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1266. Jul 02 13:28:12 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:12 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:12 volumio go-librespot[15117]: Librespot-go daemon starting... Jul 02 13:28:12 volumio go-librespot[15117]: time="2025-07-02T13:28:12Z" level=info msg="generated new device id: b23fdd9bb5cc8b17cf0d84ab55cfe69b4a08e14a" Jul 02 13:28:12 volumio go-librespot[15117]: time="2025-07-02T13:28:12Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:14 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:14 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1267. Jul 02 13:28:15 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:15 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:15 volumio go-librespot[15124]: Librespot-go daemon starting... Jul 02 13:28:15 volumio go-librespot[15124]: time="2025-07-02T13:28:15Z" level=info msg="generated new device id: a1e6660b7a822b0b3f320d4f7c014ffed7b4804f" Jul 02 13:28:15 volumio go-librespot[15124]: time="2025-07-02T13:28:15Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:17 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:17 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:18 volumio ntpd[990]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 02 13:28:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1268. Jul 02 13:28:19 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:19 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:19 volumio go-librespot[15131]: Librespot-go daemon starting... Jul 02 13:28:19 volumio go-librespot[15131]: time="2025-07-02T13:28:19Z" level=info msg="generated new device id: a5cba308eec4520340fcdb77a0f3aca24290e229" Jul 02 13:28:19 volumio go-librespot[15131]: time="2025-07-02T13:28:19Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:20 volumio ntpd[990]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 02 13:28:20 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:20 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1269. Jul 02 13:28:22 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:22 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:22 volumio go-librespot[15138]: Librespot-go daemon starting... Jul 02 13:28:22 volumio go-librespot[15138]: time="2025-07-02T13:28:22Z" level=info msg="generated new device id: 7f152bdf774ff93e33fa1d32244492fd1148b41f" Jul 02 13:28:22 volumio go-librespot[15138]: time="2025-07-02T13:28:22Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:23 volumio ntpd[990]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 02 13:28:23 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:23 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:25 volumio ntpd[990]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 02 13:28:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1270. Jul 02 13:28:25 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:25 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:25 volumio go-librespot[15145]: Librespot-go daemon starting... Jul 02 13:28:25 volumio go-librespot[15145]: time="2025-07-02T13:28:25Z" level=info msg="generated new device id: 718bc555933349d62518f5c734d8e3e7677fb98b" Jul 02 13:28:25 volumio go-librespot[15145]: time="2025-07-02T13:28:25Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:26 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:26 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1271. Jul 02 13:28:28 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:28 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:28 volumio go-librespot[15152]: Librespot-go daemon starting... Jul 02 13:28:28 volumio go-librespot[15152]: time="2025-07-02T13:28:28Z" level=info msg="generated new device id: a9081f4376fb624bb4ca21de2c26b812aa1bab09" Jul 02 13:28:28 volumio go-librespot[15152]: time="2025-07-02T13:28:28Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:29 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:29 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1272. Jul 02 13:28:32 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:32 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:32 volumio go-librespot[15159]: Librespot-go daemon starting... Jul 02 13:28:32 volumio go-librespot[15159]: time="2025-07-02T13:28:32Z" level=info msg="generated new device id: 9c0b746d1a02bedbd007a48f03a573cadb8c4fdc" Jul 02 13:28:32 volumio go-librespot[15159]: time="2025-07-02T13:28:32Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:32 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:32 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1273. Jul 02 13:28:35 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:35 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:35 volumio go-librespot[15210]: Librespot-go daemon starting... Jul 02 13:28:35 volumio go-librespot[15210]: time="2025-07-02T13:28:35Z" level=info msg="generated new device id: d5efa719aed5c10c9897e9bdf236f8b0e276af7f" Jul 02 13:28:35 volumio go-librespot[15210]: time="2025-07-02T13:28:35Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:35 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:35 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1274. Jul 02 13:28:38 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:38 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:38 volumio go-librespot[15218]: Librespot-go daemon starting... Jul 02 13:28:38 volumio go-librespot[15218]: time="2025-07-02T13:28:38Z" level=info msg="generated new device id: 6d5766a9e8077bf83866a468adf30f1d98eb6055" Jul 02 13:28:38 volumio go-librespot[15218]: time="2025-07-02T13:28:38Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:38 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:38 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:41 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:41 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1275. Jul 02 13:28:41 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:41 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:41 volumio go-librespot[15225]: Librespot-go daemon starting... Jul 02 13:28:41 volumio go-librespot[15225]: time="2025-07-02T13:28:41Z" level=info msg="generated new device id: a7a89f2e51ef54513e00b1ba7672f060e0e936a3" Jul 02 13:28:41 volumio go-librespot[15225]: time="2025-07-02T13:28:41Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:44 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:44 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1276. Jul 02 13:28:45 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:45 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:45 volumio go-librespot[15232]: Librespot-go daemon starting... Jul 02 13:28:45 volumio go-librespot[15232]: time="2025-07-02T13:28:45Z" level=info msg="generated new device id: 4a6e13758adc6d61dc4083ed8cc2b5f5d4fbbff5" Jul 02 13:28:45 volumio go-librespot[15232]: time="2025-07-02T13:28:45Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:47 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:47 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1277. Jul 02 13:28:48 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:48 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:48 volumio go-librespot[15240]: Librespot-go daemon starting... Jul 02 13:28:48 volumio go-librespot[15240]: time="2025-07-02T13:28:48Z" level=info msg="generated new device id: 2d651093903fc290494a691971ba093661fd6b2f" Jul 02 13:28:48 volumio go-librespot[15240]: time="2025-07-02T13:28:48Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:50 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:50 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1278. Jul 02 13:28:51 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:51 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:51 volumio go-librespot[15247]: Librespot-go daemon starting... Jul 02 13:28:51 volumio go-librespot[15247]: time="2025-07-02T13:28:51Z" level=info msg="generated new device id: 79abb43e04a0f233f3d21f143be0f1b0dd2a1863" Jul 02 13:28:51 volumio go-librespot[15247]: time="2025-07-02T13:28:51Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:53 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:53 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1279. Jul 02 13:28:54 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:54 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:54 volumio go-librespot[15254]: Librespot-go daemon starting... Jul 02 13:28:54 volumio go-librespot[15254]: time="2025-07-02T13:28:54Z" level=info msg="generated new device id: c9ed327ac3ac8e26a9c3eb80b6e768d8cfde4718" Jul 02 13:28:54 volumio go-librespot[15254]: time="2025-07-02T13:28:54Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:56 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:56 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:28:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:28:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1280. Jul 02 13:28:58 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:28:58 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:28:58 volumio go-librespot[15261]: Librespot-go daemon starting... Jul 02 13:28:58 volumio go-librespot[15261]: time="2025-07-02T13:28:58Z" level=info msg="generated new device id: f42fa4f0097766e221d13a1aa9bff85808cad4f1" Jul 02 13:28:58 volumio go-librespot[15261]: time="2025-07-02T13:28:58Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:28:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:28:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:28:59 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:28:59 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1281. Jul 02 13:29:01 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:01 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:01 volumio go-librespot[15268]: Librespot-go daemon starting... Jul 02 13:29:01 volumio go-librespot[15268]: time="2025-07-02T13:29:01Z" level=info msg="generated new device id: e8c35cc32df959c2a495aaf34c48b55d6543eb9a" Jul 02 13:29:01 volumio go-librespot[15268]: time="2025-07-02T13:29:01Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:02 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:02 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1282. Jul 02 13:29:04 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:04 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:04 volumio go-librespot[15275]: Librespot-go daemon starting... Jul 02 13:29:04 volumio go-librespot[15275]: time="2025-07-02T13:29:04Z" level=info msg="generated new device id: e494be7e958c7b865660cbaf37b47e777ed7f8a0" Jul 02 13:29:04 volumio go-librespot[15275]: time="2025-07-02T13:29:04Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:05 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:05 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1283. Jul 02 13:29:07 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:07 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:07 volumio go-librespot[15282]: Librespot-go daemon starting... Jul 02 13:29:07 volumio go-librespot[15282]: time="2025-07-02T13:29:07Z" level=info msg="generated new device id: dc9d5ca98dee32de0e3d655b5513c1f7b4ae54ac" Jul 02 13:29:07 volumio go-librespot[15282]: time="2025-07-02T13:29:07Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:08 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:08 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1284. Jul 02 13:29:11 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:11 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:11 volumio go-librespot[15289]: Librespot-go daemon starting... Jul 02 13:29:11 volumio go-librespot[15289]: time="2025-07-02T13:29:11Z" level=info msg="generated new device id: 19b680da3295eeb39d3011e5b2c53b1f5f5fc87e" Jul 02 13:29:11 volumio go-librespot[15289]: time="2025-07-02T13:29:11Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:11 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:11 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1285. Jul 02 13:29:14 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:14 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:14 volumio go-librespot[15296]: Librespot-go daemon starting... Jul 02 13:29:14 volumio go-librespot[15296]: time="2025-07-02T13:29:14Z" level=info msg="generated new device id: 9c9e852d2f5b991536ab04ad14b8ac50d86e8c46" Jul 02 13:29:14 volumio go-librespot[15296]: time="2025-07-02T13:29:14Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:14 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:14 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1286. Jul 02 13:29:17 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:17 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:17 volumio go-librespot[15303]: Librespot-go daemon starting... Jul 02 13:29:17 volumio go-librespot[15303]: time="2025-07-02T13:29:17Z" level=info msg="generated new device id: 913ec5e6b56f0b8b66b1e9bc30cc19979d16fc4b" Jul 02 13:29:17 volumio go-librespot[15303]: time="2025-07-02T13:29:17Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:17 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:17 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:20 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:20 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1287. Jul 02 13:29:20 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:20 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:20 volumio go-librespot[15310]: Librespot-go daemon starting... Jul 02 13:29:20 volumio go-librespot[15310]: time="2025-07-02T13:29:20Z" level=info msg="generated new device id: 444871ed71ac56c0862a74044ed0a810f52bfd0b" Jul 02 13:29:20 volumio go-librespot[15310]: time="2025-07-02T13:29:20Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:23 volumio ntpd[990]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 02 13:29:23 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:23 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:24 volumio ntpd[990]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 02 13:29:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1288. Jul 02 13:29:24 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:24 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:24 volumio go-librespot[15317]: Librespot-go daemon starting... Jul 02 13:29:24 volumio go-librespot[15317]: time="2025-07-02T13:29:24Z" level=info msg="generated new device id: fa00e0e17bf264fe769f276fa7ee0e3cf8a4831a" Jul 02 13:29:24 volumio go-librespot[15317]: time="2025-07-02T13:29:24Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:26 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:26 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1289. Jul 02 13:29:27 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:27 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:27 volumio go-librespot[15324]: Librespot-go daemon starting... Jul 02 13:29:27 volumio go-librespot[15324]: time="2025-07-02T13:29:27Z" level=info msg="generated new device id: 8d72e0e9b93d1eaa6b86c11ad1eca6d2c1134e36" Jul 02 13:29:27 volumio go-librespot[15324]: time="2025-07-02T13:29:27Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:29 volumio ntpd[990]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 02 13:29:29 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:29 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:30 volumio ntpd[990]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 02 13:29:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1290. Jul 02 13:29:30 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:30 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:30 volumio go-librespot[15331]: Librespot-go daemon starting... Jul 02 13:29:30 volumio go-librespot[15331]: time="2025-07-02T13:29:30Z" level=info msg="generated new device id: 1df629de85c69c09e06c96003dcf92c16040967f" Jul 02 13:29:30 volumio go-librespot[15331]: time="2025-07-02T13:29:30Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:32 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:32 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1291. Jul 02 13:29:33 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:33 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:33 volumio go-librespot[15338]: Librespot-go daemon starting... Jul 02 13:29:33 volumio go-librespot[15338]: time="2025-07-02T13:29:33Z" level=info msg="generated new device id: 4d3ea6dfb185c8c376c74dfc4503465439ad81db" Jul 02 13:29:33 volumio go-librespot[15338]: time="2025-07-02T13:29:33Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:35 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:35 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1292. Jul 02 13:29:37 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:37 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:37 volumio go-librespot[15390]: Librespot-go daemon starting... Jul 02 13:29:37 volumio go-librespot[15390]: time="2025-07-02T13:29:37Z" level=info msg="generated new device id: a82445e7ceed19d6e06fd20da83603d19c91ea03" Jul 02 13:29:37 volumio go-librespot[15390]: time="2025-07-02T13:29:37Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:38 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:38 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1293. Jul 02 13:29:40 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:40 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:40 volumio go-librespot[15397]: Librespot-go daemon starting... Jul 02 13:29:40 volumio go-librespot[15397]: time="2025-07-02T13:29:40Z" level=info msg="generated new device id: 6747f93c99fa1199293e9d9874d0b7bd6446bb6e" Jul 02 13:29:40 volumio go-librespot[15397]: time="2025-07-02T13:29:40Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:41 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:41 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1294. Jul 02 13:29:43 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:43 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:43 volumio go-librespot[15404]: Librespot-go daemon starting... Jul 02 13:29:43 volumio go-librespot[15404]: time="2025-07-02T13:29:43Z" level=info msg="generated new device id: 5ddd69087adf9f4d8bfee8f820b795293cb1475a" Jul 02 13:29:43 volumio go-librespot[15404]: time="2025-07-02T13:29:43Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:44 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:44 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1295. Jul 02 13:29:46 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:46 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:46 volumio go-librespot[15411]: Librespot-go daemon starting... Jul 02 13:29:46 volumio go-librespot[15411]: time="2025-07-02T13:29:46Z" level=info msg="generated new device id: 74a6cf43d46e2a13d8705736a592c6a798f7ec61" Jul 02 13:29:46 volumio go-librespot[15411]: time="2025-07-02T13:29:46Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:47 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:47 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1296. Jul 02 13:29:50 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:50 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:50 volumio go-librespot[15418]: Librespot-go daemon starting... Jul 02 13:29:50 volumio go-librespot[15418]: time="2025-07-02T13:29:50Z" level=info msg="generated new device id: cb4a8bbb3d9e3a4ca8b4fb956d8037b1d067ce57" Jul 02 13:29:50 volumio go-librespot[15418]: time="2025-07-02T13:29:50Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:50 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:50 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:52 volumio kernel: tg3 0000:03:00.0 eth0: Link is up at 1000 Mbps, full duplex Jul 02 13:29:52 volumio kernel: tg3 0000:03:00.0 eth0: Flow control is on for TX and on for RX Jul 02 13:29:52 volumio kernel: tg3 0000:03:00.0 eth0: EEE is disabled Jul 02 13:29:52 volumio dhcpcd[833]: eth0: carrier acquired Jul 02 13:29:52 volumio dhcpcd[833]: DUID 00:04:3d:44:a9:3d:99:ce:17:5a:84:65:97:59:ac:9f:95:f8 Jul 02 13:29:52 volumio dhcpcd[833]: eth0: IAID b6:1a:6a:99 Jul 02 13:29:52 volumio dhcpcd[833]: eth0: adding address fe80::aa60:b6ff:fe1a:6a99 Jul 02 13:29:52 volumio dhcpcd[833]: ipv6_addaddr1: Permission denied Jul 02 13:29:52 volumio dhcpcd[833]: eth0: soliciting an IPv6 router Jul 02 13:29:52 volumio dhcpcd[833]: eth0: soliciting a DHCP lease Jul 02 13:29:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1297. Jul 02 13:29:53 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:53 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:53 volumio go-librespot[15427]: Librespot-go daemon starting... Jul 02 13:29:53 volumio go-librespot[15427]: time="2025-07-02T13:29:53Z" level=info msg="generated new device id: ace67997d47858c26be164a9c8e87d75596993f2" Jul 02 13:29:53 volumio go-librespot[15427]: time="2025-07-02T13:29:53Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:53 volumio ifplugd(eth0)[983]: Link beat detected. Jul 02 13:29:53 volumio ifplugd(eth0)[983]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 02 13:29:53 volumio ifplugd(eth0)[983]: client: command failed: No such device (-19) Jul 02 13:29:53 volumio dhcpcd[15455]: sending commands to master dhcpcd process Jul 02 13:29:53 volumio dhcpcd[833]: control command: /sbin/dhcpcd eth0 Jul 02 13:29:53 volumio ifplugd(eth0)[983]: client: sending commands to master dhcpcd process Jul 02 13:29:53 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:53 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:53 volumio ifplugd(eth0)[983]: Program executed successfully. Jul 02 13:29:54 volumio dhcpcd[833]: eth0: offered 192.168.1.12 from 192.168.1.1 Jul 02 13:29:54 volumio dhcpcd[833]: eth0: probing address 192.168.1.12/24 Jul 02 13:29:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1298. Jul 02 13:29:56 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:56 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:56 volumio go-librespot[15481]: Librespot-go daemon starting... Jul 02 13:29:56 volumio go-librespot[15481]: time="2025-07-02T13:29:56Z" level=info msg="generated new device id: 8b598614a567acdb7be9095ee5ccf9711abc982e" Jul 02 13:29:56 volumio go-librespot[15481]: time="2025-07-02T13:29:56Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 02 13:29:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 02 13:29:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 13:29:56 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:56 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:59 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:29:59 volumio volumio[1024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 13:29:59 volumio dhcpcd[833]: eth0: leased 192.168.1.12 for 86400 seconds Jul 02 13:29:59 volumio dhcpcd[833]: eth0: adding route to 192.168.1.0/24 Jul 02 13:29:59 volumio avahi-daemon[767]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.12. Jul 02 13:29:59 volumio dhcpcd[833]: eth0: adding default route via 192.168.1.1 Jul 02 13:29:59 volumio avahi-daemon[767]: New relevant interface eth0.IPv4 for mDNS. Jul 02 13:29:59 volumio avahi-daemon[767]: Registering new address record for 192.168.1.12 on eth0.IPv4. Jul 02 13:29:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 13:29:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1299. Jul 02 13:29:59 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 13:29:59 volumio systemd[1]: Started go-librespot Daemon. Jul 02 13:29:59 volumio go-librespot[15511]: Librespot-go daemon starting... Jul 02 13:29:59 volumio go-librespot[15511]: time="2025-07-02T13:29:59Z" level=info msg="generated new device id: 99a2ebb06cfe8274b527647561a0e29c09dcea6a" Jul 02 13:29:59 volumio ntpd[990]: ntpd exiting on signal 15 (Terminated) Jul 02 13:29:59 volumio systemd[1]: Stopping Network Time Service... Jul 02 13:29:59 volumio systemd[1]: ntp.service: Succeeded. Jul 02 13:29:59 volumio systemd[1]: Stopped Network Time Service. Jul 02 13:29:59 volumio systemd[1]: Starting Network Time Service... Jul 02 13:29:59 volumio ntpd[15531]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 02 13:29:59 volumio ntpd[15531]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 02 13:29:59 volumio systemd[1]: Started Network Time Service. Jul 02 13:29:59 volumio ntpd[15537]: proto: precision = 0.046 usec (-24) Jul 02 13:29:59 volumio ntpd[15537]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 02 13:29:59 volumio ntpd[15537]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 02 13:29:59 volumio ntpd[15537]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 918 days ago Jul 02 13:29:59 volumio ntpd[15537]: Listen and drop on 0 v6wildcard [::]:123 Jul 02 13:29:59 volumio ntpd[15537]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 02 13:29:59 volumio ntpd[15537]: Listen normally on 2 lo 127.0.0.1:123 Jul 02 13:29:59 volumio ntpd[15537]: Listen normally on 3 eth0 192.168.1.12:123 Jul 02 13:29:59 volumio ntpd[15537]: Listening on routing socket on fd #20 for interface updates Jul 02 13:29:59 volumio ntpd[15537]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jul 02 13:29:59 volumio ntpd[15537]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Jul 02 13:30:00 volumio go-librespot[15511]: time="2025-07-02T13:30:00Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 02 13:30:00 volumio go-librespot[15511]: time="2025-07-02T13:30:00Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 02 13:30:00 volumio go-librespot[15511]: time="2025-07-02T13:30:00Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 02 13:30:00 volumio go-librespot[15511]: time="2025-07-02T13:30:00Z" level=debug msg="zeroconf server listening on port 45615" Jul 02 13:30:00 volumio volumio[1024]: info: Discovery: adding 27e9dbf3-d919-415c-8ddc-4652ff97b17a Jul 02 13:30:00 volumio volumio[1024]: info: Discovery: Found device Volumio Jul 02 13:30:00 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Jul 02 13:30:00 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 13:30:00 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 13:30:00 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 13:30:00 volumio volumio[1024]: info: Discovery: Getting this device information Jul 02 13:30:00 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Jul 02 13:30:00 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 13:30:00 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 13:30:00 volumio volumio[1024]: verbose: New Socket.io Connection to 192.168.1.12:3000 from 192.168.1.6 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jul 02 13:30:00 volumio ntpd[15537]: Soliciting pool server 27.71.27.209 Jul 02 13:30:01 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Jul 02 13:30:01 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 13:30:01 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 02 13:30:01 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 02 13:30:01 volumio ntpd[15537]: Soliciting pool server 103.199.19.135 Jul 02 13:30:01 volumio ntpd[15537]: Soliciting pool server 103.184.124.254 Jul 02 13:30:02 volumio volumio[1024]: info: Initializing connection to go-librespot Websocket Jul 02 13:30:02 volumio go-librespot[15511]: time="2025-07-02T13:30:02Z" level=debug msg="new websocket client" Jul 02 13:30:02 volumio volumio[1024]: info: Connection to go-librespot Websocket established Jul 02 13:30:02 volumio ntpd[15537]: Soliciting pool server 103.184.124.254 Jul 02 13:30:02 volumio ntpd[15537]: Soliciting pool server 115.165.161.155 Jul 02 13:30:03 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Jul 02 13:30:03 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 13:30:03 volumio ntpd[15537]: Soliciting pool server 103.188.82.179 Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 02 13:30:04 volumio volumio[1024]: info: Adding plugin bluetooth to MyMusic Plugins Jul 02 13:30:04 volumio volumio[1024]: info: Adding plugin multiroom to MyMusic Plugins Jul 02 13:30:04 volumio volumio[1024]: info: Adding plugin metavolumio to MyMusic Plugins Jul 02 13:30:04 volumio volumio[1024]: info: Adding plugin cd_controller to MyMusic Plugins Jul 02 13:30:04 volumio volumio[1024]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 02 13:30:04 volumio volumio[1024]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 02 13:30:04 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 13:30:04 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 13:30:04 volumio volumio[1024]: info: Starting MyVolumio Remote Streaming Endpoints Jul 02 13:30:04 volumio volumio[1024]: info: MyVolumio login type: Token Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 02 13:30:04 volumio volumio[1024]: info: Starting Streaming Service Transparent Proxy Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 02 13:30:04 volumio volumio[1024]: info: Streaming services startup Jul 02 13:30:04 volumio volumio[1024]: info: Starting Streaming Daemon Jul 02 13:30:04 volumio sudo[15549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 02 13:30:04 volumio sudo[15549]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 13:30:04 volumio sudo[15549]: pam_unix(sudo:session): session closed for user root Jul 02 13:30:04 volumio volumio[1024]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 02 13:30:04 volumio volumio[1024]: error: Cannot start Volumio Streaming Daemon Jul 02 13:30:04 volumio volumio[1024]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 02 13:30:04 volumio volumio[1024]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 02 13:30:04 volumio volumio[1024]: verbose: New Socket.io Connection to 192.168.1.12:3000 from 192.168.1.6 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jul 02 13:30:04 volumio volumio[1024]: STREAMING PROXY: Starting server on port 3245 Jul 02 13:30:04 volumio volumio[1024]: Node JS runtime: 14 Jul 02 13:30:04 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Jul 02 13:30:04 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 13:30:04 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 02 13:30:04 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 02 13:30:04 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Jul 02 13:30:04 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 13:30:04 volumio ntpd[15537]: Soliciting pool server 103.184.124.254 Jul 02 13:30:05 volumio volumio[1024]: error: MyVolumio Custom Token format not valid, refreshing it Jul 02 13:30:05 volumio volumio[1024]: info: Getting Spotify volume Jul 02 13:30:05 volumio volumio[1024]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 02 13:30:05 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Jul 02 13:30:05 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 13:30:05 volumio volumio[1024]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Jul 02 13:30:05 volumio volumio[1024]: SPOTIFY: SPOTIFY VOLUME undefined Jul 02 13:30:05 volumio volumio[1024]: SPOTIFY: VOLUMIO VOLUME 30 Jul 02 13:30:05 volumio volumio[1024]: info: Aligning Spotify Volume to Volumio Volume Jul 02 13:30:05 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Jul 02 13:30:05 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 02 13:30:05 volumio volumio[1024]: info: Setting Spotify Volume from Volumio: 30 Jul 02 13:30:06 volumio volumio[1024]: info: MyVolumio login type: Token Jul 02 13:30:06 volumio volumio[1024]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 02 13:30:07 volumio volumio[1024]: SPOTIFY: SETTING SPOTIFY VOLUME 30 Jul 02 13:30:07 volumio volumio[1024]: info: Sending Spotify command with payload to local API: /player/volume Jul 02 13:30:07 volumio volumio[1024]: info: MyVolumio token set successfully Jul 02 13:30:07 volumio volumio[1024]: info: MYVOLUMIO: Adding device Jul 02 13:30:07 volumio volumio[1024]: info: MYVOLUMIO: Evaluating Server Jul 02 13:30:07 volumio volumio[1024]: info: MyVolumio status changed Jul 02 13:30:07 volumio volumio[1024]: info: Streaming services startup Jul 02 13:30:07 volumio volumio[1024]: info: Starting Streaming Daemon Jul 02 13:30:07 volumio volumio[1024]: info: Removing browser output: myVolumio user plan is not superstar Jul 02 13:30:07 volumio volumio[1024]: info: Removing audio output: Jul 02 13:30:07 volumio volumio[1024]: info: Stoppping Tunnel 1 Jul 02 13:30:07 volumio sudo[15573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 02 13:30:07 volumio sudo[15573]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 13:30:07 volumio sudo[15573]: pam_unix(sudo:session): session closed for user root Jul 02 13:30:07 volumio volumio[1024]: error: Cannot start Volumio Streaming Daemon Jul 02 13:30:07 volumio volumio[1024]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 02 13:30:07 volumio volumio[1024]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 02 13:30:07 volumio sudo[15576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 02 13:30:07 volumio sudo[15576]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 13:30:07 volumio sudo[15576]: pam_unix(sudo:session): session closed for user root Jul 02 13:30:07 volumio volumio[1024]: info: Remote SSH Stopped Jul 02 13:28:58 volumio volumio[1024]: error: Failed to ping endpoint eu5.myvolumio.org : unknown error Jul 02 13:28:58 volumio volumio[1024]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 13:28:58 volumio volumio[1024]: Error: Unable to resolve or reject the same promise twice Jul 02 13:28:58 volumio volumio[1024]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 02 13:28:58 volumio volumio[1024]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Jul 02 13:28:58 volumio volumio[1024]: at Socket.emit (events.js:412:35) Jul 02 13:28:58 volumio volumio[1024]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 02 13:28:58 volumio volumio[1024]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 02 13:28:58 volumio volumio[1024]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 13:28:58 volumio sudo[15588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 13:27 Jul 02 13:28:58 volumio sudo[15588]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"