May 26 21:00:00 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:00 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 154. May 26 21:00:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:02 volumio go-librespot[9213]: go-librespot daemon starting... May 26 21:00:02 volumio go-librespot[9214]: time="2026-05-26T21:00:02-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:02 volumio go-librespot[9214]: time="2026-05-26T21:00:02-05:00" level=debug msg="app state loaded" May 26 21:00:02 volumio go-librespot[9214]: time="2026-05-26T21:00:02-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:02 volumio go-librespot[9214]: time="2026-05-26T21:00:02-05: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" May 26 21:00:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:03 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:03 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:03 volumio wpa_supplicant[1182]: wlan0: Failed to initiate sched scan May 26 21:00:04 volumio volumio[1220]: info: Received Get System Info May 26 21:00:04 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 21:00:04 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 21:00:04 volumio volumio[1220]: info: Discovery: Getting this device information May 26 21:00:04 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:00:04 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:00:04 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 21:00:04 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 21:00:04 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 21:00:05 volumio volumio5-onboarding[1568]: time=2026-05-26T21:00:05.279-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 21:00:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 155. May 26 21:00:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:05 volumio go-librespot[9236]: go-librespot daemon starting... May 26 21:00:05 volumio go-librespot[9237]: time="2026-05-26T21:00:05-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:05 volumio go-librespot[9237]: time="2026-05-26T21:00:05-05:00" level=debug msg="app state loaded" May 26 21:00:05 volumio go-librespot[9237]: time="2026-05-26T21:00:05-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:05 volumio go-librespot[9237]: time="2026-05-26T21:00:05-05: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" May 26 21:00:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:06 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:06 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 156. May 26 21:00:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:08 volumio go-librespot[9245]: go-librespot daemon starting... May 26 21:00:08 volumio go-librespot[9246]: time="2026-05-26T21:00:08-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:08 volumio go-librespot[9246]: time="2026-05-26T21:00:08-05:00" level=debug msg="app state loaded" May 26 21:00:08 volumio go-librespot[9246]: time="2026-05-26T21:00:08-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:08 volumio go-librespot[9246]: time="2026-05-26T21:00:08-05: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" May 26 21:00:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:09 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:09 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:11 volumio wpa_supplicant[1182]: wlan0: Failed to initiate sched scan May 26 21:00:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 157. May 26 21:00:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:12 volumio volumio[1220]: info: Received Get System Info May 26 21:00:12 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 21:00:12 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 21:00:12 volumio volumio[1220]: info: Discovery: Getting this device information May 26 21:00:12 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:00:12 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:00:12 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 21:00:12 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 21:00:12 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 21:00:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:12 volumio go-librespot[9254]: go-librespot daemon starting... May 26 21:00:12 volumio go-librespot[9255]: time="2026-05-26T21:00:12-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:12 volumio go-librespot[9255]: time="2026-05-26T21:00:12-05:00" level=debug msg="app state loaded" May 26 21:00:12 volumio go-librespot[9255]: time="2026-05-26T21:00:12-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:12 volumio go-librespot[9255]: time="2026-05-26T21:00:12-05: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" May 26 21:00:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:12 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:12 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:13 volumio volumio5-onboarding[1568]: time=2026-05-26T21:00:13.027-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 21:00:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 158. May 26 21:00:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:15 volumio go-librespot[9277]: go-librespot daemon starting... May 26 21:00:15 volumio go-librespot[9278]: time="2026-05-26T21:00:15-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:15 volumio go-librespot[9278]: time="2026-05-26T21:00:15-05:00" level=debug msg="app state loaded" May 26 21:00:15 volumio go-librespot[9278]: time="2026-05-26T21:00:15-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:15 volumio go-librespot[9278]: time="2026-05-26T21:00:15-05: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" May 26 21:00:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:15 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:15 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:18 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:18 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 159. May 26 21:00:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:18 volumio go-librespot[9286]: go-librespot daemon starting... May 26 21:00:18 volumio go-librespot[9287]: time="2026-05-26T21:00:18-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:18 volumio go-librespot[9287]: time="2026-05-26T21:00:18-05:00" level=debug msg="app state loaded" May 26 21:00:18 volumio go-librespot[9287]: time="2026-05-26T21:00:18-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:18 volumio go-librespot[9287]: time="2026-05-26T21:00:18-05: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" May 26 21:00:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:19 volumio wpa_supplicant[1182]: wlan0: Failed to initiate sched scan May 26 21:00:19 volumio volumio[1220]: info: Received Get System Info May 26 21:00:19 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 21:00:19 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 21:00:19 volumio volumio[1220]: info: Discovery: Getting this device information May 26 21:00:19 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:00:19 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:00:19 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 21:00:19 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 21:00:19 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 21:00:20 volumio volumio5-onboarding[1568]: time=2026-05-26T21:00:20.824-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 21:00:21 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:21 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 160. May 26 21:00:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:21 volumio go-librespot[9295]: go-librespot daemon starting... May 26 21:00:21 volumio go-librespot[9296]: time="2026-05-26T21:00:21-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:21 volumio go-librespot[9296]: time="2026-05-26T21:00:21-05:00" level=debug msg="app state loaded" May 26 21:00:21 volumio go-librespot[9296]: time="2026-05-26T21:00:21-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:21 volumio go-librespot[9296]: time="2026-05-26T21:00:21-05: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" May 26 21:00:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:24 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:24 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 161. May 26 21:00:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:25 volumio go-librespot[9318]: go-librespot daemon starting... May 26 21:00:25 volumio go-librespot[9319]: time="2026-05-26T21:00:25-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:25 volumio go-librespot[9319]: time="2026-05-26T21:00:25-05:00" level=debug msg="app state loaded" May 26 21:00:25 volumio go-librespot[9319]: time="2026-05-26T21:00:25-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:25 volumio go-librespot[9319]: time="2026-05-26T21:00:25-05: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" May 26 21:00:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:27 volumio wpa_supplicant[1182]: wlan0: Failed to initiate sched scan May 26 21:00:27 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:27 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:27 volumio volumio[1220]: info: Received Get System Info May 26 21:00:27 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 21:00:27 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 21:00:27 volumio volumio[1220]: info: Discovery: Getting this device information May 26 21:00:27 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:00:27 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:00:27 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 21:00:27 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 21:00:27 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 21:00:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 162. May 26 21:00:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:28 volumio go-librespot[9327]: go-librespot daemon starting... May 26 21:00:28 volumio go-librespot[9328]: time="2026-05-26T21:00:28-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:28 volumio go-librespot[9328]: time="2026-05-26T21:00:28-05:00" level=debug msg="app state loaded" May 26 21:00:28 volumio go-librespot[9328]: time="2026-05-26T21:00:28-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:28 volumio go-librespot[9328]: time="2026-05-26T21:00:28-05: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" May 26 21:00:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:28 volumio volumio5-onboarding[1568]: time=2026-05-26T21:00:28.602-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 21:00:30 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:30 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 163. May 26 21:00:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:31 volumio go-librespot[9336]: go-librespot daemon starting... May 26 21:00:31 volumio go-librespot[9337]: time="2026-05-26T21:00:31-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:31 volumio go-librespot[9337]: time="2026-05-26T21:00:31-05:00" level=debug msg="app state loaded" May 26 21:00:31 volumio go-librespot[9337]: time="2026-05-26T21:00:31-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:31 volumio go-librespot[9337]: time="2026-05-26T21:00:31-05: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" May 26 21:00:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:33 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:33 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 164. May 26 21:00:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:34 volumio go-librespot[9359]: go-librespot daemon starting... May 26 21:00:34 volumio go-librespot[9360]: time="2026-05-26T21:00:34-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:34 volumio go-librespot[9360]: time="2026-05-26T21:00:34-05:00" level=debug msg="app state loaded" May 26 21:00:34 volumio go-librespot[9360]: time="2026-05-26T21:00:34-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:34 volumio go-librespot[9360]: time="2026-05-26T21:00:34-05: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" May 26 21:00:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:34 volumio wpa_supplicant[1182]: wlan0: Failed to initiate sched scan May 26 21:00:35 volumio volumio[1220]: info: Received Get System Info May 26 21:00:35 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 21:00:35 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 21:00:35 volumio volumio[1220]: info: Discovery: Getting this device information May 26 21:00:35 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:00:35 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:00:35 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 21:00:35 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 21:00:35 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 21:00:36 volumio volumio5-onboarding[1568]: time=2026-05-26T21:00:36.284-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 21:00:36 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:36 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 165. May 26 21:00:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:38 volumio go-librespot[9368]: go-librespot daemon starting... May 26 21:00:38 volumio go-librespot[9369]: time="2026-05-26T21:00:38-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:38 volumio go-librespot[9369]: time="2026-05-26T21:00:38-05:00" level=debug msg="app state loaded" May 26 21:00:38 volumio go-librespot[9369]: time="2026-05-26T21:00:38-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:38 volumio go-librespot[9369]: time="2026-05-26T21:00:38-05: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" May 26 21:00:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:39 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:39 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 166. May 26 21:00:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:41 volumio go-librespot[9377]: go-librespot daemon starting... May 26 21:00:41 volumio go-librespot[9378]: time="2026-05-26T21:00:41-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:41 volumio go-librespot[9378]: time="2026-05-26T21:00:41-05:00" level=debug msg="app state loaded" May 26 21:00:41 volumio go-librespot[9378]: time="2026-05-26T21:00:41-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:41 volumio go-librespot[9378]: time="2026-05-26T21:00:41-05: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" May 26 21:00:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:42 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:42 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:42 volumio wpa_supplicant[1182]: wlan0: Failed to initiate sched scan May 26 21:00:43 volumio volumio[1220]: info: Received Get System Info May 26 21:00:43 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 21:00:43 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 21:00:43 volumio volumio[1220]: info: Discovery: Getting this device information May 26 21:00:43 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:00:43 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:00:43 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 21:00:43 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 21:00:43 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 21:00:44 volumio volumio5-onboarding[1568]: time=2026-05-26T21:00:44.096-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 21:00:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 167. May 26 21:00:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:44 volumio go-librespot[9400]: go-librespot daemon starting... May 26 21:00:44 volumio go-librespot[9401]: time="2026-05-26T21:00:44-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:44 volumio go-librespot[9401]: time="2026-05-26T21:00:44-05:00" level=debug msg="app state loaded" May 26 21:00:44 volumio go-librespot[9401]: time="2026-05-26T21:00:44-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:44 volumio go-librespot[9401]: time="2026-05-26T21:00:44-05: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" May 26 21:00:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:45 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:45 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 168. May 26 21:00:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:47 volumio go-librespot[9409]: go-librespot daemon starting... May 26 21:00:47 volumio go-librespot[9410]: time="2026-05-26T21:00:47-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:47 volumio go-librespot[9410]: time="2026-05-26T21:00:47-05:00" level=debug msg="app state loaded" May 26 21:00:47 volumio go-librespot[9410]: time="2026-05-26T21:00:47-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:47 volumio go-librespot[9410]: time="2026-05-26T21:00:47-05: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" May 26 21:00:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:48 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:48 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:50 volumio wpa_supplicant[1182]: wlan0: Failed to initiate sched scan May 26 21:00:50 volumio volumio[1220]: info: Received Get System Info May 26 21:00:50 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 21:00:50 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 21:00:50 volumio volumio[1220]: info: Discovery: Getting this device information May 26 21:00:50 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:00:50 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:00:50 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 21:00:50 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 21:00:50 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 21:00:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 169. May 26 21:00:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:51 volumio go-librespot[9419]: go-librespot daemon starting... May 26 21:00:51 volumio go-librespot[9420]: time="2026-05-26T21:00:51-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:51 volumio go-librespot[9420]: time="2026-05-26T21:00:51-05:00" level=debug msg="app state loaded" May 26 21:00:51 volumio go-librespot[9420]: time="2026-05-26T21:00:51-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:51 volumio go-librespot[9420]: time="2026-05-26T21:00:51-05: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" May 26 21:00:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:51 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:51 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:51 volumio volumio5-onboarding[1568]: time=2026-05-26T21:00:51.908-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 21:00:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 170. May 26 21:00:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:54 volumio go-librespot[9442]: go-librespot daemon starting... May 26 21:00:54 volumio go-librespot[9443]: time="2026-05-26T21:00:54-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:54 volumio go-librespot[9443]: time="2026-05-26T21:00:54-05:00" level=debug msg="app state loaded" May 26 21:00:54 volumio go-librespot[9443]: time="2026-05-26T21:00:54-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:54 volumio go-librespot[9443]: time="2026-05-26T21:00:54-05: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" May 26 21:00:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:54 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:54 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 171. May 26 21:00:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:57 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:00:57 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:00:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:00:57 volumio go-librespot[9451]: go-librespot daemon starting... May 26 21:00:57 volumio go-librespot[9452]: time="2026-05-26T21:00:57-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:00:57 volumio go-librespot[9452]: time="2026-05-26T21:00:57-05:00" level=debug msg="app state loaded" May 26 21:00:57 volumio go-librespot[9452]: time="2026-05-26T21:00:57-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:00:57 volumio go-librespot[9452]: time="2026-05-26T21:00:57-05: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" May 26 21:00:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:00:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:00:58 volumio wpa_supplicant[1182]: wlan0: Failed to initiate sched scan May 26 21:00:58 volumio volumio[1220]: info: Received Get System Info May 26 21:00:58 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 21:00:58 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 21:00:58 volumio volumio[1220]: info: Discovery: Getting this device information May 26 21:00:58 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:00:58 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:00:58 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 21:00:58 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 21:00:58 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 21:00:59 volumio volumio5-onboarding[1568]: time=2026-05-26T21:00:59.690-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 21:01:00 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:01:00 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:01:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 172. May 26 21:01:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:00 volumio go-librespot[9460]: go-librespot daemon starting... May 26 21:01:00 volumio go-librespot[9461]: time="2026-05-26T21:01:00-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:01:00 volumio go-librespot[9461]: time="2026-05-26T21:01:00-05:00" level=debug msg="app state loaded" May 26 21:01:00 volumio go-librespot[9461]: time="2026-05-26T21:01:00-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:01:00 volumio go-librespot[9461]: time="2026-05-26T21:01:00-05: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" May 26 21:01:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:01:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:01:03 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:01:03 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:01:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 173. May 26 21:01:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:04 volumio go-librespot[9483]: go-librespot daemon starting... May 26 21:01:04 volumio go-librespot[9484]: time="2026-05-26T21:01:04-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:01:04 volumio go-librespot[9484]: time="2026-05-26T21:01:04-05:00" level=debug msg="app state loaded" May 26 21:01:04 volumio go-librespot[9484]: time="2026-05-26T21:01:04-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:01:04 volumio go-librespot[9484]: time="2026-05-26T21:01:04-05: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" May 26 21:01:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:01:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:01:06 volumio wpa_supplicant[1182]: wlan0: Trying to associate with 94:18:65:ea:36:24 (SSID='Stardust-5G' freq=5220 MHz) May 26 21:01:06 volumio wpa_supplicant[1182]: wlan0: Associated with 94:18:65:ea:36:24 May 26 21:01:06 volumio wpa_supplicant[1182]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 26 21:01:06 volumio wpa_supplicant[1182]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CA May 26 21:01:06 volumio wpa_supplicant[1182]: wlan0: WPA: Key negotiation completed with 94:18:65:ea:36:24 [PTK=CCMP GTK=CCMP] May 26 21:01:06 volumio wpa_supplicant[1182]: wlan0: CTRL-EVENT-CONNECTED - Connection to 94:18:65:ea:36:24 completed [id=0 id_str=] May 26 21:01:06 volumio dhcpcd[812]: wlan0: carrier acquired May 26 21:01:06 volumio dhcpcd[812]: wlan0: IAID 01:07:14:ae May 26 21:01:06 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:01:06 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:01:06 volumio volumio[1220]: info: Received Get System Info May 26 21:01:06 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 21:01:06 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 21:01:06 volumio volumio[1220]: info: Discovery: Getting this device information May 26 21:01:06 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:01:06 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:01:06 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 21:01:06 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 21:01:06 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 21:01:06 volumio dhcpcd[812]: wlan0: soliciting an IPv6 router May 26 21:01:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 174. May 26 21:01:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:07 volumio dhcpcd[812]: wlan0: rebinding lease of 192.168.1.64 May 26 21:01:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:07 volumio go-librespot[9493]: go-librespot daemon starting... May 26 21:01:07 volumio go-librespot[9496]: time="2026-05-26T21:01:07-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:01:07 volumio go-librespot[9496]: time="2026-05-26T21:01:07-05:00" level=debug msg="app state loaded" May 26 21:01:07 volumio go-librespot[9496]: time="2026-05-26T21:01:07-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:01:07 volumio go-librespot[9496]: time="2026-05-26T21:01:07-05: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" May 26 21:01:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:01:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:01:07 volumio dhcpcd[812]: wlan0: NAK: from 192.168.1.1 May 26 21:01:07 volumio dhcpcd[812]: wlan0: soliciting a DHCP lease May 26 21:01:07 volumio volumio5-onboarding[1568]: time=2026-05-26T21:01:07.681-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 21:01:09 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:01:09 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:01:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 175. May 26 21:01:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:10 volumio go-librespot[9513]: go-librespot daemon starting... May 26 21:01:10 volumio go-librespot[9514]: time="2026-05-26T21:01:10-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:01:10 volumio go-librespot[9514]: time="2026-05-26T21:01:10-05:00" level=debug msg="app state loaded" May 26 21:01:10 volumio go-librespot[9514]: time="2026-05-26T21:01:10-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:01:10 volumio go-librespot[9514]: time="2026-05-26T21:01:10-05: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" May 26 21:01:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:01:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:01:12 volumio dhcpcd[812]: wlan0: offered 192.168.1.4 from 192.168.1.1 May 26 21:01:12 volumio dhcpcd[812]: wlan0: probing address 192.168.1.4/24 May 26 21:01:12 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:01:12 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:01:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 176. May 26 21:01:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:13 volumio go-librespot[9537]: go-librespot daemon starting... May 26 21:01:13 volumio go-librespot[9538]: time="2026-05-26T21:01:13-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:01:13 volumio go-librespot[9538]: time="2026-05-26T21:01:13-05:00" level=debug msg="app state loaded" May 26 21:01:13 volumio go-librespot[9538]: time="2026-05-26T21:01:13-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:01:13 volumio go-librespot[9538]: time="2026-05-26T21:01:13-05: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" May 26 21:01:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:01:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:01:15 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:01:15 volumio volumio[1220]: info: Error connecting to go-librespot Websocket: AggregateError May 26 21:01:16 volumio dhcpcd[812]: wlan0: leased 192.168.1.4 for 86400 seconds May 26 21:01:16 volumio avahi-daemon[1430]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.4. May 26 21:01:16 volumio avahi-daemon[1430]: New relevant interface wlan0.IPv4 for mDNS. May 26 21:01:16 volumio avahi-daemon[1430]: Registering new address record for 192.168.1.4 on wlan0.IPv4. May 26 21:01:16 volumio dhcpcd[812]: wlan0: adding route to 192.168.1.0/24 May 26 21:01:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 177. May 26 21:01:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:16 volumio dhcpcd[812]: wlan0: adding default route via 192.168.1.1 May 26 21:01:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 26 21:01:17 volumio go-librespot[9559]: go-librespot daemon starting... May 26 21:01:17 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 26 21:01:17 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 26 21:01:17 volumio systemd[1]: welcome.service: Deactivated successfully. May 26 21:01:17 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 26 21:01:17 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 26 21:01:17 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 26 21:01:17 volumio go-librespot[9568]: time="2026-05-26T21:01:17-05:00" level=info msg="running go-librespot 0.7.1" May 26 21:01:17 volumio go-librespot[9568]: time="2026-05-26T21:01:17-05:00" level=debug msg="app state loaded" May 26 21:01:17 volumio welcome[9571]: Resolved ip:[1] 192.168.1.4 May 26 21:01:17 volumio go-librespot[9568]: time="2026-05-26T21:01:17-05:00" level=info msg="api server listening on 127.0.0.1:9879" May 26 21:01:17 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 26 21:01:17 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 26 21:01:17 volumio volumio[1220]: info: Received Get System Info May 26 21:01:17 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 21:01:17 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 21:01:17 volumio volumio[1220]: info: Discovery: Getting this device information May 26 21:01:17 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:01:17 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:01:17 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 21:01:17 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 21:01:17 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 21:01:18 volumio ntpd[1002]: IO: Listen normally on 4 wlan0 192.168.1.4:123 May 26 21:01:18 volumio ntpd[1002]: IO: new interface(s) found: waking up resolver May 26 21:01:18 volumio volumio5-onboarding[1568]: time=2026-05-26T21:01:18.338-05:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 21:01:18 volumio volumio[1220]: info: Initializing connection to go-librespot Websocket May 26 21:01:18 volumio go-librespot[9568]: time="2026-05-26T21:01:18-05:00" level=debug msg="new websocket client" May 26 21:01:18 volumio volumio[1220]: info: Connection to go-librespot Websocket established May 26 21:01:21 volumio volumio[1220]: info: Getting Spotify volume May 26 21:01:21 volumio volumio[1220]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 26 21:01:21 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:01:21 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:01:21 volumio volumio[1220]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 26 21:01:23 volumio volumio[1220]: info: Volumio Network Manager: Network status updated: 2 May 26 21:01:36 volumio go-librespot[9568]: time="2026-05-26T21:01:36-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" May 26 21:01:36 volumio go-librespot[9568]: time="2026-05-26T21:01:36-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 26 21:01:36 volumio go-librespot[9568]: time="2026-05-26T21:01:36-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 26 21:01:36 volumio go-librespot[9568]: time="2026-05-26T21:01:36-05:00" level=info msg="zeroconf server listening on port 43187" May 26 21:01:36 volumio go-librespot[9568]: time="2026-05-26T21:01:36-05:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" May 26 21:01:55 volumio go-librespot[9568]: time="2026-05-26T21:01:55-05:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" May 26 21:01:55 volumio volumio[1220]: info: Discovery: adding 5945fbb0-3434-489a-8b05-8c1457f7bf4a May 26 21:01:55 volumio volumio[1220]: info: Discovery: Found device Volumio May 26 21:01:55 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 26 21:01:55 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 26 21:01:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 26 21:01:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 26 21:01:55 volumio volumio[1220]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 21:01:55 volumio volumio[1220]: Error: socket hang up May 26 21:01:55 volumio volumio[1220]: at connResetException (node:internal/errors:720:14) May 26 21:01:55 volumio volumio[1220]: at Socket.socketOnEnd (node:_http_client:519:23) May 26 21:01:55 volumio volumio[1220]: at Socket.emit (node:events:526:35) May 26 21:01:55 volumio volumio[1220]: at endReadableNT (node:internal/streams/readable:1376:12) May 26 21:01:55 volumio volumio[1220]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { May 26 21:01:55 volumio volumio[1220]: code: 'ECONNRESET', May 26 21:01:55 volumio volumio[1220]: response: undefined May 26 21:01:55 volumio volumio[1220]: } May 26 21:01:55 volumio volumio[1220]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 21:01:55 volumio sudo[9657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 21:00' May 26 21:01:55 volumio sudo[9657]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"