Aug 04 17:20:00 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:00 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754907. Aug 04 17:20:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:00 volumio go-librespot[3223]: go-librespot daemon starting... Aug 04 17:20:00 volumio go-librespot[3224]: time="2026-08-04T17:20:00+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:00 volumio go-librespot[3224]: time="2026-08-04T17:20:00+10:00" level=debug msg="app state loaded" Aug 04 17:20:00 volumio go-librespot[3224]: time="2026-08-04T17:20:00+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:00 volumio go-librespot[3224]: time="2026-08-04T17:20:00+10: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" Aug 04 17:20:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:03 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:03 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754908. Aug 04 17:20:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:04 volumio go-librespot[3230]: go-librespot daemon starting... Aug 04 17:20:04 volumio go-librespot[3231]: time="2026-08-04T17:20:04+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:04 volumio go-librespot[3231]: time="2026-08-04T17:20:04+10:00" level=debug msg="app state loaded" Aug 04 17:20:04 volumio go-librespot[3231]: time="2026-08-04T17:20:04+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:04 volumio go-librespot[3231]: time="2026-08-04T17:20:04+10: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" Aug 04 17:20:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:06 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:06 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754909. Aug 04 17:20:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:07 volumio go-librespot[3252]: go-librespot daemon starting... Aug 04 17:20:07 volumio go-librespot[3253]: time="2026-08-04T17:20:07+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:07 volumio go-librespot[3253]: time="2026-08-04T17:20:07+10:00" level=debug msg="app state loaded" Aug 04 17:20:07 volumio go-librespot[3253]: time="2026-08-04T17:20:07+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:07 volumio go-librespot[3253]: time="2026-08-04T17:20:07+10: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" Aug 04 17:20:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:09 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:09 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754910. Aug 04 17:20:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:10 volumio go-librespot[3259]: go-librespot daemon starting... Aug 04 17:20:10 volumio go-librespot[3260]: time="2026-08-04T17:20:10+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:10 volumio go-librespot[3260]: time="2026-08-04T17:20:10+10:00" level=debug msg="app state loaded" Aug 04 17:20:10 volumio go-librespot[3260]: time="2026-08-04T17:20:10+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:10 volumio go-librespot[3260]: time="2026-08-04T17:20:10+10: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" Aug 04 17:20:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:12 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:12 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754911. Aug 04 17:20:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:13 volumio go-librespot[3267]: go-librespot daemon starting... Aug 04 17:20:13 volumio go-librespot[3268]: time="2026-08-04T17:20:13+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:13 volumio go-librespot[3268]: time="2026-08-04T17:20:13+10:00" level=debug msg="app state loaded" Aug 04 17:20:13 volumio go-librespot[3268]: time="2026-08-04T17:20:13+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:13 volumio go-librespot[3268]: time="2026-08-04T17:20:13+10: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" Aug 04 17:20:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:15 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:15 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754912. Aug 04 17:20:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:17 volumio go-librespot[3274]: go-librespot daemon starting... Aug 04 17:20:17 volumio go-librespot[3275]: time="2026-08-04T17:20:17+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:17 volumio go-librespot[3275]: time="2026-08-04T17:20:17+10:00" level=debug msg="app state loaded" Aug 04 17:20:17 volumio go-librespot[3275]: time="2026-08-04T17:20:17+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:17 volumio go-librespot[3275]: time="2026-08-04T17:20:17+10: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" Aug 04 17:20:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:18 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:18 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754913. Aug 04 17:20:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:20 volumio go-librespot[3295]: go-librespot daemon starting... Aug 04 17:20:20 volumio go-librespot[3296]: time="2026-08-04T17:20:20+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:20 volumio go-librespot[3296]: time="2026-08-04T17:20:20+10:00" level=debug msg="app state loaded" Aug 04 17:20:20 volumio go-librespot[3296]: time="2026-08-04T17:20:20+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:20 volumio go-librespot[3296]: time="2026-08-04T17:20:20+10: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" Aug 04 17:20:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:21 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:21 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754914. Aug 04 17:20:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:23 volumio go-librespot[3303]: go-librespot daemon starting... Aug 04 17:20:23 volumio go-librespot[3304]: time="2026-08-04T17:20:23+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:23 volumio go-librespot[3304]: time="2026-08-04T17:20:23+10:00" level=debug msg="app state loaded" Aug 04 17:20:23 volumio go-librespot[3304]: time="2026-08-04T17:20:23+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:23 volumio go-librespot[3304]: time="2026-08-04T17:20:23+10: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" Aug 04 17:20:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:24 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:24 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754915. Aug 04 17:20:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:26 volumio go-librespot[3310]: go-librespot daemon starting... Aug 04 17:20:26 volumio go-librespot[3311]: time="2026-08-04T17:20:26+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:26 volumio go-librespot[3311]: time="2026-08-04T17:20:26+10:00" level=debug msg="app state loaded" Aug 04 17:20:26 volumio go-librespot[3311]: time="2026-08-04T17:20:26+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:26 volumio go-librespot[3311]: time="2026-08-04T17:20:26+10: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" Aug 04 17:20:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:27 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:27 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754916. Aug 04 17:20:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:30 volumio go-librespot[3331]: go-librespot daemon starting... Aug 04 17:20:30 volumio go-librespot[3332]: time="2026-08-04T17:20:30+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:30 volumio go-librespot[3332]: time="2026-08-04T17:20:30+10:00" level=debug msg="app state loaded" Aug 04 17:20:30 volumio go-librespot[3332]: time="2026-08-04T17:20:30+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:30 volumio go-librespot[3332]: time="2026-08-04T17:20:30+10: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" Aug 04 17:20:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:30 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:30 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754917. Aug 04 17:20:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:33 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:33 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:33 volumio go-librespot[3339]: go-librespot daemon starting... Aug 04 17:20:33 volumio go-librespot[3340]: time="2026-08-04T17:20:33+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:33 volumio go-librespot[3340]: time="2026-08-04T17:20:33+10:00" level=debug msg="app state loaded" Aug 04 17:20:33 volumio go-librespot[3340]: time="2026-08-04T17:20:33+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:33 volumio go-librespot[3340]: time="2026-08-04T17:20:33+10: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" Aug 04 17:20:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:36 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:36 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754918. Aug 04 17:20:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:36 volumio go-librespot[3347]: go-librespot daemon starting... Aug 04 17:20:36 volumio go-librespot[3348]: time="2026-08-04T17:20:36+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:36 volumio go-librespot[3348]: time="2026-08-04T17:20:36+10:00" level=debug msg="app state loaded" Aug 04 17:20:36 volumio go-librespot[3348]: time="2026-08-04T17:20:36+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:36 volumio go-librespot[3348]: time="2026-08-04T17:20:36+10: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" Aug 04 17:20:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:39 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:39 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754919. Aug 04 17:20:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:39 volumio go-librespot[3368]: go-librespot daemon starting... Aug 04 17:20:39 volumio go-librespot[3369]: time="2026-08-04T17:20:39+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:39 volumio go-librespot[3369]: time="2026-08-04T17:20:39+10:00" level=debug msg="app state loaded" Aug 04 17:20:39 volumio go-librespot[3369]: time="2026-08-04T17:20:39+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:39 volumio go-librespot[3369]: time="2026-08-04T17:20:39+10: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" Aug 04 17:20:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:42 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:42 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754920. Aug 04 17:20:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:43 volumio go-librespot[3375]: go-librespot daemon starting... Aug 04 17:20:43 volumio go-librespot[3376]: time="2026-08-04T17:20:43+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:43 volumio go-librespot[3376]: time="2026-08-04T17:20:43+10:00" level=debug msg="app state loaded" Aug 04 17:20:43 volumio go-librespot[3376]: time="2026-08-04T17:20:43+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:43 volumio go-librespot[3376]: time="2026-08-04T17:20:43+10: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" Aug 04 17:20:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:45 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:45 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754921. Aug 04 17:20:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:46 volumio go-librespot[3384]: go-librespot daemon starting... Aug 04 17:20:46 volumio go-librespot[3385]: time="2026-08-04T17:20:46+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:46 volumio go-librespot[3385]: time="2026-08-04T17:20:46+10:00" level=debug msg="app state loaded" Aug 04 17:20:46 volumio go-librespot[3385]: time="2026-08-04T17:20:46+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:46 volumio go-librespot[3385]: time="2026-08-04T17:20:46+10: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" Aug 04 17:20:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:48 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:48 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754922. Aug 04 17:20:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:49 volumio go-librespot[3405]: go-librespot daemon starting... Aug 04 17:20:49 volumio go-librespot[3406]: time="2026-08-04T17:20:49+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:49 volumio go-librespot[3406]: time="2026-08-04T17:20:49+10:00" level=debug msg="app state loaded" Aug 04 17:20:49 volumio go-librespot[3406]: time="2026-08-04T17:20:49+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:49 volumio go-librespot[3406]: time="2026-08-04T17:20:49+10: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" Aug 04 17:20:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:51 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:51 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754923. Aug 04 17:20:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:52 volumio go-librespot[3413]: go-librespot daemon starting... Aug 04 17:20:52 volumio go-librespot[3414]: time="2026-08-04T17:20:52+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:52 volumio go-librespot[3414]: time="2026-08-04T17:20:52+10:00" level=debug msg="app state loaded" Aug 04 17:20:52 volumio go-librespot[3414]: time="2026-08-04T17:20:52+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:52 volumio go-librespot[3414]: time="2026-08-04T17:20:52+10: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" Aug 04 17:20:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:54 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:54 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754924. Aug 04 17:20:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:56 volumio go-librespot[3421]: go-librespot daemon starting... Aug 04 17:20:56 volumio go-librespot[3422]: time="2026-08-04T17:20:56+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:56 volumio go-librespot[3422]: time="2026-08-04T17:20:56+10:00" level=debug msg="app state loaded" Aug 04 17:20:56 volumio go-librespot[3422]: time="2026-08-04T17:20:56+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:56 volumio go-librespot[3422]: time="2026-08-04T17:20:56+10: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" Aug 04 17:20:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:20:57 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:20:57 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:20:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754925. Aug 04 17:20:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:20:59 volumio go-librespot[3442]: go-librespot daemon starting... Aug 04 17:20:59 volumio go-librespot[3443]: time="2026-08-04T17:20:59+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:20:59 volumio go-librespot[3443]: time="2026-08-04T17:20:59+10:00" level=debug msg="app state loaded" Aug 04 17:20:59 volumio go-librespot[3443]: time="2026-08-04T17:20:59+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:20:59 volumio go-librespot[3443]: time="2026-08-04T17:20:59+10: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" Aug 04 17:20:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:20:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:00 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:00 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754926. Aug 04 17:21:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:02 volumio go-librespot[3451]: go-librespot daemon starting... Aug 04 17:21:02 volumio go-librespot[3452]: time="2026-08-04T17:21:02+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:02 volumio go-librespot[3452]: time="2026-08-04T17:21:02+10:00" level=debug msg="app state loaded" Aug 04 17:21:02 volumio go-librespot[3452]: time="2026-08-04T17:21:02+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:02 volumio go-librespot[3452]: time="2026-08-04T17:21:02+10: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" Aug 04 17:21:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:03 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:03 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:05 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 04 17:21:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754927. Aug 04 17:21:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:05 volumio go-librespot[3479]: go-librespot daemon starting... Aug 04 17:21:05 volumio go-librespot[3480]: time="2026-08-04T17:21:05+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:05 volumio go-librespot[3480]: time="2026-08-04T17:21:05+10:00" level=debug msg="app state loaded" Aug 04 17:21:05 volumio go-librespot[3480]: time="2026-08-04T17:21:05+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:05 volumio go-librespot[3480]: time="2026-08-04T17:21:05+10: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" Aug 04 17:21:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:06 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:06 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754928. Aug 04 17:21:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:09 volumio go-librespot[3518]: go-librespot daemon starting... Aug 04 17:21:09 volumio go-librespot[3519]: time="2026-08-04T17:21:09+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:09 volumio go-librespot[3519]: time="2026-08-04T17:21:09+10:00" level=debug msg="app state loaded" Aug 04 17:21:09 volumio go-librespot[3519]: time="2026-08-04T17:21:09+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:09 volumio go-librespot[3519]: time="2026-08-04T17:21:09+10: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" Aug 04 17:21:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:09 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:09 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:10 volumio bash[3459]: setdatetime-helper: all HTTPS Date fallbacks failed Aug 04 17:21:10 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 04 17:21:10 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 04 17:21:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754929. Aug 04 17:21:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:12 volumio go-librespot[3532]: go-librespot daemon starting... Aug 04 17:21:12 volumio go-librespot[3533]: time="2026-08-04T17:21:12+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:12 volumio go-librespot[3533]: time="2026-08-04T17:21:12+10:00" level=debug msg="app state loaded" Aug 04 17:21:12 volumio go-librespot[3533]: time="2026-08-04T17:21:12+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:12 volumio go-librespot[3533]: time="2026-08-04T17:21:12+10: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" Aug 04 17:21:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:12 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:12 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754930. Aug 04 17:21:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:15 volumio go-librespot[3540]: go-librespot daemon starting... Aug 04 17:21:15 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:15 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:15 volumio go-librespot[3541]: time="2026-08-04T17:21:15+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:15 volumio go-librespot[3541]: time="2026-08-04T17:21:15+10:00" level=debug msg="app state loaded" Aug 04 17:21:15 volumio go-librespot[3541]: time="2026-08-04T17:21:15+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:15 volumio go-librespot[3541]: time="2026-08-04T17:21:15+10: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" Aug 04 17:21:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:18 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:18 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754931. Aug 04 17:21:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:18 volumio go-librespot[3563]: go-librespot daemon starting... Aug 04 17:21:18 volumio go-librespot[3564]: time="2026-08-04T17:21:18+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:18 volumio go-librespot[3564]: time="2026-08-04T17:21:18+10:00" level=debug msg="app state loaded" Aug 04 17:21:18 volumio go-librespot[3564]: time="2026-08-04T17:21:18+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:18 volumio go-librespot[3564]: time="2026-08-04T17:21:18+10: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" Aug 04 17:21:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:21 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:21 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754932. Aug 04 17:21:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:21 volumio go-librespot[3570]: go-librespot daemon starting... Aug 04 17:21:21 volumio go-librespot[3571]: time="2026-08-04T17:21:21+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:21 volumio go-librespot[3571]: time="2026-08-04T17:21:21+10:00" level=debug msg="app state loaded" Aug 04 17:21:21 volumio go-librespot[3571]: time="2026-08-04T17:21:21+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:21 volumio go-librespot[3571]: time="2026-08-04T17:21:21+10: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" Aug 04 17:21:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:24 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:24 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754933. Aug 04 17:21:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:25 volumio go-librespot[3580]: go-librespot daemon starting... Aug 04 17:21:25 volumio go-librespot[3581]: time="2026-08-04T17:21:25+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:25 volumio go-librespot[3581]: time="2026-08-04T17:21:25+10:00" level=debug msg="app state loaded" Aug 04 17:21:25 volumio go-librespot[3581]: time="2026-08-04T17:21:25+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:25 volumio go-librespot[3581]: time="2026-08-04T17:21:25+10: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" Aug 04 17:21:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:27 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:27 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754934. Aug 04 17:21:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:28 volumio go-librespot[3601]: go-librespot daemon starting... Aug 04 17:21:28 volumio go-librespot[3602]: time="2026-08-04T17:21:28+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:28 volumio go-librespot[3602]: time="2026-08-04T17:21:28+10:00" level=debug msg="app state loaded" Aug 04 17:21:28 volumio go-librespot[3602]: time="2026-08-04T17:21:28+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:28 volumio go-librespot[3602]: time="2026-08-04T17:21:28+10: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" Aug 04 17:21:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:30 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:30 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754935. Aug 04 17:21:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:31 volumio go-librespot[3608]: go-librespot daemon starting... Aug 04 17:21:31 volumio go-librespot[3609]: time="2026-08-04T17:21:31+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:31 volumio go-librespot[3609]: time="2026-08-04T17:21:31+10:00" level=debug msg="app state loaded" Aug 04 17:21:31 volumio go-librespot[3609]: time="2026-08-04T17:21:31+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:31 volumio go-librespot[3609]: time="2026-08-04T17:21:31+10: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" Aug 04 17:21:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:33 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:33 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754936. Aug 04 17:21:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:34 volumio go-librespot[3618]: go-librespot daemon starting... Aug 04 17:21:34 volumio go-librespot[3619]: time="2026-08-04T17:21:34+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:34 volumio go-librespot[3619]: time="2026-08-04T17:21:34+10:00" level=debug msg="app state loaded" Aug 04 17:21:34 volumio go-librespot[3619]: time="2026-08-04T17:21:34+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:34 volumio go-librespot[3619]: time="2026-08-04T17:21:34+10: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" Aug 04 17:21:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:36 volumio dhcpcd[1008]: eth0: carrier acquired Aug 04 17:21:36 volumio kernel: macb 1f00100000.ethernet eth0: Link is Up - 100Mbps/Full - flow control tx Aug 04 17:21:36 volumio dhcpcd[1008]: eth0: IAID dd:cf:5c:ae Aug 04 17:21:36 volumio dhcpcd[1008]: eth0: adding address fe80::6421:fc75:5aa4:eeb5 Aug 04 17:21:36 volumio dhcpcd[1008]: ipv6_addaddr1: Permission denied Aug 04 17:21:36 volumio ifplugd(eth0)[1175]: Link beat detected. Aug 04 17:21:36 volumio ifplugd(eth0)[1175]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Aug 04 17:21:36 volumio ifplugd(eth0)[1175]: client: sending commands to dhcpcd process Aug 04 17:21:36 volumio dhcpcd[1008]: control command: dhcpcd eth0 Aug 04 17:21:36 volumio dhcpcd[1008]: control_free: No such file or directory Aug 04 17:21:36 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:36 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:36 volumio dhcpcd[1008]: eth0: soliciting an IPv6 router Aug 04 17:21:36 volumio ifplugd(eth0)[1175]: Program executed successfully. Aug 04 17:21:36 volumio volumio[18395]: info: Received Get System Info Aug 04 17:21:36 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 04 17:21:36 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 04 17:21:36 volumio volumio[18395]: info: Discovery: Getting this device information Aug 04 17:21:36 volumio volumio[18395]: info: CoreCommandRouter::volumioGetState Aug 04 17:21:36 volumio volumio[18395]: info: CorePlayQueue::getTrack 0 Aug 04 17:21:36 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 04 17:21:36 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 04 17:21:36 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 04 17:21:37 volumio dhcpcd[1008]: eth0: soliciting a DHCP lease Aug 04 17:21:37 volumio wireless.js[999]: WIRELESS.JS - INFO: === SNM TRANSITION === Aug 04 17:21:37 volumio wireless.js[999]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Aug 04 17:21:37 volumio wireless.js[999]: WIRELESS.JS - INFO: New ethernet state: connected Aug 04 17:21:37 volumio wireless.js[999]: WIRELESS.JS - INFO: Single Network Mode: enabled Aug 04 17:21:37 volumio wireless.js[999]: WIRELESS.JS - INFO: First start: no Aug 04 17:21:37 volumio wireless.js[999]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Aug 04 17:21:37 volumio wireless.js[999]: WIRELESS.JS - INFO: === END TRANSITION === Aug 04 17:21:37 volumio wireless.js[999]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Aug 04 17:21:37 volumio sudo[3692]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Aug 04 17:21:37 volumio sudo[3692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 04 17:21:37 volumio dhcpcd[3693]: dhcpcd not running Aug 04 17:21:37 volumio sudo[3692]: pam_unix(sudo:session): session closed for user root Aug 04 17:21:37 volumio wireless.js[999]: dhcpcd not running Aug 04 17:21:37 volumio wireless.js[999]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Aug 04 17:21:37 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Aug 04 17:21:37 volumio dnsmasq[1798]: exiting on receipt of SIGTERM Aug 04 17:21:37 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Aug 04 17:21:37 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Aug 04 17:21:37 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Aug 04 17:21:37 volumio volumio5-onboarding[2181]: time=2026-08-04T17:21:37.775+10:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 04 17:21:38 volumio dhcpcd[1008]: wlan0: carrier lost Aug 04 17:21:38 volumio systemd[1]: hostapd.service: Deactivated successfully. Aug 04 17:21:38 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Aug 04 17:21:38 volumio systemd[1]: hostapd.service: Consumed 7.179s CPU time. Aug 04 17:21:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754937. Aug 04 17:21:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:38 volumio sudo[3719]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 04 17:21:38 volumio sudo[3719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 04 17:21:38 volumio avahi-daemon[984]: Withdrawing address record for 192.168.211.1 on wlan0. Aug 04 17:21:38 volumio avahi-daemon[984]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Aug 04 17:21:38 volumio avahi-daemon[984]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 04 17:21:38 volumio sudo[3719]: pam_unix(sudo:session): session closed for user root Aug 04 17:21:38 volumio volumio[18395]: info: Discovery: A device disappeared from network Aug 04 17:21:38 volumio sudo[3723]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 04 17:21:38 volumio sudo[3723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 04 17:21:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:38 volumio go-librespot[3717]: go-librespot daemon starting... Aug 04 17:21:38 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 04 17:21:38 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Aug 04 17:21:38 volumio systemd[1]: welcome.service: Deactivated successfully. Aug 04 17:21:38 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 04 17:21:38 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 04 17:21:38 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 04 17:21:38 volumio go-librespot[3726]: time="2026-08-04T17:21:38+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:38 volumio go-librespot[3726]: time="2026-08-04T17:21:38+10:00" level=debug msg="app state loaded" Aug 04 17:21:38 volumio welcome[3732]: Resolved ip:[0] Aug 04 17:21:38 volumio go-librespot[3726]: time="2026-08-04T17:21:38+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:38 volumio go-librespot[3726]: time="2026-08-04T17:21:38+10: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" Aug 04 17:21:38 volumio sudo[3723]: pam_unix(sudo:session): session closed for user root Aug 04 17:21:38 volumio wireless.js[999]: WIRELESS.JS - INFO: Cleaning previous... Aug 04 17:21:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:38 volumio volumio[18395]: info: Received Get System Info Aug 04 17:21:38 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 04 17:21:38 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 04 17:21:38 volumio volumio[18395]: info: Discovery: Getting this device information Aug 04 17:21:38 volumio volumio[18395]: info: CoreCommandRouter::volumioGetState Aug 04 17:21:38 volumio volumio[18395]: info: CorePlayQueue::getTrack 0 Aug 04 17:21:38 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 04 17:21:38 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 04 17:21:38 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 04 17:21:38 volumio sudo[3737]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 04 17:21:38 volumio sudo[3737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 04 17:21:38 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 04 17:21:38 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 04 17:21:38 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 04 17:21:38 volumio sudo[3737]: pam_unix(sudo:session): session closed for user root Aug 04 17:21:38 volumio wireless.js[999]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Aug 04 17:21:38 volumio wireless.js[999]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 0ms Aug 04 17:21:38 volumio wireless.js[999]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: d8:3a:dd:cf:5c:af) Aug 04 17:21:38 volumio sudo[3745]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 04 17:21:38 volumio sudo[3745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 04 17:21:38 volumio sudo[3745]: pam_unix(sudo:session): session closed for user root Aug 04 17:21:38 volumio sudo[3755]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 04 17:21:38 volumio sudo[3755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 04 17:21:39 volumio volumio[18395]: info: Received Get System Info Aug 04 17:21:39 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 04 17:21:39 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 04 17:21:39 volumio volumio[18395]: info: Discovery: Getting this device information Aug 04 17:21:39 volumio volumio[18395]: info: CoreCommandRouter::volumioGetState Aug 04 17:21:39 volumio volumio[18395]: info: CorePlayQueue::getTrack 0 Aug 04 17:21:39 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 04 17:21:39 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 04 17:21:39 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 04 17:21:39 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:39 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: AggregateError Aug 04 17:21:39 volumio ntpd[1155]: IO: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=2450528 secs Aug 04 17:21:40 volumio volumio5-onboarding[2181]: time=2026-08-04T17:21:40.609+10:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 04 17:21:41 volumio sudo[3755]: pam_unix(sudo:session): session closed for user root Aug 04 17:21:41 volumio wireless.js[999]: WIRELESS.JS - INFO: Regdomain already correct: AU Aug 04 17:21:41 volumio wireless.js[999]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Aug 04 17:21:41 volumio wireless.js[999]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Aug 04 17:21:41 volumio wireless.js[999]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Aug 04 17:21:41 volumio sudo[3764]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 04 17:21:41 volumio sudo[3764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 04 17:21:41 volumio sudo[3764]: pam_unix(sudo:session): session closed for user root Aug 04 17:21:41 volumio sudo[3767]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 04 17:21:41 volumio sudo[3767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 04 17:21:41 volumio sudo[3767]: pam_unix(sudo:session): session closed for user root Aug 04 17:21:41 volumio wpa_supplicant[3770]: Successfully initialized wpa_supplicant Aug 04 17:21:41 volumio wpa_supplicant[3770]: nl80211: kernel reports: Registration to specific type not supported Aug 04 17:21:41 volumio wpa_supplicant[3773]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Aug 04 17:21:41 volumio wireless.js[999]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 3785ms Aug 04 17:21:41 volumio wireless.js[999]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Aug 04 17:21:41 volumio wireless.js[999]: WIRELESS.JS - INFO: Notified systemd about wireless ready Aug 04 17:21:41 volumio volumio[18395]: info: Received Get System Info Aug 04 17:21:41 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 04 17:21:41 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 04 17:21:41 volumio volumio[18395]: info: Discovery: Getting this device information Aug 04 17:21:41 volumio volumio[18395]: info: CoreCommandRouter::volumioGetState Aug 04 17:21:41 volumio volumio[18395]: info: CorePlayQueue::getTrack 0 Aug 04 17:21:41 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 04 17:21:41 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 04 17:21:41 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 04 17:21:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754938. Aug 04 17:21:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:41 volumio go-librespot[3789]: go-librespot daemon starting... Aug 04 17:21:41 volumio go-librespot[3790]: time="2026-08-04T17:21:41+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:41 volumio go-librespot[3790]: time="2026-08-04T17:21:41+10:00" level=debug msg="app state loaded" Aug 04 17:21:41 volumio go-librespot[3790]: time="2026-08-04T17:21:41+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:41 volumio go-librespot[3790]: time="2026-08-04T17:21:41+10: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" Aug 04 17:21:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:42 volumio dhcpcd[1008]: eth0: offered 192.168.1.101 from 192.168.1.254 Aug 04 17:21:42 volumio dhcpcd[1008]: eth0: probing address 192.168.1.101/24 Aug 04 17:21:42 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:42 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: AggregateError Aug 04 17:21:42 volumio volumio5-onboarding[2181]: time=2026-08-04T17:21:42.628+10:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 04 17:21:44 volumio volumio[18395]: info: Received Get System Info Aug 04 17:21:44 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 04 17:21:44 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 04 17:21:44 volumio volumio[18395]: info: Discovery: Getting this device information Aug 04 17:21:44 volumio volumio[18395]: info: CoreCommandRouter::volumioGetState Aug 04 17:21:44 volumio volumio[18395]: info: CorePlayQueue::getTrack 0 Aug 04 17:21:44 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 04 17:21:44 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 04 17:21:44 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 04 17:21:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754939. Aug 04 17:21:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:44 volumio go-librespot[3797]: go-librespot daemon starting... Aug 04 17:21:44 volumio go-librespot[3798]: time="2026-08-04T17:21:44+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:44 volumio go-librespot[3798]: time="2026-08-04T17:21:44+10:00" level=debug msg="app state loaded" Aug 04 17:21:44 volumio go-librespot[3798]: time="2026-08-04T17:21:44+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:44 volumio go-librespot[3798]: time="2026-08-04T17:21:44+10: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" Aug 04 17:21:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:45 volumio volumio5-onboarding[2181]: time=2026-08-04T17:21:45.161+10:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 04 17:21:45 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:45 volumio volumio[18395]: info: Error connecting to go-librespot Websocket: AggregateError Aug 04 17:21:47 volumio dhcpcd[1008]: eth0: leased 192.168.1.101 for 86400 seconds Aug 04 17:21:47 volumio avahi-daemon[984]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.101. Aug 04 17:21:47 volumio avahi-daemon[984]: New relevant interface eth0.IPv4 for mDNS. Aug 04 17:21:47 volumio avahi-daemon[984]: Registering new address record for 192.168.1.101 on eth0.IPv4. Aug 04 17:21:47 volumio dhcpcd[1008]: eth0: adding route to 192.168.1.0/24 Aug 04 17:21:47 volumio dhcpcd[1008]: eth0: adding default route via 192.168.1.254 Aug 04 17:21:47 volumio systemd[1]: welcome.service: Deactivated successfully. Aug 04 17:21:47 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 04 17:21:47 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 04 17:21:47 volumio volumio[18395]: info: Volumio Network Manager: Network status updated: 1 Aug 04 17:21:47 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 04 17:21:47 volumio welcome[3824]: Resolved ip:[1] 192.168.1.101 Aug 04 17:21:47 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 04 17:21:47 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Aug 04 17:21:47 volumio volumio[18395]: info: Received Get System Info Aug 04 17:21:47 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 04 17:21:47 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 04 17:21:47 volumio volumio[18395]: info: Discovery: Getting this device information Aug 04 17:21:47 volumio volumio[18395]: info: CoreCommandRouter::volumioGetState Aug 04 17:21:47 volumio volumio[18395]: info: CorePlayQueue::getTrack 0 Aug 04 17:21:47 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 04 17:21:47 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 04 17:21:47 volumio volumio[18395]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 04 17:21:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 754940. Aug 04 17:21:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 04 17:21:48 volumio go-librespot[3844]: go-librespot daemon starting... Aug 04 17:21:48 volumio go-librespot[3845]: time="2026-08-04T17:21:48+10:00" level=info msg="running go-librespot 0.7.1" Aug 04 17:21:48 volumio go-librespot[3845]: time="2026-08-04T17:21:48+10:00" level=debug msg="app state loaded" Aug 04 17:21:48 volumio go-librespot[3845]: time="2026-08-04T17:21:48+10:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 04 17:21:48 volumio volumio[18395]: info: Discovery: adding 73a4639a-4efc-4865-a8ff-9d9f67b9fe1a Aug 04 17:21:48 volumio volumio[18395]: info: Discovery: Found device Volumio Aug 04 17:21:48 volumio volumio[18395]: info: CoreCommandRouter::volumioGetState Aug 04 17:21:48 volumio volumio[18395]: info: CorePlayQueue::getTrack 0 Aug 04 17:21:48 volumio volumio[18395]: info: Initializing connection to go-librespot Websocket Aug 04 17:21:48 volumio go-librespot[3845]: time="2026-08-04T17:21:48+10:00" level=debug msg="new websocket client" Aug 04 17:21:48 volumio volumio[18395]: info: Connection to go-librespot Websocket established Aug 04 17:21:48 volumio go-librespot[3845]: time="2026-08-04T17:21:48+10:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 04 17:21:48 volumio go-librespot[3845]: time="2026-08-04T17:21:48+10:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 04 17:21:48 volumio go-librespot[3845]: time="2026-08-04T17:21:48+10:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 04 17:21:48 volumio go-librespot[3845]: time="2026-08-04T17:21:48+10:00" level=info msg="zeroconf server listening on port 44411" Aug 04 17:21:48 volumio go-librespot[3845]: time="2026-08-04T17:21:48+10:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 04 17:21:48 volumio go-librespot[3845]: time="2026-08-04T17:21:48+10:00" level=debug msg="obtained new client token: AAEUvd6nfCmLMEZy02NDLot5C9s5bZDF26Mjb9ZO6AeKGFn7kw/qleUr7WRWHnGmB9XCPtSXCqQhCKLdOh9P8CunejJFaJyDlzOtK1tMQQ2I8zzgPF0VidD0Tes7xM6QImYbq6HOWqGMzoXZfO5v9o6v/YfEbXMEBl8mIgZZmpOJXqmVNGmL8ARq0IYjFYbfBSHKfoKlB6MGr3wmeUODeo+A3B4fe+oCIN+ugdNJgoMiPQV98OI3Wy3O9g==" Aug 04 17:21:48 volumio volumio5-onboarding[2181]: time=2026-08-04T17:21:48.751+10:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 04 17:21:48 volumio go-librespot[3845]: time="2026-08-04T17:21:48+10:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Aug 04 17:21:48 volumio ntpd[1155]: IO: Listen normally on 4 eth0 192.168.1.101:123 Aug 04 17:21:48 volumio ntpd[1155]: IO: new interface(s) found: waking up resolver Aug 04 17:21:48 volumio ntpd[1155]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 04 17:21:49 volumio go-librespot[3845]: time="2026-08-04T17:21:49+10:00" level=debug msg="connected to ap-gae2.spotify.com:443" Aug 04 17:21:49 volumio go-librespot[3845]: time="2026-08-04T17:21:49+10:00" level=debug msg="completed keyexchange" Aug 04 17:21:49 volumio go-librespot[3845]: time="2026-08-04T17:21:49+10:00" level=debug msg="completed challenge" Aug 04 17:21:49 volumio go-librespot[3845]: time="2026-08-04T17:21:49+10:00" level=info msg="authenticated AP" username="12******35" Aug 04 17:21:49 volumio ntpd[1155]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 202.142.133.141 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 162.159.200.1 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 44.32.200.122 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 44.32.200.123 Aug 04 17:21:49 volumio ntpd[1155]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Aug 04 17:21:49 volumio go-librespot[3845]: time="2026-08-04T17:21:49+10:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 04 17:21:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 04 17:21:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 04 17:21:49 volumio volumio[18395]: info: Connection to go-librespot Websocket closed Aug 04 17:21:49 volumio ntpd[1155]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 04 17:21:49 volumio ntpd[1155]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 203.29.241.127 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 27.100.36.46 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 159.196.194.81 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 119.18.6.37 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 2401:d002:3e03:1f01::123 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 2401:c080:1800:499c::1:123 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 2400:6180:10:200::de00:0 Aug 04 17:21:49 volumio ntpd[1155]: DNS: Pool taking: 2606:4700:f1::1 Aug 04 17:21:49 volumio ntpd[1155]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Aug 04 17:21:50 volumio ntpd[1155]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 04 17:21:50 volumio ntpd[1155]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Aug 04 17:21:50 volumio ntpd[1155]: DNS: Pool taking: 203.29.242.49 Aug 04 17:21:50 volumio ntpd[1155]: DNS: Pool taking: 27.124.125.250 Aug 04 17:21:50 volumio ntpd[1155]: DNS: Pool taking: 13.55.50.68 Aug 04 17:21:50 volumio ntpd[1155]: DNS: Pool taking: 43.224.181.207 Aug 04 17:21:50 volumio ntpd[1155]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Aug 04 17:21:51 volumio volumio[18395]: info: Getting Spotify volume Aug 04 17:21:51 volumio volumio[18395]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 04 17:21:51 volumio volumio[18395]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 04 17:21:51 volumio volumio[18395]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 04 17:21:51 volumio volumio[18395]: errno: -111, Aug 04 17:21:51 volumio volumio[18395]: code: 'ECONNREFUSED', Aug 04 17:21:51 volumio volumio[18395]: syscall: 'connect', Aug 04 17:21:51 volumio volumio[18395]: address: '127.0.0.1', Aug 04 17:21:51 volumio volumio[18395]: port: 9879, Aug 04 17:21:51 volumio volumio[18395]: response: undefined Aug 04 17:21:51 volumio volumio[18395]: } Aug 04 17:21:51 volumio volumio[18395]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 04 17:21:51 volumio sudo[3871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-04 17:20' Aug 04 17:21:51 volumio sudo[3871]: 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"