Dec 05 21:56:02 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 240. Dec 05 21:56:02 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:02 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:02 volumio-4 go-librespot[4566]: go-librespot daemon starting... Dec 05 21:56:02 volumio-4 go-librespot[4567]: time="2025-12-05T21:56:02+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:02 volumio-4 go-librespot[4567]: time="2025-12-05T21:56:02+01:00" level=debug msg="app state loaded" Dec 05 21:56:02 volumio-4 go-librespot[4567]: time="2025-12-05T21:56:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:02 volumio-4 go-librespot[4567]: time="2025-12-05T21:56:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:02 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:02 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:02 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:02 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:05 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 241. Dec 05 21:56:05 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:05 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:05 volumio-4 go-librespot[4590]: go-librespot daemon starting... Dec 05 21:56:05 volumio-4 go-librespot[4591]: time="2025-12-05T21:56:05+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:05 volumio-4 go-librespot[4591]: time="2025-12-05T21:56:05+01:00" level=debug msg="app state loaded" Dec 05 21:56:05 volumio-4 go-librespot[4591]: time="2025-12-05T21:56:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:05 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:05 volumio-4 go-librespot[4591]: time="2025-12-05T21:56:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:05 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:05 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:05 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:08 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:08 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:08 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 242. Dec 05 21:56:08 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:08 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:08 volumio-4 go-librespot[4597]: go-librespot daemon starting... Dec 05 21:56:08 volumio-4 go-librespot[4598]: time="2025-12-05T21:56:08+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:08 volumio-4 go-librespot[4598]: time="2025-12-05T21:56:08+01:00" level=debug msg="app state loaded" Dec 05 21:56:08 volumio-4 go-librespot[4598]: time="2025-12-05T21:56:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:08 volumio-4 go-librespot[4598]: time="2025-12-05T21:56:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:08 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:08 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:11 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:11 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:11 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 243. Dec 05 21:56:11 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:11 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:11 volumio-4 go-librespot[4605]: go-librespot daemon starting... Dec 05 21:56:11 volumio-4 go-librespot[4606]: time="2025-12-05T21:56:11+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:11 volumio-4 go-librespot[4606]: time="2025-12-05T21:56:11+01:00" level=debug msg="app state loaded" Dec 05 21:56:11 volumio-4 go-librespot[4606]: time="2025-12-05T21:56:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:11 volumio-4 go-librespot[4606]: time="2025-12-05T21:56:11+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:11 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:11 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:12 volumio-4 volumio[1180]: verbose: New Socket.io Connection to 192.168.1.239:3000 from 192.168.1.92 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 05 21:56:12 volumio-4 volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:56:12 volumio-4 volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:56:12 volumio-4 volumio[1180]: info: Discovery: Getting this device information Dec 05 21:56:12 volumio-4 volumio[1180]: info: CoreCommandRouter::volumioGetState Dec 05 21:56:12 volumio-4 volumio[1180]: info: CorePlayQueue::getTrack 2 Dec 05 21:56:12 volumio-4 volumio[1180]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:56:14 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:14 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:15 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 244. Dec 05 21:56:15 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:15 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:15 volumio-4 go-librespot[4626]: go-librespot daemon starting... Dec 05 21:56:15 volumio-4 go-librespot[4627]: time="2025-12-05T21:56:15+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:15 volumio-4 go-librespot[4627]: time="2025-12-05T21:56:15+01:00" level=debug msg="app state loaded" Dec 05 21:56:15 volumio-4 go-librespot[4627]: time="2025-12-05T21:56:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:15 volumio-4 go-librespot[4627]: time="2025-12-05T21:56:15+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:15 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:15 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:17 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:17 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:18 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 245. Dec 05 21:56:18 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:18 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:18 volumio-4 go-librespot[4634]: go-librespot daemon starting... Dec 05 21:56:18 volumio-4 go-librespot[4635]: time="2025-12-05T21:56:18+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:18 volumio-4 go-librespot[4635]: time="2025-12-05T21:56:18+01:00" level=debug msg="app state loaded" Dec 05 21:56:18 volumio-4 go-librespot[4635]: time="2025-12-05T21:56:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:18 volumio-4 go-librespot[4635]: time="2025-12-05T21:56:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:18 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:18 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:20 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:20 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:21 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 246. Dec 05 21:56:21 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:21 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:21 volumio-4 go-librespot[4641]: go-librespot daemon starting... Dec 05 21:56:21 volumio-4 go-librespot[4642]: time="2025-12-05T21:56:21+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:21 volumio-4 go-librespot[4642]: time="2025-12-05T21:56:21+01:00" level=debug msg="app state loaded" Dec 05 21:56:21 volumio-4 go-librespot[4642]: time="2025-12-05T21:56:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:21 volumio-4 go-librespot[4642]: time="2025-12-05T21:56:21+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:21 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:21 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:23 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:23 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:24 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 247. Dec 05 21:56:24 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:24 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:24 volumio-4 go-librespot[4662]: go-librespot daemon starting... Dec 05 21:56:24 volumio-4 go-librespot[4663]: time="2025-12-05T21:56:24+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:24 volumio-4 go-librespot[4663]: time="2025-12-05T21:56:24+01:00" level=debug msg="app state loaded" Dec 05 21:56:24 volumio-4 go-librespot[4663]: time="2025-12-05T21:56:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:24 volumio-4 go-librespot[4663]: time="2025-12-05T21:56:24+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:24 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:24 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:26 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:26 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:28 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 248. Dec 05 21:56:28 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:28 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:28 volumio-4 go-librespot[4672]: go-librespot daemon starting... Dec 05 21:56:28 volumio-4 go-librespot[4673]: time="2025-12-05T21:56:28+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:28 volumio-4 go-librespot[4673]: time="2025-12-05T21:56:28+01:00" level=debug msg="app state loaded" Dec 05 21:56:28 volumio-4 go-librespot[4673]: time="2025-12-05T21:56:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:28 volumio-4 go-librespot[4673]: time="2025-12-05T21:56:28+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:28 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:28 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:29 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:29 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:31 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 249. Dec 05 21:56:31 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:31 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:31 volumio-4 go-librespot[4680]: go-librespot daemon starting... Dec 05 21:56:31 volumio-4 go-librespot[4681]: time="2025-12-05T21:56:31+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:31 volumio-4 go-librespot[4681]: time="2025-12-05T21:56:31+01:00" level=debug msg="app state loaded" Dec 05 21:56:31 volumio-4 go-librespot[4681]: time="2025-12-05T21:56:31+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:31 volumio-4 go-librespot[4681]: time="2025-12-05T21:56:31+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:31 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:31 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:32 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:32 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:33 volumio-4 volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 21:56:33 volumio-4 volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 21:56:33 volumio-4 volumio[1180]: info: Discovery: Getting this device information Dec 05 21:56:33 volumio-4 volumio[1180]: info: CoreCommandRouter::volumioGetState Dec 05 21:56:33 volumio-4 volumio[1180]: info: CorePlayQueue::getTrack 2 Dec 05 21:56:33 volumio-4 volumio[1180]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 21:56:33 volumio-4 volumio[1180]: verbose: New Socket.io Connection to 192.168.1.239:3000 from 192.168.1.92 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Dec 05 21:56:33 volumio-4 volumio[1180]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 05 21:56:33 volumio-4 volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 05 21:56:34 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 250. Dec 05 21:56:34 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:34 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:34 volumio-4 go-librespot[4701]: go-librespot daemon starting... Dec 05 21:56:34 volumio-4 go-librespot[4702]: time="2025-12-05T21:56:34+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:34 volumio-4 go-librespot[4702]: time="2025-12-05T21:56:34+01:00" level=debug msg="app state loaded" Dec 05 21:56:34 volumio-4 go-librespot[4702]: time="2025-12-05T21:56:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:34 volumio-4 go-librespot[4702]: time="2025-12-05T21:56:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:34 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:34 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:35 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:35 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:37 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 251. Dec 05 21:56:37 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:37 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:37 volumio-4 go-librespot[4709]: go-librespot daemon starting... Dec 05 21:56:37 volumio-4 go-librespot[4710]: time="2025-12-05T21:56:37+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:37 volumio-4 go-librespot[4710]: time="2025-12-05T21:56:37+01:00" level=debug msg="app state loaded" Dec 05 21:56:37 volumio-4 go-librespot[4710]: time="2025-12-05T21:56:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:37 volumio-4 go-librespot[4710]: time="2025-12-05T21:56:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:37 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:37 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:38 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:38 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:41 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 252. Dec 05 21:56:41 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:41 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:41 volumio-4 go-librespot[4716]: go-librespot daemon starting... Dec 05 21:56:41 volumio-4 go-librespot[4717]: time="2025-12-05T21:56:41+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:41 volumio-4 go-librespot[4717]: time="2025-12-05T21:56:41+01:00" level=debug msg="app state loaded" Dec 05 21:56:41 volumio-4 go-librespot[4717]: time="2025-12-05T21:56:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:41 volumio-4 go-librespot[4717]: time="2025-12-05T21:56:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:41 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:41 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:41 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:41 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:44 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 253. Dec 05 21:56:44 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:44 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:44 volumio-4 go-librespot[4737]: go-librespot daemon starting... Dec 05 21:56:44 volumio-4 go-librespot[4738]: time="2025-12-05T21:56:44+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:44 volumio-4 go-librespot[4738]: time="2025-12-05T21:56:44+01:00" level=debug msg="app state loaded" Dec 05 21:56:44 volumio-4 go-librespot[4738]: time="2025-12-05T21:56:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:44 volumio-4 go-librespot[4738]: time="2025-12-05T21:56:44+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:44 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:44 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:44 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:44 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:47 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:47 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:47 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 254. Dec 05 21:56:47 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:47 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:47 volumio-4 go-librespot[4748]: go-librespot daemon starting... Dec 05 21:56:47 volumio-4 go-librespot[4749]: time="2025-12-05T21:56:47+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:47 volumio-4 go-librespot[4749]: time="2025-12-05T21:56:47+01:00" level=debug msg="app state loaded" Dec 05 21:56:47 volumio-4 go-librespot[4749]: time="2025-12-05T21:56:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:47 volumio-4 go-librespot[4749]: time="2025-12-05T21:56:47+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:47 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:47 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:50 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:50 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:50 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 255. Dec 05 21:56:50 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:50 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:50 volumio-4 go-librespot[4756]: go-librespot daemon starting... Dec 05 21:56:50 volumio-4 go-librespot[4757]: time="2025-12-05T21:56:50+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:50 volumio-4 go-librespot[4757]: time="2025-12-05T21:56:50+01:00" level=debug msg="app state loaded" Dec 05 21:56:50 volumio-4 go-librespot[4757]: time="2025-12-05T21:56:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:50 volumio-4 go-librespot[4757]: time="2025-12-05T21:56:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:50 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:50 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:51 volumio-4 volumio[1180]: info: CorePlayQueue::getTrack 2 Dec 05 21:56:51 volumio-4 volumio[1180]: info: CorePlayQueue::getTrack 3 Dec 05 21:56:51 volumio-4 volumio[1180]: info: Prefetching next song Dec 05 21:56:51 volumio-4 volumio[1180]: info: [1764968211320] ControllerSpotify::prefetch Dec 05 21:56:51 volumio-4 volumio[1180]: info: Sending Spotify command with payload to local API: /player/add_to_queue Dec 05 21:56:51 volumio-4 volumio[1180]: error: Failed to send command to Spotify local API: /player/add_to_queue: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:53 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:53 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:54 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 256. Dec 05 21:56:54 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:54 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:54 volumio-4 go-librespot[4777]: go-librespot daemon starting... Dec 05 21:56:54 volumio-4 go-librespot[4778]: time="2025-12-05T21:56:54+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:54 volumio-4 go-librespot[4778]: time="2025-12-05T21:56:54+01:00" level=debug msg="app state loaded" Dec 05 21:56:54 volumio-4 go-librespot[4778]: time="2025-12-05T21:56:54+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:54 volumio-4 go-librespot[4778]: time="2025-12-05T21:56:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:54 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:54 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:55 volumio-4 volumio[1180]: info: CoreStateMachine::startPlaybackTimer Dec 05 21:56:55 volumio-4 volumio[1180]: info: CorePlayQueue::getTrack 3 Dec 05 21:56:56 volumio-4 volumio[1180]: info: CoreStateMachine::pushState Dec 05 21:56:56 volumio-4 volumio[1180]: info: CorePlayQueue::getTrack 3 Dec 05 21:56:56 volumio-4 volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 05 21:56:56 volumio-4 volumio[1180]: info: CoreCommandRouter::volumioPushState Dec 05 21:56:56 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:56 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:56:57 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 257. Dec 05 21:56:57 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:57 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:56:57 volumio-4 go-librespot[4784]: go-librespot daemon starting... Dec 05 21:56:57 volumio-4 systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Dec 05 21:56:57 volumio-4 go-librespot[4785]: time="2025-12-05T21:56:57+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:56:57 volumio-4 go-librespot[4785]: time="2025-12-05T21:56:57+01:00" level=debug msg="app state loaded" Dec 05 21:56:57 volumio-4 go-librespot[4785]: time="2025-12-05T21:56:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:56:57 volumio-4 go-librespot[4785]: time="2025-12-05T21:56:57+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:56:57 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:56:57 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:56:57 volumio-4 systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Dec 05 21:56:57 volumio-4 systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Dec 05 21:56:57 volumio-4 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Dec 05 21:56:59 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:56:59 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:57:00 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 258. Dec 05 21:57:00 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:00 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:00 volumio-4 go-librespot[4793]: go-librespot daemon starting... Dec 05 21:57:00 volumio-4 go-librespot[4794]: time="2025-12-05T21:57:00+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:57:00 volumio-4 go-librespot[4794]: time="2025-12-05T21:57:00+01:00" level=debug msg="app state loaded" Dec 05 21:57:00 volumio-4 go-librespot[4794]: time="2025-12-05T21:57:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:57:00 volumio-4 go-librespot[4794]: time="2025-12-05T21:57:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:57:00 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:57:00 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:57:02 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:57:02 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:57:03 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 259. Dec 05 21:57:03 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:03 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:03 volumio-4 go-librespot[4814]: go-librespot daemon starting... Dec 05 21:57:03 volumio-4 go-librespot[4815]: time="2025-12-05T21:57:03+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:57:03 volumio-4 go-librespot[4815]: time="2025-12-05T21:57:03+01:00" level=debug msg="app state loaded" Dec 05 21:57:03 volumio-4 go-librespot[4815]: time="2025-12-05T21:57:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:57:03 volumio-4 go-librespot[4815]: time="2025-12-05T21:57:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:57:03 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:57:03 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:57:05 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:57:05 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:57:07 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 260. Dec 05 21:57:07 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:07 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:07 volumio-4 go-librespot[4824]: go-librespot daemon starting... Dec 05 21:57:07 volumio-4 go-librespot[4825]: time="2025-12-05T21:57:07+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:57:07 volumio-4 go-librespot[4825]: time="2025-12-05T21:57:07+01:00" level=debug msg="app state loaded" Dec 05 21:57:07 volumio-4 go-librespot[4825]: time="2025-12-05T21:57:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:57:07 volumio-4 go-librespot[4825]: time="2025-12-05T21:57:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:57:07 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:57:07 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:57:08 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:57:08 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:57:10 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 261. Dec 05 21:57:10 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:10 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:10 volumio-4 go-librespot[4833]: go-librespot daemon starting... Dec 05 21:57:10 volumio-4 go-librespot[4834]: time="2025-12-05T21:57:10+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:57:10 volumio-4 go-librespot[4834]: time="2025-12-05T21:57:10+01:00" level=debug msg="app state loaded" Dec 05 21:57:10 volumio-4 go-librespot[4834]: time="2025-12-05T21:57:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:57:10 volumio-4 go-librespot[4834]: time="2025-12-05T21:57:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:57:10 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:57:10 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:57:11 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:57:11 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:57:13 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 262. Dec 05 21:57:13 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:13 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:13 volumio-4 go-librespot[4840]: go-librespot daemon starting... Dec 05 21:57:13 volumio-4 go-librespot[4841]: time="2025-12-05T21:57:13+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:57:13 volumio-4 go-librespot[4841]: time="2025-12-05T21:57:13+01:00" level=debug msg="app state loaded" Dec 05 21:57:13 volumio-4 go-librespot[4841]: time="2025-12-05T21:57:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:57:13 volumio-4 go-librespot[4841]: time="2025-12-05T21:57:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:57:13 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:57:13 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:57:14 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:57:14 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:57:16 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 263. Dec 05 21:57:16 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:16 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:16 volumio-4 go-librespot[4861]: go-librespot daemon starting... Dec 05 21:57:16 volumio-4 go-librespot[4862]: time="2025-12-05T21:57:16+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:57:16 volumio-4 go-librespot[4862]: time="2025-12-05T21:57:16+01:00" level=debug msg="app state loaded" Dec 05 21:57:16 volumio-4 go-librespot[4862]: time="2025-12-05T21:57:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:57:16 volumio-4 go-librespot[4862]: time="2025-12-05T21:57:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:57:16 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:57:16 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:57:17 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:57:17 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:57:20 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 264. Dec 05 21:57:20 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:20 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:20 volumio-4 go-librespot[4869]: go-librespot daemon starting... Dec 05 21:57:20 volumio-4 go-librespot[4870]: time="2025-12-05T21:57:20+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:57:20 volumio-4 go-librespot[4870]: time="2025-12-05T21:57:20+01:00" level=debug msg="app state loaded" Dec 05 21:57:20 volumio-4 go-librespot[4870]: time="2025-12-05T21:57:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:57:20 volumio-4 go-librespot[4870]: time="2025-12-05T21:57:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 05 21:57:20 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:57:20 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:57:20 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:57:20 volumio-4 volumio[1180]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:57:23 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 265. Dec 05 21:57:23 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:23 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:23 volumio-4 go-librespot[4877]: go-librespot daemon starting... Dec 05 21:57:23 volumio-4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 05 21:57:23 volumio-4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 05 21:57:23 volumio-4 systemd[1]: welcome.service: Deactivated successfully. Dec 05 21:57:23 volumio-4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 05 21:57:23 volumio-4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 05 21:57:23 volumio-4 go-librespot[4894]: time="2025-12-05T21:57:23+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:57:23 volumio-4 go-librespot[4894]: time="2025-12-05T21:57:23+01:00" level=debug msg="app state loaded" Dec 05 21:57:23 volumio-4 go-librespot[4894]: time="2025-12-05T21:57:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:57:23 volumio-4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 05 21:57:23 volumio-4 welcome[4906]: Resolved ip:[1] 192.168.1.239 Dec 05 21:57:23 volumio-4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 05 21:57:23 volumio-4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 05 21:57:23 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:57:23 volumio-4 go-librespot[4894]: time="2025-12-05T21:57:23+01:00" level=debug msg="new websocket client" Dec 05 21:57:23 volumio-4 volumio[1180]: info: Connection to go-librespot Websocket established Dec 05 21:57:23 volumio-4 go-librespot[4894]: time="2025-12-05T21:57:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-05T21:57:23+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:57:23 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:57:23 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:57:23 volumio-4 volumio[1180]: info: Connection to go-librespot Websocket closed Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 05 21:57:25 volumio-4 volumio[1180]: info: Adding plugin bluetooth to MyMusic Plugins Dec 05 21:57:25 volumio-4 volumio[1180]: info: Adding plugin multiroom to MyMusic Plugins Dec 05 21:57:25 volumio-4 volumio[1180]: info: Adding plugin metavolumio to MyMusic Plugins Dec 05 21:57:25 volumio-4 volumio[1180]: info: Adding plugin cd_controller to MyMusic Plugins Dec 05 21:57:25 volumio-4 volumio[1180]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 05 21:57:25 volumio-4 volumio[1180]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 05 21:57:25 volumio-4 volumio[1180]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 05 21:57:25 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 05 21:57:26 volumio-4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 266. Dec 05 21:57:26 volumio-4 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:26 volumio-4 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 21:57:26 volumio-4 go-librespot[4933]: go-librespot daemon starting... Dec 05 21:57:26 volumio-4 go-librespot[4934]: time="2025-12-05T21:57:26+01:00" level=info msg="running go-librespot 0.4.0" Dec 05 21:57:26 volumio-4 go-librespot[4934]: time="2025-12-05T21:57:26+01:00" level=debug msg="app state loaded" Dec 05 21:57:26 volumio-4 go-librespot[4934]: time="2025-12-05T21:57:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 21:57:27 volumio-4 go-librespot[4934]: time="2025-12-05T21:57:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-12-05T21:57:26+01:00 is before 2025-12-08T00:00:00Z" Dec 05 21:57:27 volumio-4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 21:57:27 volumio-4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 21:57:27 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 05 21:57:27 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 05 21:57:27 volumio-4 volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:57:27 volumio-4 volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 05 21:57:27 volumio-4 volumio[1180]: info: Starting MyVolumio Remote Streaming Endpoints Dec 05 21:57:27 volumio-4 volumio[1180]: info: MyVolumio login type: Token Dec 05 21:57:27 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 05 21:57:27 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 05 21:57:28 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 05 21:57:28 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 05 21:57:28 volumio-4 volumio[1180]: info: Streaming services startup Dec 05 21:57:28 volumio-4 volumio[1180]: info: Starting Streaming Daemon Dec 05 21:57:28 volumio-4 volumio[1180]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 05 21:57:28 volumio-4 sudo[4942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 05 21:57:28 volumio-4 sudo[4942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 21:57:28 volumio-4 sudo[4942]: pam_unix(sudo:session): session closed for user root Dec 05 21:57:28 volumio-4 volumio[1180]: info: Getting Spotify volume Dec 05 21:57:28 volumio-4 volumio[1180]: info: Initializing connection to go-librespot Websocket Dec 05 21:57:28 volumio-4 volumio[1180]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 21:57:28 volumio-4 volumio[1180]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 21:57:28 volumio-4 volumio[1180]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 05 21:57:28 volumio-4 volumio[1180]: errno: -111, Dec 05 21:57:28 volumio-4 volumio[1180]: code: 'ECONNREFUSED', Dec 05 21:57:28 volumio-4 volumio[1180]: syscall: 'connect', Dec 05 21:57:28 volumio-4 volumio[1180]: address: '127.0.0.1', Dec 05 21:57:28 volumio-4 volumio[1180]: port: 9879, Dec 05 21:57:28 volumio-4 volumio[1180]: response: undefined Dec 05 21:57:28 volumio-4 volumio[1180]: } Dec 05 21:57:28 volumio-4 volumio[1180]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 21:57:29 volumio-4 sudo[4963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-05 21:56' Dec 05 21:57:29 volumio-4 sudo[4963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"