Jul 10 00:28:01 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jul 10 00:28:01 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:01 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:01 vardagsrumstereo go-librespot[1586]: go-librespot daemon starting... Jul 10 00:28:01 vardagsrumstereo go-librespot[1587]: time="2025-07-10T00:28:01+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:01 vardagsrumstereo go-librespot[1587]: time="2025-07-10T00:28:01+02:00" level=debug msg="app state loaded" Jul 10 00:28:01 vardagsrumstereo go-librespot[1587]: time="2025-07-10T00:28:01+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:01 vardagsrumstereo go-librespot[1587]: time="2025-07-10T00:28:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:01 vardagsrumstereo go-librespot[1587]: time="2025-07-10T00:28:01+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:01 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:01 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:01 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:02 vardagsrumstereo volumio[1125]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.015&uuid=3c57902c46d9bb6457055bc2d7e29a22" http://updates.volumio.org/downloader-v1/track-device Jul 10 00:28:02 vardagsrumstereo volumio[1125]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 10 00:28:02 vardagsrumstereo volumio[1125]: Dload Upload Total Spent Left Speed Jul 10 00:28:02 vardagsrumstereo volumio[1125]: [132B blob data] Jul 10 00:28:02 vardagsrumstereo volumio[1125]: retrying in 5 seconds, trial 1 Jul 10 00:28:02 vardagsrumstereo volumio[1125]: info: Volumio Calling Home Jul 10 00:28:02 vardagsrumstereo sudo[1597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=oddjob,password=lame,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.11.12.13/Pub/flac /mnt/NAS/Rolfen Jul 10 00:28:02 vardagsrumstereo sudo[1597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 10 00:28:02 vardagsrumstereo sudo[1597]: pam_unix(sudo:session): session closed for user root Jul 10 00:28:02 vardagsrumstereo kernel: CIFS: Attempting to mount //10.11.12.13/Pub/flac Jul 10 00:28:02 vardagsrumstereo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 10 00:28:02 vardagsrumstereo kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jul 10 00:28:03 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:03 vardagsrumstereo volumio[1125]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jul 10 00:28:04 vardagsrumstereo volumio[1125]: error: MyVolumio Plugin failed to start in a timely fashion Jul 10 00:28:04 vardagsrumstereo volumio[1125]: [Metrics] CommandRouter: 24s 903.53ms Jul 10 00:28:04 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::volumiosetStartupVolume Jul 10 00:28:04 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 00:28:04 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 00:28:04 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::Close All Modals sent Jul 10 00:28:04 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::Close All Modals sent Jul 10 00:28:04 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jul 10 00:28:04 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:04 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:04 vardagsrumstereo go-librespot[1604]: go-librespot daemon starting... Jul 10 00:28:04 vardagsrumstereo go-librespot[1605]: time="2025-07-10T00:28:04+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:04 vardagsrumstereo go-librespot[1605]: time="2025-07-10T00:28:04+02:00" level=debug msg="app state loaded" Jul 10 00:28:04 vardagsrumstereo go-librespot[1605]: time="2025-07-10T00:28:04+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:04 vardagsrumstereo go-librespot[1605]: time="2025-07-10T00:28:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:04 vardagsrumstereo go-librespot[1605]: time="2025-07-10T00:28:04+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:04 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:04 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:04 vardagsrumstereo systemd[1]: systemd-fsckd.service: Deactivated successfully. Jul 10 00:28:05 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 10 00:28:05 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 00:28:05 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 10 00:28:05 vardagsrumstereo dhcpcd[822]: timed out Jul 10 00:28:05 vardagsrumstereo sh[812]: timed out Jul 10 00:28:05 vardagsrumstereo sh[775]: ifup: failed to bring up eth0 Jul 10 00:28:05 vardagsrumstereo systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:05 vardagsrumstereo systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 10 00:28:06 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:06 vardagsrumstereo systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jul 10 00:28:06 vardagsrumstereo systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 10 00:28:07 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:07 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jul 10 00:28:07 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:07 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:07 vardagsrumstereo go-librespot[1620]: go-librespot daemon starting... Jul 10 00:28:07 vardagsrumstereo go-librespot[1621]: time="2025-07-10T00:28:07+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:07 vardagsrumstereo go-librespot[1621]: time="2025-07-10T00:28:07+02:00" level=debug msg="app state loaded" Jul 10 00:28:07 vardagsrumstereo go-librespot[1621]: time="2025-07-10T00:28:07+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:07 vardagsrumstereo go-librespot[1621]: time="2025-07-10T00:28:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:07 vardagsrumstereo go-librespot[1621]: time="2025-07-10T00:28:07+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:07 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:07 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:10 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:10 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jul 10 00:28:10 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:10 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:10 vardagsrumstereo go-librespot[1642]: go-librespot daemon starting... Jul 10 00:28:10 vardagsrumstereo go-librespot[1643]: time="2025-07-10T00:28:10+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:10 vardagsrumstereo go-librespot[1643]: time="2025-07-10T00:28:10+02:00" level=debug msg="app state loaded" Jul 10 00:28:10 vardagsrumstereo go-librespot[1643]: time="2025-07-10T00:28:10+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:10 vardagsrumstereo go-librespot[1643]: time="2025-07-10T00:28:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:10 vardagsrumstereo go-librespot[1643]: time="2025-07-10T00:28:10+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:10 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:10 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:11 vardagsrumstereo volumio[1125]: info: BOOT COMPLETED Jul 10 00:28:11 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:11 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 00:28:11 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 00:28:11 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 10 00:28:14 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Jul 10 00:28:14 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:14 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:14 vardagsrumstereo go-librespot[1652]: go-librespot daemon starting... Jul 10 00:28:14 vardagsrumstereo go-librespot[1653]: time="2025-07-10T00:28:14+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:14 vardagsrumstereo go-librespot[1653]: time="2025-07-10T00:28:14+02:00" level=debug msg="app state loaded" Jul 10 00:28:14 vardagsrumstereo go-librespot[1653]: time="2025-07-10T00:28:14+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:14 vardagsrumstereo go-librespot[1653]: time="2025-07-10T00:28:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:14 vardagsrumstereo go-librespot[1653]: time="2025-07-10T00:28:14+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:14 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:14 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:14 vardagsrumstereo volumio[1125]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.015&uuid=3c57902c46d9bb6457055bc2d7e29a22" http://updates.volumio.org/downloader-v1/track-device Jul 10 00:28:14 vardagsrumstereo volumio[1125]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 10 00:28:14 vardagsrumstereo volumio[1125]: Dload Upload Total Spent Left Speed Jul 10 00:28:14 vardagsrumstereo volumio[1125]: [132B blob data] Jul 10 00:28:14 vardagsrumstereo volumio[1125]: retrying in 5 seconds, trial 2 Jul 10 00:28:14 vardagsrumstereo volumio[1125]: info: Volumio Calling Home Jul 10 00:28:15 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:16 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:17 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Jul 10 00:28:17 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:17 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:17 vardagsrumstereo go-librespot[1662]: go-librespot daemon starting... Jul 10 00:28:17 vardagsrumstereo go-librespot[1663]: time="2025-07-10T00:28:17+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:17 vardagsrumstereo go-librespot[1663]: time="2025-07-10T00:28:17+02:00" level=debug msg="app state loaded" Jul 10 00:28:17 vardagsrumstereo go-librespot[1663]: time="2025-07-10T00:28:17+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:17 vardagsrumstereo go-librespot[1663]: time="2025-07-10T00:28:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:17 vardagsrumstereo go-librespot[1663]: time="2025-07-10T00:28:17+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:17 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:17 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:20 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:20 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Jul 10 00:28:20 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:20 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:20 vardagsrumstereo go-librespot[1683]: go-librespot daemon starting... Jul 10 00:28:20 vardagsrumstereo go-librespot[1684]: time="2025-07-10T00:28:20+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:20 vardagsrumstereo go-librespot[1684]: time="2025-07-10T00:28:20+02:00" level=debug msg="app state loaded" Jul 10 00:28:20 vardagsrumstereo go-librespot[1684]: time="2025-07-10T00:28:20+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:20 vardagsrumstereo go-librespot[1684]: time="2025-07-10T00:28:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:20 vardagsrumstereo go-librespot[1684]: time="2025-07-10T00:28:20+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:20 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:20 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:21 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:23 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Jul 10 00:28:23 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:23 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:23 vardagsrumstereo go-librespot[1690]: go-librespot daemon starting... Jul 10 00:28:23 vardagsrumstereo go-librespot[1691]: time="2025-07-10T00:28:23+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:23 vardagsrumstereo go-librespot[1691]: time="2025-07-10T00:28:23+02:00" level=debug msg="app state loaded" Jul 10 00:28:23 vardagsrumstereo go-librespot[1691]: time="2025-07-10T00:28:23+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:23 vardagsrumstereo go-librespot[1691]: time="2025-07-10T00:28:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:23 vardagsrumstereo go-librespot[1691]: time="2025-07-10T00:28:23+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:23 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:23 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:24 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:26 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:27 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Jul 10 00:28:27 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:27 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:27 vardagsrumstereo go-librespot[1697]: go-librespot daemon starting... Jul 10 00:28:27 vardagsrumstereo go-librespot[1698]: time="2025-07-10T00:28:27+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:27 vardagsrumstereo go-librespot[1698]: time="2025-07-10T00:28:27+02:00" level=debug msg="app state loaded" Jul 10 00:28:27 vardagsrumstereo go-librespot[1698]: time="2025-07-10T00:28:27+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:27 vardagsrumstereo go-librespot[1698]: time="2025-07-10T00:28:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:27 vardagsrumstereo go-librespot[1698]: time="2025-07-10T00:28:27+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:27 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:27 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:30 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:30 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Jul 10 00:28:30 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:30 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:30 vardagsrumstereo go-librespot[1719]: go-librespot daemon starting... Jul 10 00:28:30 vardagsrumstereo go-librespot[1720]: time="2025-07-10T00:28:30+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:30 vardagsrumstereo go-librespot[1720]: time="2025-07-10T00:28:30+02:00" level=debug msg="app state loaded" Jul 10 00:28:30 vardagsrumstereo go-librespot[1720]: time="2025-07-10T00:28:30+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:30 vardagsrumstereo go-librespot[1720]: time="2025-07-10T00:28:30+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:30 vardagsrumstereo go-librespot[1720]: time="2025-07-10T00:28:30+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:30 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:30 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:31 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:33 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Jul 10 00:28:33 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:33 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:33 vardagsrumstereo go-librespot[1727]: go-librespot daemon starting... Jul 10 00:28:33 vardagsrumstereo go-librespot[1728]: time="2025-07-10T00:28:33+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:33 vardagsrumstereo go-librespot[1728]: time="2025-07-10T00:28:33+02:00" level=debug msg="app state loaded" Jul 10 00:28:33 vardagsrumstereo go-librespot[1728]: time="2025-07-10T00:28:33+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:33 vardagsrumstereo go-librespot[1728]: time="2025-07-10T00:28:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:33 vardagsrumstereo go-librespot[1728]: time="2025-07-10T00:28:33+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:33 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:33 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:35 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:36 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Jul 10 00:28:36 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:36 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:36 vardagsrumstereo go-librespot[1734]: go-librespot daemon starting... Jul 10 00:28:36 vardagsrumstereo systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 10 00:28:36 vardagsrumstereo go-librespot[1735]: time="2025-07-10T00:28:36+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:36 vardagsrumstereo go-librespot[1735]: time="2025-07-10T00:28:36+02:00" level=debug msg="app state loaded" Jul 10 00:28:36 vardagsrumstereo go-librespot[1735]: time="2025-07-10T00:28:36+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:36 vardagsrumstereo go-librespot[1735]: time="2025-07-10T00:28:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:36 vardagsrumstereo go-librespot[1735]: time="2025-07-10T00:28:36+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:36 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:36 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:36 vardagsrumstereo dbus-daemon[887]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.21' (uid=0 pid=1742 comm="timedatectl show --property=NTPSynchronized --valu") Jul 10 00:28:36 vardagsrumstereo systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 10 00:28:36 vardagsrumstereo dbus-daemon[887]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 10 00:28:36 vardagsrumstereo systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 10 00:28:36 vardagsrumstereo setdatetime-helper.sh[1736]: Time is already synchronized. Jul 10 00:28:36 vardagsrumstereo systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 10 00:28:36 vardagsrumstereo systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 10 00:28:40 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Jul 10 00:28:40 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:40 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:40 vardagsrumstereo go-librespot[1759]: go-librespot daemon starting... Jul 10 00:28:40 vardagsrumstereo go-librespot[1760]: time="2025-07-10T00:28:40+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:40 vardagsrumstereo go-librespot[1760]: time="2025-07-10T00:28:40+02:00" level=debug msg="app state loaded" Jul 10 00:28:40 vardagsrumstereo go-librespot[1760]: time="2025-07-10T00:28:40+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:40 vardagsrumstereo go-librespot[1760]: time="2025-07-10T00:28:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:40 vardagsrumstereo go-librespot[1760]: time="2025-07-10T00:28:40+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:40 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:40 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:42 vardagsrumstereo volumio[1125]: Cannot compose Albumart path Jul 10 00:28:43 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Jul 10 00:28:43 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:43 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:43 vardagsrumstereo go-librespot[1766]: go-librespot daemon starting... Jul 10 00:28:43 vardagsrumstereo go-librespot[1767]: time="2025-07-10T00:28:43+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:43 vardagsrumstereo go-librespot[1767]: time="2025-07-10T00:28:43+02:00" level=debug msg="app state loaded" Jul 10 00:28:43 vardagsrumstereo go-librespot[1767]: time="2025-07-10T00:28:43+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:43 vardagsrumstereo go-librespot[1767]: time="2025-07-10T00:28:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:43 vardagsrumstereo go-librespot[1767]: time="2025-07-10T00:28:43+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:43 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:43 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:43 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:46 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Jul 10 00:28:46 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:46 vardagsrumstereo sudo[1774]: volumio : TTY=tty1 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl status wait-for-wifi-firmware Jul 10 00:28:46 vardagsrumstereo sudo[1774]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Jul 10 00:28:46 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:46 vardagsrumstereo go-librespot[1773]: go-librespot daemon starting... Jul 10 00:28:46 vardagsrumstereo go-librespot[1778]: time="2025-07-10T00:28:46+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:46 vardagsrumstereo go-librespot[1778]: time="2025-07-10T00:28:46+02:00" level=debug msg="app state loaded" Jul 10 00:28:46 vardagsrumstereo go-librespot[1778]: time="2025-07-10T00:28:46+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:46 vardagsrumstereo go-librespot[1778]: time="2025-07-10T00:28:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:46 vardagsrumstereo go-librespot[1778]: time="2025-07-10T00:28:46+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:46 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:46 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:46 vardagsrumstereo sudo[1774]: pam_unix(sudo:session): session closed for user root Jul 10 00:28:46 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:46 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:49 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:49 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:49 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Jul 10 00:28:49 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:49 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:49 vardagsrumstereo go-librespot[1784]: go-librespot daemon starting... Jul 10 00:28:49 vardagsrumstereo go-librespot[1785]: time="2025-07-10T00:28:49+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:49 vardagsrumstereo go-librespot[1785]: time="2025-07-10T00:28:49+02:00" level=debug msg="app state loaded" Jul 10 00:28:49 vardagsrumstereo go-librespot[1785]: time="2025-07-10T00:28:49+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:49 vardagsrumstereo go-librespot[1785]: time="2025-07-10T00:28:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:49 vardagsrumstereo go-librespot[1785]: time="2025-07-10T00:28:49+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:49 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:49 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:50 vardagsrumstereo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:50 vardagsrumstereo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 10 00:28:52 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:52 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:53 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Jul 10 00:28:53 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:53 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:53 vardagsrumstereo go-librespot[1805]: go-librespot daemon starting... Jul 10 00:28:53 vardagsrumstereo go-librespot[1806]: time="2025-07-10T00:28:53+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:53 vardagsrumstereo go-librespot[1806]: time="2025-07-10T00:28:53+02:00" level=debug msg="app state loaded" Jul 10 00:28:53 vardagsrumstereo go-librespot[1806]: time="2025-07-10T00:28:53+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:53 vardagsrumstereo go-librespot[1806]: time="2025-07-10T00:28:53+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:53 vardagsrumstereo go-librespot[1806]: time="2025-07-10T00:28:53+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:53 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:53 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:55 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:55 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:56 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Jul 10 00:28:56 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:56 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:56 vardagsrumstereo go-librespot[1812]: go-librespot daemon starting... Jul 10 00:28:56 vardagsrumstereo go-librespot[1813]: time="2025-07-10T00:28:56+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:56 vardagsrumstereo go-librespot[1813]: time="2025-07-10T00:28:56+02:00" level=debug msg="app state loaded" Jul 10 00:28:56 vardagsrumstereo go-librespot[1813]: time="2025-07-10T00:28:56+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:56 vardagsrumstereo go-librespot[1813]: time="2025-07-10T00:28:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:56 vardagsrumstereo go-librespot[1813]: time="2025-07-10T00:28:56+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:56 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:56 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:28:58 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:28:58 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:28:59 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Jul 10 00:28:59 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:59 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:28:59 vardagsrumstereo go-librespot[1819]: go-librespot daemon starting... Jul 10 00:28:59 vardagsrumstereo go-librespot[1820]: time="2025-07-10T00:28:59+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:28:59 vardagsrumstereo go-librespot[1820]: time="2025-07-10T00:28:59+02:00" level=debug msg="app state loaded" Jul 10 00:28:59 vardagsrumstereo go-librespot[1820]: time="2025-07-10T00:28:59+02:00" level=debug msg="stored credentials not found" Jul 10 00:28:59 vardagsrumstereo go-librespot[1820]: time="2025-07-10T00:28:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:28:59 vardagsrumstereo go-librespot[1820]: time="2025-07-10T00:28:59+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:28:59 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:28:59 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:29:01 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:29:01 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:29:02 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Jul 10 00:29:02 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:02 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:02 vardagsrumstereo go-librespot[1840]: go-librespot daemon starting... Jul 10 00:29:02 vardagsrumstereo go-librespot[1841]: time="2025-07-10T00:29:02+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:29:02 vardagsrumstereo go-librespot[1841]: time="2025-07-10T00:29:02+02:00" level=debug msg="app state loaded" Jul 10 00:29:02 vardagsrumstereo go-librespot[1841]: time="2025-07-10T00:29:02+02:00" level=debug msg="stored credentials not found" Jul 10 00:29:02 vardagsrumstereo go-librespot[1841]: time="2025-07-10T00:29:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:29:02 vardagsrumstereo go-librespot[1841]: time="2025-07-10T00:29:02+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:29:02 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:29:02 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:29:03 vardagsrumstereo sudo[1847]: volumio : TTY=tty1 ; PWD=/home/volumio ; USER=root ; COMMAND=/sbin/ifconfig Jul 10 00:29:03 vardagsrumstereo sudo[1847]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Jul 10 00:29:03 vardagsrumstereo sudo[1847]: pam_unix(sudo:session): session closed for user root Jul 10 00:29:04 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:29:04 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:29:06 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Jul 10 00:29:06 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:06 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:06 vardagsrumstereo go-librespot[1850]: go-librespot daemon starting... Jul 10 00:29:06 vardagsrumstereo go-librespot[1851]: time="2025-07-10T00:29:06+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:29:06 vardagsrumstereo go-librespot[1851]: time="2025-07-10T00:29:06+02:00" level=debug msg="app state loaded" Jul 10 00:29:06 vardagsrumstereo go-librespot[1851]: time="2025-07-10T00:29:06+02:00" level=debug msg="stored credentials not found" Jul 10 00:29:06 vardagsrumstereo go-librespot[1851]: time="2025-07-10T00:29:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:29:06 vardagsrumstereo go-librespot[1851]: time="2025-07-10T00:29:06+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:29:06 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:29:06 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:29:06 vardagsrumstereo systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 10 00:29:07 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:29:07 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:29:09 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Jul 10 00:29:09 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:09 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:09 vardagsrumstereo go-librespot[1859]: go-librespot daemon starting... Jul 10 00:29:09 vardagsrumstereo go-librespot[1860]: time="2025-07-10T00:29:09+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:29:09 vardagsrumstereo go-librespot[1860]: time="2025-07-10T00:29:09+02:00" level=debug msg="app state loaded" Jul 10 00:29:09 vardagsrumstereo go-librespot[1860]: time="2025-07-10T00:29:09+02:00" level=debug msg="stored credentials not found" Jul 10 00:29:09 vardagsrumstereo go-librespot[1860]: time="2025-07-10T00:29:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:29:09 vardagsrumstereo go-librespot[1860]: time="2025-07-10T00:29:09+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:29:09 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:29:09 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:29:10 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:29:10 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:29:12 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Jul 10 00:29:12 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:12 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:12 vardagsrumstereo go-librespot[1880]: go-librespot daemon starting... Jul 10 00:29:12 vardagsrumstereo go-librespot[1881]: time="2025-07-10T00:29:12+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:29:12 vardagsrumstereo go-librespot[1881]: time="2025-07-10T00:29:12+02:00" level=debug msg="app state loaded" Jul 10 00:29:12 vardagsrumstereo go-librespot[1881]: time="2025-07-10T00:29:12+02:00" level=debug msg="stored credentials not found" Jul 10 00:29:12 vardagsrumstereo go-librespot[1881]: time="2025-07-10T00:29:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:29:12 vardagsrumstereo go-librespot[1881]: time="2025-07-10T00:29:12+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:29:12 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:29:12 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:29:13 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:29:13 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:29:15 vardagsrumstereo dhcpcd[923]: eth0: carrier acquired Jul 10 00:29:15 vardagsrumstereo kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Jul 10 00:29:15 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Jul 10 00:29:15 vardagsrumstereo dhcpcd[923]: eth0: IAID 67:00:b0:91 Jul 10 00:29:15 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:15 vardagsrumstereo dhcpcd[923]: eth0: adding address fe80::2ecf:67ff:fe00:b091 Jul 10 00:29:15 vardagsrumstereo dhcpcd[923]: ipv6_addaddr1: Permission denied Jul 10 00:29:15 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:15 vardagsrumstereo go-librespot[1888]: go-librespot daemon starting... Jul 10 00:29:15 vardagsrumstereo go-librespot[1889]: time="2025-07-10T00:29:15+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:29:15 vardagsrumstereo go-librespot[1889]: time="2025-07-10T00:29:15+02:00" level=debug msg="app state loaded" Jul 10 00:29:15 vardagsrumstereo go-librespot[1889]: time="2025-07-10T00:29:15+02:00" level=debug msg="stored credentials not found" Jul 10 00:29:15 vardagsrumstereo go-librespot[1889]: time="2025-07-10T00:29:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:29:15 vardagsrumstereo go-librespot[1889]: time="2025-07-10T00:29:15+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:29:15 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:29:15 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:29:16 vardagsrumstereo ifplugd(eth0)[1063]: Link beat detected. Jul 10 00:29:16 vardagsrumstereo ifplugd(eth0)[1063]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 10 00:29:16 vardagsrumstereo ifplugd(eth0)[1063]: client: sending commands to dhcpcd process Jul 10 00:29:16 vardagsrumstereo dhcpcd[923]: control command: dhcpcd eth0 Jul 10 00:29:16 vardagsrumstereo dhcpcd[923]: control_free: No such file or directory Jul 10 00:29:16 vardagsrumstereo ifplugd(eth0)[1063]: Program executed successfully. Jul 10 00:29:16 vardagsrumstereo dhcpcd[923]: eth0: soliciting an IPv6 router Jul 10 00:29:16 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:29:16 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:29:17 vardagsrumstereo dhcpcd[923]: eth0: rebinding lease of 192.168.5.210 Jul 10 00:29:19 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Jul 10 00:29:19 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:19 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:19 vardagsrumstereo go-librespot[1943]: go-librespot daemon starting... Jul 10 00:29:19 vardagsrumstereo go-librespot[1944]: time="2025-07-10T00:29:19+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:29:19 vardagsrumstereo go-librespot[1944]: time="2025-07-10T00:29:19+02:00" level=debug msg="app state loaded" Jul 10 00:29:19 vardagsrumstereo go-librespot[1944]: time="2025-07-10T00:29:19+02:00" level=debug msg="stored credentials not found" Jul 10 00:29:19 vardagsrumstereo go-librespot[1944]: time="2025-07-10T00:29:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:29:19 vardagsrumstereo go-librespot[1944]: time="2025-07-10T00:29:19+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:29:19 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:29:19 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:29:19 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:29:19 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:29:21 vardagsrumstereo dhcpcd[923]: eth0: probing address 192.168.5.210/24 Jul 10 00:29:22 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Jul 10 00:29:22 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:22 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:22 vardagsrumstereo go-librespot[1964]: go-librespot daemon starting... Jul 10 00:29:22 vardagsrumstereo go-librespot[1965]: time="2025-07-10T00:29:22+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:29:22 vardagsrumstereo go-librespot[1965]: time="2025-07-10T00:29:22+02:00" level=debug msg="app state loaded" Jul 10 00:29:22 vardagsrumstereo go-librespot[1965]: time="2025-07-10T00:29:22+02:00" level=debug msg="stored credentials not found" Jul 10 00:29:22 vardagsrumstereo go-librespot[1965]: time="2025-07-10T00:29:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:29:22 vardagsrumstereo go-librespot[1965]: time="2025-07-10T00:29:22+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:29:22 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:29:22 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:29:22 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:29:22 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:29:25 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Jul 10 00:29:25 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:25 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:25 vardagsrumstereo go-librespot[1971]: go-librespot daemon starting... Jul 10 00:29:25 vardagsrumstereo go-librespot[1972]: time="2025-07-10T00:29:25+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:29:25 vardagsrumstereo go-librespot[1972]: time="2025-07-10T00:29:25+02:00" level=debug msg="app state loaded" Jul 10 00:29:25 vardagsrumstereo go-librespot[1972]: time="2025-07-10T00:29:25+02:00" level=debug msg="stored credentials not found" Jul 10 00:29:25 vardagsrumstereo go-librespot[1972]: time="2025-07-10T00:29:25+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:29:25 vardagsrumstereo go-librespot[1972]: time="2025-07-10T00:29:25+02:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 10 00:29:25 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 10 00:29:25 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 00:29:25 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:29:25 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:29:26 vardagsrumstereo dhcpcd[923]: eth0: leased 192.168.5.210 for 4000 seconds Jul 10 00:29:26 vardagsrumstereo avahi-daemon[885]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.5.210. Jul 10 00:29:26 vardagsrumstereo avahi-daemon[885]: New relevant interface eth0.IPv4 for mDNS. Jul 10 00:29:26 vardagsrumstereo avahi-daemon[885]: Registering new address record for 192.168.5.210 on eth0.IPv4. Jul 10 00:29:26 vardagsrumstereo dhcpcd[923]: eth0: adding route to 192.168.5.0/24 Jul 10 00:29:26 vardagsrumstereo dhcpcd[923]: eth0: adding default route via 192.168.5.10 Jul 10 00:29:26 vardagsrumstereo systemd[1]: welcome.service: Deactivated successfully. Jul 10 00:29:26 vardagsrumstereo systemd[1]: Stopped welcome.service - Show a welcome message on console. Jul 10 00:29:26 vardagsrumstereo systemd[1]: Stopping welcome.service - Show a welcome message on console... Jul 10 00:29:26 vardagsrumstereo volumio[1125]: verbose: New Socket.io Connection to 192.168.5.210 from 192.168.5.188 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::volumioGetState Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: CorePlayQueue::getTrack 0 Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::volumioGetQueue Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: CoreStateMachine::getQueue Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: CorePlayQueue::getQueue Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jul 10 00:29:26 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jul 10 00:29:26 vardagsrumstereo systemd[1]: Starting welcome.service - Show a welcome message on console... Jul 10 00:29:26 vardagsrumstereo welcome[1997]: Resolved ip:[1] 192.168.5.210 Jul 10 00:29:26 vardagsrumstereo systemd[1]: Finished welcome.service - Show a welcome message on console. Jul 10 00:29:26 vardagsrumstereo systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jul 10 00:29:27 vardagsrumstereo volumio[1125]: info: Discovery: this is already registered, a36d1994-eb36-44b2-b2f7-422f76076a96 Jul 10 00:29:27 vardagsrumstereo volumio[1125]: info: Discovery: Found device vardagsrumstereo Jul 10 00:29:27 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::volumioGetState Jul 10 00:29:27 vardagsrumstereo volumio[1125]: info: CorePlayQueue::getTrack 0 Jul 10 00:29:28 vardagsrumstereo ntpd[1050]: IO: Listen normally on 3 eth0 192.168.5.210:123 Jul 10 00:29:28 vardagsrumstereo ntpd[1050]: IO: new interface(s) found: waking up resolver Jul 10 00:29:28 vardagsrumstereo ntpd[1050]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 10 00:29:28 vardagsrumstereo ntpd[1050]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jul 10 00:29:28 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 192.36.143.130 Jul 10 00:29:28 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 98.128.175.45 Jul 10 00:29:28 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 13.51.141.188 Jul 10 00:29:28 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 176.126.86.247 Jul 10 00:29:28 vardagsrumstereo ntpd[1050]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jul 10 00:29:28 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:29:28 vardagsrumstereo volumio[1125]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 00:29:28 vardagsrumstereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Jul 10 00:29:28 vardagsrumstereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:28 vardagsrumstereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 10 00:29:28 vardagsrumstereo go-librespot[2004]: go-librespot daemon starting... Jul 10 00:29:28 vardagsrumstereo go-librespot[2005]: time="2025-07-10T00:29:28+02:00" level=info msg="running go-librespot 0.2.0" Jul 10 00:29:28 vardagsrumstereo go-librespot[2005]: time="2025-07-10T00:29:28+02:00" level=debug msg="app state loaded" Jul 10 00:29:28 vardagsrumstereo go-librespot[2005]: time="2025-07-10T00:29:28+02:00" level=debug msg="stored credentials not found" Jul 10 00:29:28 vardagsrumstereo go-librespot[2005]: time="2025-07-10T00:29:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 10 00:29:28 vardagsrumstereo go-librespot[2005]: time="2025-07-10T00:29:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 10 00:29:28 vardagsrumstereo go-librespot[2005]: time="2025-07-10T00:29:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jul 10 00:29:28 vardagsrumstereo go-librespot[2005]: time="2025-07-10T00:29:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jul 10 00:29:28 vardagsrumstereo go-librespot[2005]: time="2025-07-10T00:29:28+02:00" level=info msg="zeroconf server listening on port 44477" Jul 10 00:29:29 vardagsrumstereo ntpd[1050]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 10 00:29:29 vardagsrumstereo ntpd[1050]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jul 10 00:29:29 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 192.36.143.134 Jul 10 00:29:29 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 193.182.111.12 Jul 10 00:29:29 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 194.58.206.20 Jul 10 00:29:29 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 194.58.204.148 Jul 10 00:29:29 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 2606:4700:f1::1 Jul 10 00:29:29 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 2a0b:4140:ec4f::2 Jul 10 00:29:29 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 2001:9b1:9bd0::aa Jul 10 00:29:29 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 2a03:8600::ff Jul 10 00:29:29 vardagsrumstereo ntpd[1050]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jul 10 00:29:30 vardagsrumstereo volumio[1125]: info: Volumio Network Manager: Network status updated: 1 Jul 10 00:29:30 vardagsrumstereo ntpd[1050]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 10 00:29:30 vardagsrumstereo ntpd[1050]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jul 10 00:29:30 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 193.182.111.142 Jul 10 00:29:30 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 194.58.205.148 Jul 10 00:29:30 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 194.58.202.148 Jul 10 00:29:30 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 162.159.200.123 Jul 10 00:29:30 vardagsrumstereo ntpd[1050]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jul 10 00:29:31 vardagsrumstereo ntpd[1050]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jul 10 00:29:31 vardagsrumstereo ntpd[1050]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jul 10 00:29:31 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 193.182.111.13 Jul 10 00:29:31 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 194.58.206.148 Jul 10 00:29:31 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 85.24.237.72 Jul 10 00:29:31 vardagsrumstereo ntpd[1050]: DNS: Pool taking: 147.78.228.41 Jul 10 00:29:31 vardagsrumstereo ntpd[1050]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: Initializing connection to go-librespot Websocket Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: Connection to go-librespot Websocket established Jul 10 00:29:31 vardagsrumstereo go-librespot[2005]: time="2025-07-10T00:29:31+02:00" level=debug msg="new websocket client" Jul 10 00:29:31 vardagsrumstereo volumio[1125]: verbose: New Socket.io Connection to 192.168.5.210 from 192.168.5.188 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::volumioGetState Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: CorePlayQueue::getTrack 0 Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::volumioGetQueue Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: CoreStateMachine::getQueue Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: CorePlayQueue::getQueue Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jul 10 00:29:31 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: Adding plugin bluetooth to MyMusic Plugins Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: Adding plugin multiroom to MyMusic Plugins Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: Adding plugin metavolumio to MyMusic Plugins Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: Adding plugin cd_controller to MyMusic Plugins Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: Starting MyVolumio Remote Streaming Endpoints Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: MyVolumio login type: Token Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 10 00:29:32 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 10 00:29:33 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 10 00:29:33 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 10 00:29:33 vardagsrumstereo volumio[1125]: info: Streaming services startup Jul 10 00:29:33 vardagsrumstereo volumio[1125]: info: Starting Streaming Daemon Jul 10 00:29:33 vardagsrumstereo sudo[2030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 10 00:29:33 vardagsrumstereo sudo[2030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 10 00:29:33 vardagsrumstereo volumio[1125]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 10 00:29:33 vardagsrumstereo sudo[2030]: pam_unix(sudo:session): session closed for user root Jul 10 00:29:33 vardagsrumstereo volumio[1125]: error: Cannot start Volumio Streaming Daemon Jul 10 00:29:33 vardagsrumstereo volumio[1125]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 10 00:29:33 vardagsrumstereo volumio[1125]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 10 00:29:33 vardagsrumstereo volumio[1125]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 10 00:29:33 vardagsrumstereo volumio[1125]: info: MyVolumio token set successfully Jul 10 00:29:33 vardagsrumstereo volumio[1125]: info: MYVOLUMIO: Adding device Jul 10 00:29:33 vardagsrumstereo volumio[1125]: info: MYVOLUMIO: Evaluating Server Jul 10 00:29:34 vardagsrumstereo volumio[1125]: info: MyVolumio status changed Jul 10 00:29:34 vardagsrumstereo volumio[1125]: info: Streaming services startup Jul 10 00:29:34 vardagsrumstereo volumio[1125]: info: Starting Streaming Daemon Jul 10 00:29:34 vardagsrumstereo volumio[1125]: info: Removing browser output: myVolumio user plan is not superstar Jul 10 00:29:34 vardagsrumstereo volumio[1125]: info: Removing audio output: Jul 10 00:29:34 vardagsrumstereo volumio[1125]: info: Starting Tunnel 2 Jul 10 00:29:34 vardagsrumstereo sudo[2054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 10 00:29:34 vardagsrumstereo sudo[2054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 10 00:29:34 vardagsrumstereo sudo[2056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop tunnel.service Jul 10 00:29:34 vardagsrumstereo sudo[2056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 10 00:29:34 vardagsrumstereo sudo[2054]: pam_unix(sudo:session): session closed for user root Jul 10 00:29:34 vardagsrumstereo volumio[1125]: error: Cannot start Volumio Streaming Daemon Jul 10 00:29:34 vardagsrumstereo volumio[1125]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 10 00:29:34 vardagsrumstereo volumio[1125]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 10 00:29:34 vardagsrumstereo sudo[2056]: pam_unix(sudo:session): session closed for user root Jul 10 00:29:34 vardagsrumstereo volumio[1125]: error: Cannot stop Remote Daemon: Error: Command failed: /usr/bin/sudo /bin/systemctl stop tunnel.service Jul 10 00:29:34 vardagsrumstereo volumio[1125]: Failed to stop tunnel.service: Unit tunnel.service not loaded. Jul 10 00:29:33 vardagsrumstereo ntpd[1050]: CLOCK: time stepped by -0.348541 Jul 10 00:29:33 vardagsrumstereo systemd-journald[433]: Time jumped backwards, rotating. Jul 10 00:29:33 vardagsrumstereo ntpd[1050]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jul 10 00:29:33 vardagsrumstereo volumio[1125]: error: Failed to ping endpoint us3.myvolumio.org : unknown error Jul 10 00:29:33 vardagsrumstereo volumio[1125]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 00:29:33 vardagsrumstereo volumio[1125]: Error: Unable to resolve or reject the same promise twice Jul 10 00:29:33 vardagsrumstereo volumio[1125]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 10 00:29:33 vardagsrumstereo volumio[1125]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jul 10 00:29:33 vardagsrumstereo volumio[1125]: at Socket.emit (node:events:514:28) Jul 10 00:29:33 vardagsrumstereo volumio[1125]: at addChunk (node:internal/streams/readable:343:12) Jul 10 00:29:33 vardagsrumstereo volumio[1125]: at readableAddChunk (node:internal/streams/readable:316:9) Jul 10 00:29:33 vardagsrumstereo volumio[1125]: at Readable.push (node:internal/streams/readable:253:10) Jul 10 00:29:33 vardagsrumstereo volumio[1125]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jul 10 00:29:33 vardagsrumstereo volumio[1125]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 00:29:34 vardagsrumstereo sudo[2074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-10 00:28' Jul 10 00:29:34 vardagsrumstereo sudo[2074]: 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="4f980d5cf693b4272997a2fa2da8bb8036ddf1ab" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 5 02:50:33 UTC 2025" VOLUMIO_VERSION="4.015" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d42ab79cc13d24482826e04fda2e8350"