Dec 05 12:46:00 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:00 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Dec 05 12:46:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:01 volumio go-librespot[2159]: go-librespot daemon starting... Dec 05 12:46:01 volumio go-librespot[2160]: time="2025-12-05T12:46:01-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:01 volumio go-librespot[2160]: time="2025-12-05T12:46:01-08:00" level=debug msg="app state loaded" Dec 05 12:46:01 volumio go-librespot[2160]: time="2025-12-05T12:46:01-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:01 volumio go-librespot[2160]: time="2025-12-05T12:46:01-08: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 12:46:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:03 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:03 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Dec 05 12:46:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:04 volumio go-librespot[2166]: go-librespot daemon starting... Dec 05 12:46:04 volumio go-librespot[2167]: time="2025-12-05T12:46:04-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:04 volumio go-librespot[2167]: time="2025-12-05T12:46:04-08:00" level=debug msg="app state loaded" Dec 05 12:46:04 volumio go-librespot[2167]: time="2025-12-05T12:46:04-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:04 volumio go-librespot[2167]: time="2025-12-05T12:46:04-08: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 12:46:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:06 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:06 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Dec 05 12:46:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:08 volumio go-librespot[2173]: go-librespot daemon starting... Dec 05 12:46:08 volumio go-librespot[2174]: time="2025-12-05T12:46:08-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:08 volumio go-librespot[2174]: time="2025-12-05T12:46:08-08:00" level=debug msg="app state loaded" Dec 05 12:46:08 volumio go-librespot[2174]: time="2025-12-05T12:46:08-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:08 volumio go-librespot[2174]: time="2025-12-05T12:46:08-08: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 12:46:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:09 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:09 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Dec 05 12:46:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:11 volumio go-librespot[2195]: go-librespot daemon starting... Dec 05 12:46:11 volumio go-librespot[2196]: time="2025-12-05T12:46:11-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:11 volumio go-librespot[2196]: time="2025-12-05T12:46:11-08:00" level=debug msg="app state loaded" Dec 05 12:46:11 volumio go-librespot[2196]: time="2025-12-05T12:46:11-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:11 volumio go-librespot[2196]: time="2025-12-05T12:46:11-08: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 12:46:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:12 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:12 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Dec 05 12:46:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:14 volumio go-librespot[2205]: go-librespot daemon starting... Dec 05 12:46:14 volumio go-librespot[2206]: time="2025-12-05T12:46:14-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:14 volumio go-librespot[2206]: time="2025-12-05T12:46:14-08:00" level=debug msg="app state loaded" Dec 05 12:46:14 volumio go-librespot[2206]: time="2025-12-05T12:46:14-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:14 volumio go-librespot[2206]: time="2025-12-05T12:46:14-08: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 12:46:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:15 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:15 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Dec 05 12:46:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:17 volumio go-librespot[2213]: go-librespot daemon starting... Dec 05 12:46:17 volumio go-librespot[2214]: time="2025-12-05T12:46:17-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:17 volumio go-librespot[2214]: time="2025-12-05T12:46:17-08:00" level=debug msg="app state loaded" Dec 05 12:46:17 volumio go-librespot[2214]: time="2025-12-05T12:46:17-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:17 volumio go-librespot[2214]: time="2025-12-05T12:46:17-08: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 12:46:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:18 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:18 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Dec 05 12:46:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:21 volumio go-librespot[2221]: go-librespot daemon starting... Dec 05 12:46:21 volumio go-librespot[2225]: time="2025-12-05T12:46:21-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:21 volumio go-librespot[2225]: time="2025-12-05T12:46:21-08:00" level=debug msg="app state loaded" Dec 05 12:46:21 volumio go-librespot[2225]: time="2025-12-05T12:46:21-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:21 volumio go-librespot[2225]: time="2025-12-05T12:46:21-08: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 12:46:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:21 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:21 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Dec 05 12:46:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:24 volumio go-librespot[2241]: go-librespot daemon starting... Dec 05 12:46:24 volumio go-librespot[2242]: time="2025-12-05T12:46:24-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:24 volumio go-librespot[2242]: time="2025-12-05T12:46:24-08:00" level=debug msg="app state loaded" Dec 05 12:46:24 volumio go-librespot[2242]: time="2025-12-05T12:46:24-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:24 volumio go-librespot[2242]: time="2025-12-05T12:46:24-08: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 12:46:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:24 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:24 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:26 volumio volumio[1184]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.121 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 05 12:46:26 volumio volumio[1184]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.121 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 05 12:46:26 volumio volumio[1184]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.121 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 05 12:46:26 volumio volumio[1184]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.121 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 05 12:46:26 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 05 12:46:26 volumio volumio[1184]: info: CoreCommandRouter::volumioGetVisibleSources Dec 05 12:46:26 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 05 12:46:26 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Dec 05 12:46:26 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 05 12:46:26 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 05 12:46:26 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 05 12:46:26 volumio volumio[1184]: info: Received Get System Info Dec 05 12:46:26 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 05 12:46:26 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 05 12:46:26 volumio volumio[1184]: info: Discovery: Getting this device information Dec 05 12:46:26 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Dec 05 12:46:26 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 05 12:46:26 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 05 12:46:26 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Dec 05 12:46:26 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 05 12:46:26 volumio volumio[1184]: info: Listing playlists Dec 05 12:46:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Dec 05 12:46:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:27 volumio go-librespot[2248]: go-librespot daemon starting... Dec 05 12:46:27 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:27 volumio go-librespot[2249]: time="2025-12-05T12:46:27-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:27 volumio go-librespot[2249]: time="2025-12-05T12:46:27-08:00" level=debug msg="app state loaded" Dec 05 12:46:27 volumio go-librespot[2249]: time="2025-12-05T12:46:27-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:27 volumio go-librespot[2249]: time="2025-12-05T12:46:27-08: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 12:46:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:27 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:30 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:30 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Dec 05 12:46:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:30 volumio go-librespot[2255]: go-librespot daemon starting... Dec 05 12:46:30 volumio go-librespot[2256]: time="2025-12-05T12:46:30-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:30 volumio go-librespot[2256]: time="2025-12-05T12:46:30-08:00" level=debug msg="app state loaded" Dec 05 12:46:30 volumio go-librespot[2256]: time="2025-12-05T12:46:30-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:30 volumio go-librespot[2256]: time="2025-12-05T12:46:30-08: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 12:46:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:33 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:33 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Dec 05 12:46:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:34 volumio go-librespot[2279]: go-librespot daemon starting... Dec 05 12:46:34 volumio go-librespot[2280]: time="2025-12-05T12:46:34-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:34 volumio go-librespot[2280]: time="2025-12-05T12:46:34-08:00" level=debug msg="app state loaded" Dec 05 12:46:34 volumio go-librespot[2280]: time="2025-12-05T12:46:34-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:34 volumio go-librespot[2280]: time="2025-12-05T12:46:34-08: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 12:46:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:36 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:36 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Dec 05 12:46:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:37 volumio go-librespot[2286]: go-librespot daemon starting... Dec 05 12:46:37 volumio go-librespot[2287]: time="2025-12-05T12:46:37-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:37 volumio go-librespot[2287]: time="2025-12-05T12:46:37-08:00" level=debug msg="app state loaded" Dec 05 12:46:37 volumio go-librespot[2287]: time="2025-12-05T12:46:37-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:37 volumio go-librespot[2287]: time="2025-12-05T12:46:37-08: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 12:46:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:39 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:39 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Dec 05 12:46:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:40 volumio go-librespot[2294]: go-librespot daemon starting... Dec 05 12:46:40 volumio go-librespot[2295]: time="2025-12-05T12:46:40-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:40 volumio go-librespot[2295]: time="2025-12-05T12:46:40-08:00" level=debug msg="app state loaded" Dec 05 12:46:40 volumio go-librespot[2295]: time="2025-12-05T12:46:40-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:40 volumio go-librespot[2295]: time="2025-12-05T12:46:40-08: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 12:46:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:42 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:42 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Dec 05 12:46:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:43 volumio go-librespot[2315]: go-librespot daemon starting... Dec 05 12:46:43 volumio go-librespot[2316]: time="2025-12-05T12:46:43-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:43 volumio go-librespot[2316]: time="2025-12-05T12:46:43-08:00" level=debug msg="app state loaded" Dec 05 12:46:43 volumio go-librespot[2316]: time="2025-12-05T12:46:43-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:43 volumio go-librespot[2316]: time="2025-12-05T12:46:43-08: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 12:46:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:45 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Dec 05 12:46:45 volumio volumio[1184]: info: Saving new wireless network Dec 05 12:46:45 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:45 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:45 volumio sudo[2323]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Dec 05 12:46:45 volumio sudo[2323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:45 volumio sudo[2323]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:46 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 05 12:46:46 volumio volumio[1184]: info: Discovery: Restarting Advertising due to device name change Dec 05 12:46:46 volumio sudo[2326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 05 12:46:46 volumio sudo[2326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:46 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 05 12:46:46 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 05 12:46:46 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 05 12:46:46 volumio systemd[1]: wireless.service: Consumed 8min 47.092s CPU time. Dec 05 12:46:46 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 05 12:46:47 volumio volumio[1184]: info: Discovery: A device disappeared from network Dec 05 12:46:47 volumio volumio[1184]: info: Discovery: Device volumio disappeared from network Dec 05 12:46:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Dec 05 12:46:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:47 volumio volumio[1184]: info: Discovery: A device disappeared from network Dec 05 12:46:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:47 volumio go-librespot[2332]: go-librespot daemon starting... Dec 05 12:46:47 volumio go-librespot[2333]: time="2025-12-05T12:46:47-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:47 volumio go-librespot[2333]: time="2025-12-05T12:46:47-08:00" level=debug msg="app state loaded" Dec 05 12:46:47 volumio go-librespot[2333]: time="2025-12-05T12:46:47-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:47 volumio go-librespot[2333]: time="2025-12-05T12:46:47-08: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 12:46:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:47 volumio sudo[2348]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:47 volumio sudo[2348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:47 volumio sudo[2348]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:48 volumio sudo[2352]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:48 volumio sudo[2352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:48 volumio sudo[2352]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:48 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:48 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:46:49 volumio wireless.js[2331]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 05 12:46:49 volumio wireless.js[2331]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 05 12:46:49 volumio wireless.js[2331]: WIRELESS.JS: Cleaning previous... Dec 05 12:46:49 volumio sudo[2360]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:49 volumio sudo[2360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:49 volumio sudo[2360]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:49 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Dec 05 12:46:49 volumio dnsmasq[1213]: exiting on receipt of SIGTERM Dec 05 12:46:49 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Dec 05 12:46:49 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Dec 05 12:46:49 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Dec 05 12:46:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Dec 05 12:46:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:50 volumio go-librespot[2365]: go-librespot daemon starting... Dec 05 12:46:50 volumio go-librespot[2366]: time="2025-12-05T12:46:50-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:50 volumio go-librespot[2366]: time="2025-12-05T12:46:50-08:00" level=debug msg="app state loaded" Dec 05 12:46:50 volumio go-librespot[2366]: time="2025-12-05T12:46:50-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:50 volumio go-librespot[2366]: time="2025-12-05T12:46:50-08: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 12:46:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:50 volumio dhcpcd[653]: wlan0: carrier lost Dec 05 12:46:50 volumio sudo[2374]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:50 volumio systemd[1]: hostapd.service: Deactivated successfully. Dec 05 12:46:50 volumio sudo[2374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:50 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Dec 05 12:46:50 volumio sudo[2374]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:50 volumio sudo[2392]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 05 12:46:50 volumio sudo[2392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 12:46:50 volumio avahi-daemon[571]: Withdrawing address record for 192.168.211.1 on wlan0. Dec 05 12:46:50 volumio avahi-daemon[571]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 05 12:46:50 volumio sudo[2392]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:50 volumio avahi-daemon[571]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 05 12:46:50 volumio sudo[2394]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 05 12:46:50 volumio sudo[2394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 12:46:51 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 05 12:46:51 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 05 12:46:51 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 05 12:46:51 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 05 12:46:51 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 05 12:46:51 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 05 12:46:51 volumio welcome[2397]: Resolved ip:[0] Dec 05 12:46:51 volumio sudo[2394]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:51 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 05 12:46:51 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 05 12:46:51 volumio volumio[1184]: info: Discovery: Started advertising with name: Volumio Dec 05 12:46:51 volumio volumio[1184]: compat.c: read() failed: Resource temporarily unavailable Dec 05 12:46:51 volumio volumio[1184]: info: Discovery: mDNS temporarily unavailable during network transition Dec 05 12:46:51 volumio wireless.js[2331]: WIRELESS.JS: Stopped aP Dec 05 12:46:51 volumio sudo[2411]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 05 12:46:51 volumio sudo[2411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:51 volumio sudo[2411]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:51 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 05 12:46:51 volumio sudo[2408]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:51 volumio sudo[2408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:51 volumio sudo[2408]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:52 volumio sudo[2417]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 05 12:46:52 volumio sudo[2417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:52 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:52 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: AggregateError Dec 05 12:46:52 volumio sudo[2417]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:52 volumio sudo[2436]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 05 12:46:52 volumio sudo[2436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:52 volumio sudo[2436]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:52 volumio sudo[2438]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 05 12:46:52 volumio sudo[2438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:52 volumio sudo[2443]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:52 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:52 volumio sudo[2438]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:52 volumio wireless.js[2331]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: US Dec 05 12:46:52 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:53 volumio sudo[2447]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 05 12:46:53 volumio sudo[2447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:53 volumio sudo[2447]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:53 volumio sudo[2449]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Dec 05 12:46:53 volumio sudo[2449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:53 volumio sudo[2449]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:53 volumio wireless.js[2331]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: US Dec 05 12:46:53 volumio wireless.js[2331]: WIRELESS.JS: Start wireless flow Dec 05 12:46:53 volumio wireless.js[2331]: WIRELESS.JS: Stopped hotspot (if there).. Dec 05 12:46:53 volumio sudo[2460]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 05 12:46:53 volumio sudo[2460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 12:46:53 volumio sudo[2460]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:53 volumio sudo[2462]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 05 12:46:53 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 12:46:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Dec 05 12:46:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:53 volumio go-librespot[2464]: go-librespot daemon starting... Dec 05 12:46:53 volumio go-librespot[2465]: time="2025-12-05T12:46:53-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:53 volumio go-librespot[2465]: time="2025-12-05T12:46:53-08:00" level=debug msg="app state loaded" Dec 05 12:46:53 volumio go-librespot[2465]: time="2025-12-05T12:46:53-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:53 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:53 volumio go-librespot[2465]: time="2025-12-05T12:46:53-08: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 12:46:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:54 volumio sudo[2472]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:54 volumio sudo[2472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:54 volumio wireless.js[2331]: WIRELESS.JS: DHCP IP fallback Dec 05 12:46:54 volumio sudo[2472]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:54 volumio wireless.js[2331]: WIRELESS.JS: Start ap Dec 05 12:46:54 volumio sudo[2478]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 05 12:46:54 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 12:46:54 volumio dhcpcd[653]: ps_ctl_dispatch: cannot handle another client Dec 05 12:46:54 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:54 volumio dhcpcd[653]: control_free: No such file or directory Dec 05 12:46:54 volumio wpa_supplicant[2476]: Successfully initialized wpa_supplicant Dec 05 12:46:54 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 05 12:46:54 volumio wpa_supplicant[2476]: nl80211: kernel reports: Registration to specific type not supported Dec 05 12:46:54 volumio sudo[2484]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:54 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:55 volumio sudo[2484]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:55 volumio wireless.js[2331]: WIRELESS.JS: trying... Dec 05 12:46:55 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:55 volumio wpa_supplicant[2482]: wlan0: Trying to associate with b0:fc:88:53:7e:03 (SSID='SpectrumSetup-FD' freq=2462 MHz) Dec 05 12:46:55 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: AggregateError Dec 05 12:46:55 volumio sudo[2488]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 05 12:46:55 volumio sudo[2488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:55 volumio sudo[2488]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:55 volumio wpa_supplicant[2482]: wlan0: Associated with b0:fc:88:53:7e:03 Dec 05 12:46:55 volumio wpa_supplicant[2482]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 05 12:46:55 volumio wpa_supplicant[2482]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Dec 05 12:46:55 volumio wpa_supplicant[2482]: wlan0: WPA: Key negotiation completed with b0:fc:88:53:7e:03 [PTK=CCMP GTK=CCMP] Dec 05 12:46:55 volumio wpa_supplicant[2482]: wlan0: CTRL-EVENT-CONNECTED - Connection to b0:fc:88:53:7e:03 completed [id=0 id_str=] Dec 05 12:46:55 volumio dhcpcd[653]: wlan0: carrier acquired Dec 05 12:46:55 volumio dhcpcd[653]: wlan0: connected to Access Point: SpectrumSetup-FD Dec 05 12:46:55 volumio dhcpcd[653]: wlan0: IAID 01:79:da:0b Dec 05 12:46:55 volumio dhcpcd[653]: wlan0: soliciting an IPv6 router Dec 05 12:46:55 volumio sudo[2492]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:55 volumio sudo[2492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:55 volumio sudo[2492]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:56 volumio dhcpcd[653]: wlan0: soliciting a DHCP lease Dec 05 12:46:56 volumio wireless.js[2331]: WIRELESS.JS: trying... Dec 05 12:46:56 volumio sudo[2497]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 05 12:46:56 volumio sudo[2497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:56 volumio sudo[2497]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:56 volumio sudo[2500]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:56 volumio sudo[2500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:56 volumio sudo[2500]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Dec 05 12:46:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:57 volumio wireless.js[2331]: WIRELESS.JS: trying... Dec 05 12:46:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:46:57 volumio go-librespot[2502]: go-librespot daemon starting... Dec 05 12:46:57 volumio sudo[2505]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 05 12:46:57 volumio sudo[2505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:57 volumio sudo[2505]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:57 volumio go-librespot[2506]: time="2025-12-05T12:46:57-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:46:57 volumio go-librespot[2506]: time="2025-12-05T12:46:57-08:00" level=debug msg="app state loaded" Dec 05 12:46:57 volumio go-librespot[2506]: time="2025-12-05T12:46:57-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:46:57 volumio go-librespot[2506]: time="2025-12-05T12:46:57-08: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 12:46:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:46:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:46:57 volumio ntpd[748]: IO: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=233 secs Dec 05 12:46:57 volumio sudo[2514]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:57 volumio sudo[2514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:57 volumio sudo[2514]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:58 volumio wireless.js[2331]: WIRELESS.JS: trying... Dec 05 12:46:58 volumio sudo[2518]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 05 12:46:58 volumio sudo[2518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:58 volumio sudo[2518]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:58 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:46:58 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: AggregateError Dec 05 12:46:58 volumio sudo[2522]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:58 volumio sudo[2522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:58 volumio sudo[2522]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:59 volumio wireless.js[2331]: WIRELESS.JS: trying... Dec 05 12:46:59 volumio sudo[2526]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 05 12:46:59 volumio sudo[2526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:59 volumio sudo[2526]: pam_unix(sudo:session): session closed for user root Dec 05 12:46:59 volumio dhcpcd[653]: wlan0: offered 192.168.1.141 from 192.168.1.1 Dec 05 12:46:59 volumio dhcpcd[653]: wlan0: probing address 192.168.1.141/24 Dec 05 12:46:59 volumio sudo[2530]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:46:59 volumio sudo[2530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:46:59 volumio sudo[2530]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:00 volumio wireless.js[2331]: WIRELESS.JS: trying... Dec 05 12:47:00 volumio sudo[2534]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 05 12:47:00 volumio sudo[2534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:00 volumio sudo[2534]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Dec 05 12:47:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:47:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:47:00 volumio go-librespot[2536]: go-librespot daemon starting... Dec 05 12:47:00 volumio go-librespot[2537]: time="2025-12-05T12:47:00-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:47:00 volumio go-librespot[2537]: time="2025-12-05T12:47:00-08:00" level=debug msg="app state loaded" Dec 05 12:47:00 volumio go-librespot[2537]: time="2025-12-05T12:47:00-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:47:00 volumio go-librespot[2537]: time="2025-12-05T12:47:00-08: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 12:47:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:47:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:47:00 volumio sudo[2544]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:47:00 volumio sudo[2544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:00 volumio sudo[2544]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:01 volumio wireless.js[2331]: WIRELESS.JS: trying... Dec 05 12:47:01 volumio sudo[2548]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 05 12:47:01 volumio sudo[2548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:01 volumio sudo[2548]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:01 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:47:01 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: AggregateError Dec 05 12:47:01 volumio volumio[1184]: info: Discovery: Started advertising with name: Volumio Dec 05 12:47:01 volumio sudo[2551]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:47:01 volumio sudo[2551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:01 volumio sudo[2551]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:02 volumio wireless.js[2331]: WIRELESS.JS: trying... Dec 05 12:47:02 volumio sudo[2556]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 05 12:47:02 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:02 volumio sudo[2556]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:02 volumio sudo[2573]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:47:02 volumio sudo[2573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:02 volumio sudo[2573]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:02 volumio volumio[1184]: info: Discovery: adding c62f1d12-f938-4662-9dc0-f3740ac65949 Dec 05 12:47:02 volumio volumio[1184]: info: Discovery: Found device Volumio Dec 05 12:47:02 volumio volumio[1184]: info: CoreCommandRouter::volumioGetState Dec 05 12:47:02 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 05 12:47:03 volumio wireless.js[2331]: WIRELESS.JS: trying... Dec 05 12:47:03 volumio sudo[2578]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 05 12:47:03 volumio sudo[2578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:03 volumio sudo[2578]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Dec 05 12:47:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:47:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:47:03 volumio go-librespot[2580]: go-librespot daemon starting... Dec 05 12:47:03 volumio go-librespot[2581]: time="2025-12-05T12:47:03-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:47:03 volumio go-librespot[2581]: time="2025-12-05T12:47:03-08:00" level=debug msg="app state loaded" Dec 05 12:47:03 volumio go-librespot[2581]: time="2025-12-05T12:47:03-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:47:03 volumio go-librespot[2581]: time="2025-12-05T12:47:03-08: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 12:47:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:47:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:47:03 volumio sudo[2589]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:47:03 volumio sudo[2589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:03 volumio sudo[2589]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:04 volumio wireless.js[2331]: WIRELESS.JS: trying... Dec 05 12:47:04 volumio sudo[2593]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 05 12:47:04 volumio sudo[2593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:04 volumio sudo[2593]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:04 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:47:04 volumio volumio[1184]: info: Error connecting to go-librespot Websocket: AggregateError Dec 05 12:47:04 volumio dhcpcd[653]: wlan0: leased 192.168.1.141 for 43200 seconds Dec 05 12:47:04 volumio avahi-daemon[571]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.141. Dec 05 12:47:04 volumio dhcpcd[653]: wlan0: adding route to 192.168.1.0/24 Dec 05 12:47:04 volumio dhcpcd[653]: wlan0: adding default route via 192.168.1.1 Dec 05 12:47:04 volumio avahi-daemon[571]: New relevant interface wlan0.IPv4 for mDNS. Dec 05 12:47:04 volumio avahi-daemon[571]: Registering new address record for 192.168.1.141 on wlan0.IPv4. Dec 05 12:47:04 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 05 12:47:04 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 05 12:47:04 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 05 12:47:04 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 05 12:47:04 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 05 12:47:04 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 05 12:47:04 volumio welcome[2612]: Resolved ip:[1] 192.168.1.141 Dec 05 12:47:04 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 05 12:47:04 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 05 12:47:04 volumio sudo[2622]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:47:04 volumio sudo[2622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:04 volumio sudo[2622]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:04 volumio sudo[2626]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 05 12:47:04 volumio sudo[2626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:04 volumio sudo[2626]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:05 volumio wireless.js[2331]: WIRELESS.JS: trying... Dec 05 12:47:05 volumio sudo[2634]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 05 12:47:05 volumio sudo[2634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:05 volumio sudo[2634]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:05 volumio wireless.js[2331]: WIRELESS.JS: Connected to: ----SpectrumSetup-FD---- Dec 05 12:47:05 volumio sudo[2637]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 05 12:47:05 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 05 12:47:05 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:05 volumio wireless.js[2331]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 192.168.1.141, ipV6 is undefined Dec 05 12:47:05 volumio wireless.js[2331]: WIRELESS.JS: It's done! AP Dec 05 12:47:05 volumio wireless.js[2331]: WIRELESS.JS: Restarting avahi-daemon... Dec 05 12:47:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 05 12:47:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 05 12:47:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 05 12:47:05 volumio systemd[1]: shairport-sync.service: Consumed 2.525s CPU time. Dec 05 12:47:05 volumio avahi-daemon[571]: Got SIGTERM, quitting. Dec 05 12:47:05 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 05 12:47:05 volumio avahi-daemon[571]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.141. Dec 05 12:47:05 volumio avahi-daemon[571]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 05 12:47:05 volumio volumio[1184]: info: Discovery: Browse raised the following error Error: dns service error: unknown Dec 05 12:47:05 volumio avahi-daemon[571]: avahi-daemon 0.8 exiting. Dec 05 12:47:05 volumio volumio[1184]: error: Discovery: Advertisement error: Error: dns service error: unknown Dec 05 12:47:05 volumio volumio[1184]: error: Discovery: advertisement error: Error: dns service error: unknown Dec 05 12:47:05 volumio volumio[1184]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Dec 05 12:47:05 volumio volumio[1184]: info: Discovery: Browse raised the following error Error: dns service error: unknown Dec 05 12:47:05 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Dec 05 12:47:05 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 05 12:47:05 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 05 12:47:05 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 05 12:47:05 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 05 12:47:05 volumio sudo[2326]: pam_unix(sudo:session): session closed for user root Dec 05 12:47:05 volumio wireless.js[2331]: WIRELESS.JS: Notified systemd about wireless ready Dec 05 12:47:05 volumio avahi-daemon[2645]: Process 571 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Dec 05 12:47:05 volumio avahi-daemon[2645]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Dec 05 12:47:05 volumio avahi-daemon[2645]: Successfully dropped root privileges. Dec 05 12:47:05 volumio avahi-daemon[2645]: avahi-daemon 0.8 starting up. Dec 05 12:47:05 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 05 12:47:05 volumio avahi-daemon[2645]: Successfully called chroot(). Dec 05 12:47:05 volumio avahi-daemon[2645]: Successfully dropped remaining capabilities. Dec 05 12:47:05 volumio avahi-daemon[2645]: No service file found in /etc/avahi/services. Dec 05 12:47:05 volumio avahi-daemon[2645]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.141. Dec 05 12:47:05 volumio avahi-daemon[2645]: New relevant interface wlan0.IPv4 for mDNS. Dec 05 12:47:05 volumio avahi-daemon[2645]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 05 12:47:05 volumio avahi-daemon[2645]: New relevant interface lo.IPv4 for mDNS. Dec 05 12:47:05 volumio avahi-daemon[2645]: Network interface enumeration completed. Dec 05 12:47:05 volumio avahi-daemon[2645]: Registering new address record for 192.168.1.141 on wlan0.IPv4. Dec 05 12:47:05 volumio avahi-daemon[2645]: Registering new address record for 127.0.0.1 on lo.IPv4. Dec 05 12:47:05 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 05 12:47:06 volumio ntpd[748]: IO: Listen normally on 4 wlan0 192.168.1.141:123 Dec 05 12:47:06 volumio ntpd[748]: IO: new interface(s) found: waking up resolver Dec 05 12:47:06 volumio ntpd[748]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 05 12:47:06 volumio avahi-daemon[2645]: Server startup complete. Host name is volumio.local. Local service cookie is 1248999339. Dec 05 12:47:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Dec 05 12:47:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:47:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:47:06 volumio go-librespot[2663]: go-librespot daemon starting... Dec 05 12:47:06 volumio go-librespot[2664]: time="2025-12-05T12:47:06-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:47:06 volumio go-librespot[2664]: time="2025-12-05T12:47:06-08:00" level=debug msg="app state loaded" Dec 05 12:47:06 volumio go-librespot[2664]: time="2025-12-05T12:47:06-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:47:07 volumio volumio[1184]: info: Initializing connection to go-librespot Websocket Dec 05 12:47:07 volumio go-librespot[2664]: time="2025-12-05T12:47:07-08:00" level=debug msg="new websocket client" Dec 05 12:47:07 volumio volumio[1184]: info: Connection to go-librespot Websocket established Dec 05 12:47:07 volumio ntpd[748]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 05 12:47:07 volumio ntpd[748]: DNS: Pool taking: 157.245.125.229 Dec 05 12:47:07 volumio ntpd[748]: DNS: Pool taking: 45.63.54.13 Dec 05 12:47:07 volumio ntpd[748]: DNS: Pool taking: 139.177.202.26 Dec 05 12:47:07 volumio ntpd[748]: DNS: Pool taking: 45.83.234.123 Dec 05 12:47:07 volumio ntpd[748]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 05 12:47:08 volumio go-librespot[2664]: time="2025-12-05T12:47:08-08: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-05T12:47:07-08:00 is before 2025-12-08T00:00:00Z" Dec 05 12:47:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:47:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 05 12:47:08 volumio volumio[1184]: info: Connection to go-librespot Websocket closed Dec 05 12:47:08 volumio ntpd[748]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 05 12:47:08 volumio ntpd[748]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 05 12:47:08 volumio ntpd[748]: DNS: Pool taking: 23.150.41.122 Dec 05 12:47:08 volumio ntpd[748]: DNS: Pool taking: 67.217.246.204 Dec 05 12:47:08 volumio ntpd[748]: DNS: Pool taking: 141.11.89.193 Dec 05 12:47:08 volumio ntpd[748]: DNS: Pool taking: 74.208.14.149 Dec 05 12:47:08 volumio ntpd[748]: DNS: Pool taking: 2602:f975:12::123 Dec 05 12:47:08 volumio ntpd[748]: DNS: Pool taking: 2600:3c01::f03c:93ff:fe5b:8a7d Dec 05 12:47:08 volumio ntpd[748]: DNS: Pool taking: 2602:f9ab:2:3d00::a Dec 05 12:47:08 volumio ntpd[748]: DNS: Pool taking: 2604:2dc0:100:25e2:2ab9:2b59:40e7:1 Dec 05 12:47:08 volumio ntpd[748]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 05 12:47:09 volumio ntpd[748]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 05 12:47:09 volumio ntpd[748]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 05 12:47:09 volumio ntpd[748]: DNS: Pool taking: 209.148.110.237 Dec 05 12:47:09 volumio ntpd[748]: DNS: Pool taking: 23.186.168.132 Dec 05 12:47:09 volumio ntpd[748]: DNS: Pool taking: 162.159.200.123 Dec 05 12:47:09 volumio ntpd[748]: DNS: Pool taking: 96.231.54.40 Dec 05 12:47:09 volumio ntpd[748]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 05 12:47:10 volumio ntpd[748]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 05 12:47:10 volumio volumio[1184]: info: Getting Spotify volume Dec 05 12:47:10 volumio volumio[1184]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 12:47:10 volumio volumio[1184]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 05 12:47:10 volumio volumio[1184]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 05 12:47:10 volumio volumio[1184]: errno: -111, Dec 05 12:47:10 volumio volumio[1184]: code: 'ECONNREFUSED', Dec 05 12:47:10 volumio volumio[1184]: syscall: 'connect', Dec 05 12:47:10 volumio volumio[1184]: address: '127.0.0.1', Dec 05 12:47:10 volumio volumio[1184]: port: 9879, Dec 05 12:47:10 volumio volumio[1184]: response: undefined Dec 05 12:47:10 volumio volumio[1184]: } Dec 05 12:47:10 volumio volumio[1184]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 05 12:47:10 volumio ntpd[748]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 05 12:47:10 volumio ntpd[748]: DNS: Pool taking: 192.48.105.15 Dec 05 12:47:10 volumio ntpd[748]: DNS: Pool taking: 141.11.228.173 Dec 05 12:47:10 volumio ntpd[748]: DNS: Pool taking: 172.234.25.10 Dec 05 12:47:10 volumio ntpd[748]: DNS: Pool taking: 23.111.186.186 Dec 05 12:47:10 volumio ntpd[748]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 05 12:47:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Dec 05 12:47:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:47:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 05 12:47:11 volumio go-librespot[2686]: go-librespot daemon starting... Dec 05 12:47:11 volumio go-librespot[2687]: time="2025-12-05T12:47:11-08:00" level=info msg="running go-librespot 0.4.0" Dec 05 12:47:11 volumio go-librespot[2687]: time="2025-12-05T12:47:11-08:00" level=debug msg="app state loaded" Dec 05 12:47:11 volumio go-librespot[2687]: time="2025-12-05T12:47:11-08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 05 12:47:11 volumio sudo[2695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-05 12:46' Dec 05 12:47:11 volumio sudo[2695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 05 12:47:11 volumio go-librespot[2687]: time="2025-12-05T12:47:11-08: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-05T12:47:11-08:00 is before 2025-12-08T00:00:00Z" Dec 05 12:47:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 05 12:47:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. 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"