-- Logs begin at Sun 2026-05-31 19:49:28 EDT, end at Tue 2026-06-02 07:00:16 EDT. -- Jun 02 06:59:01 amp go-librespot[28914]: time="2026-06-02T06:59:01-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: i/o timeout" Jun 02 06:59:07 amp volumio[12308]: info: [squeezelite_mc] Server lost: {"ip":"192.168.1.205","name":"amp","ver":"8.3.1","uuid":"14ade454-80a0-4dc7-a8a7-218ff4162d3b","jsonPort":"9000","cliPort":"9090"} Jun 02 06:59:07 amp volumio[12308]: info: [squeezelite_mc] Server discovered: {"ip":"169.254.47.209","name":"amp","ver":"8.3.1","uuid":"14ade454-80a0-4dc7-a8a7-218ff4162d3b","jsonPort":"9000","cliPort":"9090"} Jun 02 06:59:07 amp volumio[12308]: info: [squeezelite_mc] Server lost: {"ip":"169.254.47.209","name":"amp","ver":"8.3.1","uuid":"14ade454-80a0-4dc7-a8a7-218ff4162d3b","jsonPort":"9000","cliPort":"9090"} Jun 02 06:59:07 amp volumio[12308]: info: [squeezelite_mc] Server lost: {"ip":"169.254.47.209","name":"amp","ver":"8.3.1","uuid":"14ade454-80a0-4dc7-a8a7-218ff4162d3b","jsonPort":"9000","cliPort":"9090"} Jun 02 06:59:07 amp volumio[12308]: error: [squeezelite_mc] Failed to start notification listener on amp (169.254.47.209): Connection terminated possibly due to missing credentials on password-protected server. Error: Connection terminated possibly due to missing credentials on password-protected server. Jun 02 06:59:07 amp volumio[12308]: at Telnet.clientCloseHandler (/data/plugins/music_service/squeezelite_mc/node_modules/lms-cli-notifications/dist/cjs/NotificationListener.js:228:32) Jun 02 06:59:07 amp volumio[12308]: at Object.onceWrapper (events.js:421:28) Jun 02 06:59:07 amp volumio[12308]: at Telnet.emit (events.js:327:22) Jun 02 06:59:07 amp volumio[12308]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:173:22) Jun 02 06:59:07 amp volumio[12308]: at Socket.emit (events.js:315:20) Jun 02 06:59:07 amp volumio[12308]: at TCP. (net.js:673:12) Jun 02 06:59:07 amp volumio[12308]: error: [squeezelite_mc] An error occurred while processing discovered server: Connection terminated possibly due to missing credentials on password-protected server. Error: Connection terminated possibly due to missing credentials on password-protected server. Jun 02 06:59:07 amp volumio[12308]: at Telnet.clientCloseHandler (/data/plugins/music_service/squeezelite_mc/node_modules/lms-cli-notifications/dist/cjs/NotificationListener.js:228:32) Jun 02 06:59:07 amp volumio[12308]: at Object.onceWrapper (events.js:421:28) Jun 02 06:59:07 amp volumio[12308]: at Telnet.emit (events.js:327:22) Jun 02 06:59:07 amp volumio[12308]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:173:22) Jun 02 06:59:07 amp volumio[12308]: at Socket.emit (events.js:315:20) Jun 02 06:59:07 amp volumio[12308]: at TCP. (net.js:673:12) Jun 02 06:59:09 amp go-librespot[28914]: time="2026-06-02T06:59:09-04:00" level=error msg="did not receive last pong from dealer, 600s passed" Jun 02 06:59:13 amp go-librespot[28914]: time="2026-06-02T06:59:13-04:00" level=debug msg="renewing login5 access token" Jun 02 06:59:23 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 02 06:59:24 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 02 06:59:24 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 02 06:59:24 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 02 06:59:24 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Jun 02 06:59:24 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Jun 02 06:59:24 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Jun 02 06:59:24 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Jun 02 06:59:24 amp wpa_supplicant[921]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="NiceWillow" Jun 02 06:59:24 amp wpa_supplicant[921]: wlan0: Trying to associate with SSID 'NiceWillow' Jun 02 06:59:24 amp volumio[12308]: info: Received Get System Info Jun 02 06:59:24 amp volumio[12308]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 06:59:24 amp volumio[12308]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 06:59:24 amp dhcpcd[1044]: wlan0: carrier lost Jun 02 06:59:24 amp volumio[12308]: info: Discovery: Getting this device information Jun 02 06:59:24 amp volumio[12308]: info: CoreCommandRouter::volumioGetState Jun 02 06:59:24 amp volumio[12308]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:24 amp volumio[12308]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 06:59:24 amp volumio[12308]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 02 06:59:24 amp volumio[12308]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 02 06:59:25 amp avahi-daemon[625]: Withdrawing address record for 169.254.47.209 on wlan0. Jun 02 06:59:25 amp avahi-daemon[625]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.47.209. Jun 02 06:59:25 amp dhcpcd[1044]: wlan0: deleting route to 169.254.0.0/16 Jun 02 06:59:25 amp dhcpcd[1044]: wlan0: deleting default route Jun 02 06:59:25 amp avahi-daemon[625]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 02 06:59:25 amp volumio[12308]: info: Discovery: A device disappeared from network Jun 02 06:59:25 amp volumio[12308]: info: Received Get System Info Jun 02 06:59:25 amp volumio[12308]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 06:59:25 amp volumio[12308]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 06:59:25 amp volumio[12308]: info: Discovery: Getting this device information Jun 02 06:59:25 amp volumio[12308]: info: CoreCommandRouter::volumioGetState Jun 02 06:59:25 amp volumio[12308]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:25 amp volumio[12308]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 06:59:25 amp volumio[12308]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 02 06:59:25 amp volumio[12308]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 02 06:59:26 amp ntpd[981]: Deleting interface #66 wlan0, 169.254.47.209#123, interface stats: received=0, sent=0, dropped=0, active_time=594 secs Jun 02 06:59:26 amp volumio5-onboarding[1271]: time=2026-06-02T06:59:26.864-04:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 02 06:59:29 amp go-librespot[28914]: time="2026-06-02T06:59:29-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:29 amp go-librespot[28914]: time="2026-06-02T06:59:29-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:29 amp go-librespot[28914]: time="2026-06-02T06:59:29-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:29 amp go-librespot[28914]: time="2026-06-02T06:59:29-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:29 amp go-librespot[28914]: time="2026-06-02T06:59:29-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:29 amp go-librespot[28914]: time="2026-06-02T06:59:29-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:29 amp go-librespot[28914]: time="2026-06-02T06:59:29-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:29 amp go-librespot[28914]: time="2026-06-02T06:59:29-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:29 amp go-librespot[28914]: time="2026-06-02T06:59:29-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:31 amp wpa_supplicant[921]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=c4:a8:16:cc:b2:06 status_code=16 Jun 02 06:59:31 amp wpa_supplicant[921]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="NiceWillow" auth_failures=253 duration=548 reason=CONN_FAILED Jun 02 06:59:31 amp go-librespot[28914]: time="2026-06-02T06:59:31-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:31 amp go-librespot[28914]: time="2026-06-02T06:59:31-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:31 amp go-librespot[28914]: time="2026-06-02T06:59:31-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:31 amp go-librespot[28914]: time="2026-06-02T06:59:31-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:31 amp go-librespot[28914]: time="2026-06-02T06:59:31-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:31 amp go-librespot[28914]: time="2026-06-02T06:59:31-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:31 amp go-librespot[28914]: time="2026-06-02T06:59:31-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:31 amp go-librespot[28914]: time="2026-06-02T06:59:31-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:31 amp go-librespot[28914]: time="2026-06-02T06:59:31-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:31 amp go-librespot[28914]: time="2026-06-02T06:59:31-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:31 amp go-librespot[28914]: time="2026-06-02T06:59:31-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:32 amp volumio[12308]: info: Volumio Network Manager: Network status updated: 1 Jun 02 06:59:34 amp go-librespot[28914]: time="2026-06-02T06:59:34-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:34 amp go-librespot[28914]: time="2026-06-02T06:59:34-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:34 amp go-librespot[28914]: time="2026-06-02T06:59:34-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:34 amp go-librespot[28914]: time="2026-06-02T06:59:34-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:34 amp go-librespot[28914]: time="2026-06-02T06:59:34-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:34 amp go-librespot[28914]: time="2026-06-02T06:59:34-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:34 amp go-librespot[28914]: time="2026-06-02T06:59:34-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:34 amp go-librespot[28914]: time="2026-06-02T06:59:34-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:34 amp go-librespot[28914]: time="2026-06-02T06:59:34-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:34 amp go-librespot[28914]: time="2026-06-02T06:59:34-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:34 amp go-librespot[28914]: time="2026-06-02T06:59:34-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:37 amp volumio[12308]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 06:59:37 amp volumio[12308]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 02 06:59:37 amp volumio[12308]: at doSend (dgram.js:692:16) Jun 02 06:59:37 amp volumio[12308]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 02 06:59:37 amp volumio[12308]: at afterDns (dgram.js:638:5) Jun 02 06:59:37 amp volumio[12308]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 02 06:59:37 amp volumio[12308]: errno: -101, Jun 02 06:59:37 amp volumio[12308]: code: 'ENETUNREACH', Jun 02 06:59:37 amp volumio[12308]: syscall: 'send', Jun 02 06:59:37 amp volumio[12308]: address: '255.255.255.255', Jun 02 06:59:37 amp volumio[12308]: port: 3483 Jun 02 06:59:37 amp volumio[12308]: } Jun 02 06:59:37 amp volumio[12308]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 06:59:37 amp sudo[13428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-02 06:58 Jun 02 06:59:37 amp sudo[13428]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:37 amp sudo[13428]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:37 amp volumio-remote-updater[619]: [2026-06-02 06:59:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 02 06:59:37 amp volumio-remote-updater[619]: [2026-06-02 06:59:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 02 06:59:37 amp volumio5-onboarding[1271]: time=2026-06-02T06:59:37.546-04:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Jun 02 06:59:37 amp volumio5-onboarding[1271]: time=2026-06-02T06:59:37.547-04:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Jun 02 06:59:37 amp go-librespot[28914]: time="2026-06-02T06:59:37-04:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 02 06:59:37 amp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 02 06:59:37 amp systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 02 06:59:37 amp systemd[1]: Started dynamicswap service. Jun 02 06:59:37 amp systemd[1]: dynamicswap.service: Succeeded. Jun 02 06:59:37 amp systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 02 06:59:37 amp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 37. Jun 02 06:59:37 amp systemd[1]: Stopped Volumio Backend Module. Jun 02 06:59:37 amp systemd[1]: Started Volumio Backend Module. Jun 02 06:59:37 amp systemd[1]: Started dynamicswap service. Jun 02 06:59:37 amp systemd[1]: dynamicswap.service: Succeeded. Jun 02 06:59:38 amp volumio[13443]: info: ------------------------------------------- Jun 02 06:59:38 amp volumio[13443]: info: ----- Volumio3 ---- Jun 02 06:59:38 amp volumio[13443]: info: ------------------------------------------- Jun 02 06:59:38 amp volumio[13443]: info: ----- System startup ---- Jun 02 06:59:38 amp volumio[13443]: info: ------------------------------------------- Jun 02 06:59:39 amp volumio[13443]: info: MYVOLUMIO Environment detected Jun 02 06:59:39 amp volumio[13443]: info: Plugin folders cleanup Jun 02 06:59:39 amp volumio[13443]: info: Scanning into folder /volumio/app/plugins/ Jun 02 06:59:39 amp volumio[13443]: info: Scanning category audio_interface Jun 02 06:59:39 amp volumio[13443]: info: Scanning category miscellanea Jun 02 06:59:39 amp volumio[13443]: info: Scanning category music_service Jun 02 06:59:39 amp volumio[13443]: info: Scanning category plugins.json Jun 02 06:59:39 amp volumio[13443]: info: Scanning category system_controller Jun 02 06:59:39 amp volumio[13443]: info: Scanning category user_interface Jun 02 06:59:39 amp volumio[13443]: info: Scanning into folder /data/plugins/ Jun 02 06:59:39 amp volumio[13443]: info: Scanning category music_service Jun 02 06:59:39 amp volumio[13443]: info: Plugin folders cleanup completed Jun 02 06:59:39 amp volumio[13443]: info: ------------------------------------------- Jun 02 06:59:39 amp volumio[13443]: info: ----- Core plugins startup ---- Jun 02 06:59:39 amp volumio[13443]: info: ------------------------------------------- Jun 02 06:59:39 amp volumio[13443]: info: Loading plugins from folder /volumio/app/plugins/ Jun 02 06:59:39 amp volumio[13443]: info: Adding plugin upnp to MyMusic Plugins Jun 02 06:59:39 amp volumio[13443]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 02 06:59:39 amp volumio[13443]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 02 06:59:39 amp volumio[13443]: info: Loading plugins from folder /data/plugins/ Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "system"... Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "appearance"... Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=error msg="did not receive last pong ack from accesspoint, 721s passed" Jun 02 06:59:39 amp go-librespot[28914]: time="2026-06-02T06:59:39-04:00" level=error msg="did not receive last pong from dealer, 630s passed" Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "network"... Jun 02 06:59:39 amp volumio[13443]: info: Refreshing Cached IP Addresses Jun 02 06:59:39 amp sudo[13470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 06:59:39 amp sudo[13470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:39 amp sudo[13470]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:39 amp sudo[13472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 06:59:39 amp sudo[13472]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:39 amp sudo[13472]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "services"... Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "volumio5onboarding"... Jun 02 06:59:39 amp sudo[13485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "alsa_controller"... Jun 02 06:59:39 amp sudo[13485]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:39 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "wizard"... Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "networkfs"... Jun 02 06:59:39 amp volumio[13443]: info: Starting Udev Watcher for removable devices Jun 02 06:59:39 amp sudo[13498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=anjenaire,password=Music06107,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.221/Flac /mnt/NAS/FlacFiles Jun 02 06:59:39 amp sudo[13498]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:39 amp volumio[13443]: info: Ignoring mount for partition: boot Jun 02 06:59:39 amp volumio[13443]: info: Ignoring mount for partition: volumio Jun 02 06:59:39 amp volumio[13443]: info: Ignoring mount for partition: volumio_data Jun 02 06:59:39 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "volumio_command_line_client"... Jun 02 06:59:39 amp sudo[13498]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "upnp"... Jun 02 06:59:39 amp volumio[13443]: info: [1780397979977] Starting Upmpd Daemon Jun 02 06:59:39 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 06:59:39 amp kernel: CIFS: Attempting to mount //192.168.1.221/Flac Jun 02 06:59:39 amp kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 02 06:59:39 amp kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "my_music"... Jun 02 06:59:39 amp volumio[13443]: info: Loading plugin "mpd"... Jun 02 06:59:40 amp volumio[13443]: info: Loading plugin "upnp_browser"... Jun 02 06:59:40 amp volumio[13443]: info: Starting UPNP Browser Jun 02 06:59:40 amp volumio[13443]: info: Loading plugin "alarm-clock"... Jun 02 06:59:40 amp volumio[13443]: info: Loading plugin "airplay_emulation"... Jun 02 06:59:40 amp volumio[13443]: info: Starting Shairport Sync Jun 02 06:59:40 amp volumio[13443]: info: Loading plugin "last_100"... Jun 02 06:59:40 amp volumio[13443]: info: Loading plugin "webradio"... Jun 02 06:59:40 amp volumio[13443]: info: Loading plugin "i2s_dacs"... Jun 02 06:59:40 amp volumio[13443]: info: Loading plugin "volumiodiscovery"... Jun 02 06:59:40 amp volumio[13443]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:40 amp volumio[13443]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:40 amp volumio[13443]: *** WARNING *** For more information see Jun 02 06:59:40 amp node[13443]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:40 amp volumio[13443]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:40 amp volumio[13443]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:40 amp volumio[13443]: *** WARNING *** For more information see Jun 02 06:59:40 amp node[13443]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:40 amp node[13443]: *** WARNING *** For more information see Jun 02 06:59:40 amp node[13443]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:40 amp node[13443]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:40 amp node[13443]: *** WARNING *** For more information see Jun 02 06:59:40 amp volumio[13443]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 02 06:59:40 amp volumio[13443]: info: Discovery: Started advertising with name: Amp Jun 02 06:59:40 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 06:59:40 amp volumio[13443]: info: Loading plugin "calmradio"... Jun 02 06:59:41 amp volumio[13443]: info: Loading plugin "spop"... Jun 02 06:59:41 amp sudo[13485]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:41 amp volumio[13443]: info: Loading plugin "squeezelite_mc"... Jun 02 06:59:41 amp volumio[13443]: info: Loading plugin "outputs"... Jun 02 06:59:41 amp volumio[13443]: info: Loading plugin "albumart"... Jun 02 06:59:41 amp volumio[13443]: info: Plugin example_plugin is not enabled Jun 02 06:59:41 amp volumio[13443]: info: Loading plugin "inputs"... Jun 02 06:59:41 amp volumio[13443]: info: Loading plugin "updater_comm"... Jun 02 06:59:42 amp volumio[13443]: info: Plugin mpdemulation is not enabled Jun 02 06:59:42 amp volumio[13443]: info: Loading plugin "rest_api"... Jun 02 06:59:42 amp volumio[13443]: info: Loading plugin "websocket"... Jun 02 06:59:42 amp volumio[13443]: info: Starting Socket.io Server version 2.3.0 Jun 02 06:59:42 amp volumio[13443]: info: Loading plugin "lms"... Jun 02 06:59:42 amp volumio[13443]: info: Loading plugin "motherearthradio"... Jun 02 06:59:42 amp volumio[13443]: Forking 3 albumart workers Jun 02 06:59:42 amp volumio[13443]: info: Applying required configuration parameters for plugin motherearthradio Jun 02 06:59:42 amp volumio[13443]: info: [1780397982329] [MotherEarth] API delay: 5 Jun 02 06:59:42 amp volumio[13443]: info: Loading plugin "podcast"... Jun 02 06:59:42 amp volumio[13443]: info: ControllerPodcast::constructor Jun 02 06:59:42 amp volumio[13443]: info: Loading i18n strings for locale en Jun 02 06:59:42 amp volumio[13443]: Updating browse sources language Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::initPlayerControls Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 06:59:42 amp volumio[13443]: Express server listening on port 3000 Jun 02 06:59:42 amp volumio[13443]: [Metrics] WebUI: 4s 241.53ms Jun 02 06:59:42 amp volumio[13443]: Starting albumart workers Jun 02 06:59:42 amp volumio[13443]: info: CoreStateMachine::resetVolumioState Jun 02 06:59:42 amp volumio[13443]: info: CoreStateMachine::getcurrentVolume Jun 02 06:59:42 amp volumio[13443]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 06:59:42 amp volumio[13443]: Starting albumart workers Jun 02 06:59:42 amp volumio[13443]: Starting albumart workers Jun 02 06:59:42 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 02 06:59:42 amp volumio[13443]: info: Volumio Network Manager: Network status updated: 1 Jun 02 06:59:42 amp volumio[13443]: info: Cannot mount NAS FlacFiles at system boot, trial number 1 ,retrying in 5 seconds Jun 02 06:59:42 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 02 06:59:42 amp volumio[13443]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 06:59:42 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 02 06:59:43 amp volumio[13443]: info: Reloading queue from file Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:43 amp volumio[13443]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 06:59:43 amp volumio[13443]: info: CoreStateMachine::setRepeat true single undefined Jun 02 06:59:43 amp volumio[13443]: info: CoreStateMachine::pushState Jun 02 06:59:43 amp volumio[13443]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:43 amp volumio[13443]: info: CoreStateMachine::setRandom true Jun 02 06:59:43 amp volumio[13443]: info: CoreStateMachine::pushState Jun 02 06:59:43 amp volumio[13443]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:43 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 02 06:59:43 amp volumio[13443]: info: Setting Device type: Raspberry PI Jun 02 06:59:43 amp volumio[13443]: info: Completed loading Core Plugins Jun 02 06:59:43 amp volumio[13443]: info: Preparing to generate the ALSA configuration file Jun 02 06:59:43 amp volumio[13443]: info: VolumeController:: Volume=100 Mute =false Jun 02 06:59:43 amp volumio[13443]: info: CoreStateMachine::pushState Jun 02 06:59:43 amp volumio[13443]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:43 amp volumio[13443]: info: CoreStateMachine::updateTrackBlock Jun 02 06:59:43 amp volumio[13443]: info: CorePlayQueue::getTrackBlock Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 06:59:43 amp volumio[13443]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 02 06:59:43 amp volumio[13443]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jun 02 06:59:43 amp volumio[13443]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 02 06:59:43 amp volumio[13443]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 06:59:43 amp volumio[13443]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 06:59:43 amp volumio[13443]: info: Asound.conf file unchanged, so no further update is needed Jun 02 06:59:43 amp volumio[13443]: info: Output device has changed, restarting MPD Jun 02 06:59:43 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Jun 02 06:59:43 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Jun 02 06:59:43 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Jun 02 06:59:43 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Jun 02 06:59:43 amp volumio[13443]: info: Output device has changed, restarting Shairport Sync Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:43 amp volumio[13443]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 06:59:43 amp volumio[13443]: info: ___________ START PLUGINS ___________ Jun 02 06:59:43 amp volumio[13443]: info: ControllerMpd::onStart: Initializing MPD Jun 02 06:59:43 amp volumio[13443]: info: Creating MPD Configuration file Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:43 amp volumio[13443]: info: [1780397983324] CoreMusicLibrary::Adding element Media Servers Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:43 amp volumio-remote-updater[619]: [2026-06-02 06:59:43] [connect] Successful connection Jun 02 06:59:43 amp volumio[13443]: info: UPNP Browser: Client initialized successfully Jun 02 06:59:43 amp sudo[13575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Jun 02 06:59:43 amp sudo[13575]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:43 amp sudo[13568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 06:59:43 amp sudo[13568]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:43 amp sudo[13570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 06:59:43 amp sudo[13570]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:43 amp sudo[13577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 06:59:43 amp sudo[13579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:43 amp sudo[13577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:43 amp sudo[13579]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:43 amp sudo[13568]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:43 amp sudo[13577]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:43 amp systemd[1]: Stopping Music Player Daemon... Jun 02 06:59:43 amp sudo[13575]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:43 amp volumio[13443]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:43 amp systemd[1]: mpd.service: Succeeded. Jun 02 06:59:43 amp systemd[1]: Stopped Music Player Daemon. Jun 02 06:59:43 amp volumio[13443]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:43 amp volumio[13443]: info: [1780397983845] CoreMusicLibrary::Adding element Last_100 Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:43 amp volumio[13443]: info: [1780397983847] CoreMusicLibrary::Adding element Webradio Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 06:59:43 amp volumio[13443]: info: Initializing BBC Radios Jun 02 06:59:43 amp systemd[1]: Starting Music Player Daemon... Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:43 amp volumio[13443]: info: Adding Calm Radio to Browse Sources Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:43 amp volumio[13443]: info: [1780397983914] CoreMusicLibrary::Adding element Calm Radio Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:43 amp volumio[13443]: Cannot find translation for source Calm Radio Jun 02 06:59:43 amp volumio[13443]: info: Creating Spotify config file Jun 02 06:59:43 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:43 amp sudo[13588]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 06:59:43 amp sudo[13588]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:43 amp sudo[13588]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:43 amp volumio[13443]: info: [squeezelite_mc] Starting proxy server... Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:44 amp volumio[13443]: info: [1780397984019] CoreMusicLibrary::Adding element Mother Earth Radio Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:44 amp volumio[13443]: Cannot find translation for source Calm Radio Jun 02 06:59:44 amp volumio[13443]: Cannot find translation for source Mother Earth Radio Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:44 amp volumio[13443]: info: [1780397984026] CoreMusicLibrary::Adding element Podcast Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:44 amp volumio[13443]: Cannot find translation for source Calm Radio Jun 02 06:59:44 amp volumio[13443]: Cannot find translation for source Mother Earth Radio Jun 02 06:59:44 amp volumio[13443]: Cannot find translation for source Podcast Jun 02 06:59:44 amp sudo[13605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jun 02 06:59:44 amp sudo[13605]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:44 amp volumio[13443]: info: Volumio Calling Home Jun 02 06:59:44 amp systemd[1]: Stopping Logitech Media Server Daemon... Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 04:52:36.8484] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 04:53:06.8494] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 04:53:06.8505] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 04:53:36.8554] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 04:53:36.8584] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 04:54:06.8585] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 04:54:06.8596] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 04:54:36.8627] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 04:54:36.8637] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 04:55:06.8607] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 04:55:06.8624] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:49:37.0901] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:49:37.0985] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:50:07.0896] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:50:07.0917] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:50:37.0931] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:50:37.0941] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:51:07.0914] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:51:07.0942] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:51:37.0937] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:51:37.0947] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:52:07.0918] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:52:07.0945] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:52:37.0936] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:52:37.0946] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:53:07.0930] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:53:07.0960] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:53:37.0946] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:53:37.0956] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:54:07.0928] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:54:07.0952] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:54:37.0966] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:54:37.0994] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:55:07.0988] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:55:07.0999] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:55:37.0994] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:55:37.1024] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:56:07.0996] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:56:07.1018] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:56:37.0994] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:56:37.1011] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:57:07.1004] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:57:07.1021] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:57:37.1046] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:57:37.1057] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:58:07.1038] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:58:07.1064] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:58:37.1031] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:58:37.1048] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:59:07.1054] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp squeezeboxserver[12520]: [26-06-02 06:59:07.1065] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jun 02 06:59:44 amp volumio[13443]: info: [squeezelite_mc] Proxy server started on port 34053 Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 06:59:44 amp systemd[1]: logitechmediaserver.service: Succeeded. Jun 02 06:59:44 amp systemd[1]: Stopped Logitech Media Server Daemon. Jun 02 06:59:44 amp volumio[13443]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jun 02 06:59:44 amp volumio[13443]: info: Discovery: adding 8f54fa7c-5571-482e-ac8b-f7e2f68efffc Jun 02 06:59:44 amp volumio[13443]: info: Discovery: Found device Amp Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::volumioGetState Jun 02 06:59:44 amp volumio[13443]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:44 amp volumio[13443]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 06:59:44 amp volumio-remote-updater[619]: [2026-06-02 06:59:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1780397982 101 Jun 02 06:59:44 amp volumio[13443]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 02 06:59:44 amp systemd[1]: Starting Logitech Media Server Daemon... Jun 02 06:59:44 amp volumio[13443]: info: MPD Permissions set Jun 02 06:59:44 amp volumio[13443]: info: MPD Permissions set Jun 02 06:59:44 amp systemd[1]: Started Logitech Media Server Daemon. Jun 02 06:59:44 amp sudo[13605]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:44 amp volumio[13443]: info: Spotify config file written Jun 02 06:59:44 amp volumio[13443]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 02 06:59:44 amp sudo[13618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 02 06:59:44 amp sudo[13618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:44 amp volumio[13443]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Jun 02 06:59:44 amp volumio[13443]: info: VolumeController:: Volume=100 Mute =false Jun 02 06:59:44 amp volumio[13443]: info: CoreStateMachine::pushState Jun 02 06:59:44 amp volumio[13443]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:44 amp volumio[13443]: info: logitechmediaserver started Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp systemd[1]: Stopping go-librespot Daemon... Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jun 02 06:59:44 amp systemd[1]: go-librespot-daemon.service: Succeeded. Jun 02 06:59:44 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: No need to fix Spotify hosts Jun 02 06:59:44 amp go-librespot[13620]: go-librespot daemon starting... Jun 02 06:59:44 amp systemd[1]: Started go-librespot Daemon. Jun 02 06:59:44 amp sudo[13618]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:44 amp go-librespot[13620]: time="2026-06-02T06:59:44-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 06:59:44 amp go-librespot[13620]: time="2026-06-02T06:59:44-04:00" level=debug msg="app state loaded" Jun 02 06:59:44 amp go-librespot[13620]: time="2026-06-02T06:59:44-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 06:59:44 amp go-librespot[13620]: time="2026-06-02T06:59:44-04: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" Jun 02 06:59:44 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 06:59:44 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 06:59:44 amp volumio[13443]: info: VolumeController:: Volume=100 Mute =false Jun 02 06:59:44 amp volumio[13443]: info: CoreStateMachine::pushState Jun 02 06:59:44 amp volumio[13443]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:44 amp volumio[13443]: info: [squeezelite_mc] Server discovery started Jun 02 06:59:44 amp volumio[13443]: info: [squeezelite_mc] Player finder started Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 06:59:44 amp volumio[13443]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 02 06:59:44 amp volumio[13443]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 06:59:44 amp volumio[13443]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 02 06:59:44 amp volumio[13443]: at doSend (dgram.js:692:16) Jun 02 06:59:44 amp volumio[13443]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 02 06:59:44 amp volumio[13443]: at afterDns (dgram.js:638:5) Jun 02 06:59:44 amp volumio[13443]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 02 06:59:44 amp volumio[13443]: errno: -101, Jun 02 06:59:44 amp volumio[13443]: code: 'ENETUNREACH', Jun 02 06:59:44 amp volumio[13443]: syscall: 'send', Jun 02 06:59:44 amp volumio[13443]: address: '255.255.255.255', Jun 02 06:59:44 amp volumio[13443]: port: 3483 Jun 02 06:59:44 amp volumio[13443]: } Jun 02 06:59:44 amp volumio[13443]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 06:59:44 amp mpd[13603]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 06:59:44 amp mpd[13603]: output: No 'audio_output' defined in config file Jun 02 06:59:44 amp mpd[13603]: output: Successfully detected a sndio audio device Jun 02 06:59:44 amp mpd[13603]: zeroconf: No global port, disabling zeroconf Jun 02 06:59:44 amp systemd[1]: Started Music Player Daemon. Jun 02 06:59:44 amp sudo[13579]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:44 amp sudo[13570]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:44 amp sudo[13647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-02 06:58 Jun 02 06:59:44 amp sudo[13647]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:44 amp sudo[13647]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:45 amp volumio-remote-updater[619]: [2026-06-02 06:59:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 02 06:59:45 amp volumio-remote-updater[619]: [2026-06-02 06:59:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 02 06:59:45 amp volumio5-onboarding[1271]: time=2026-06-02T06:59:45.070-04:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:32946->127.0.0.1:3000: read: connection reset by peer" Jun 02 06:59:45 amp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 02 06:59:45 amp systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 02 06:59:45 amp systemd[1]: Started dynamicswap service. Jun 02 06:59:45 amp systemd[1]: dynamicswap.service: Succeeded. Jun 02 06:59:45 amp systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 02 06:59:45 amp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 38. Jun 02 06:59:45 amp systemd[1]: Stopped Volumio Backend Module. Jun 02 06:59:45 amp systemd[1]: Started Volumio Backend Module. Jun 02 06:59:45 amp systemd[1]: Started dynamicswap service. Jun 02 06:59:45 amp systemd[1]: dynamicswap.service: Succeeded. Jun 02 06:59:46 amp volumio5-onboarding[1271]: time=2026-06-02T06:59:46.071-04:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Jun 02 06:59:46 amp volumio[13660]: info: ------------------------------------------- Jun 02 06:59:46 amp volumio[13660]: info: ----- Volumio3 ---- Jun 02 06:59:46 amp volumio[13660]: info: ------------------------------------------- Jun 02 06:59:46 amp volumio[13660]: info: ----- System startup ---- Jun 02 06:59:46 amp volumio[13660]: info: ------------------------------------------- Jun 02 06:59:46 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 02 06:59:46 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 02 06:59:46 amp volumio[13660]: info: MYVOLUMIO Environment detected Jun 02 06:59:46 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 02 06:59:46 amp volumio[13660]: info: Plugin folders cleanup Jun 02 06:59:46 amp volumio[13660]: info: Scanning into folder /volumio/app/plugins/ Jun 02 06:59:46 amp volumio[13660]: info: Scanning category audio_interface Jun 02 06:59:46 amp volumio[13660]: info: Scanning category miscellanea Jun 02 06:59:46 amp volumio[13660]: info: Scanning category music_service Jun 02 06:59:46 amp volumio[13660]: info: Scanning category plugins.json Jun 02 06:59:46 amp volumio[13660]: info: Scanning category system_controller Jun 02 06:59:46 amp volumio[13660]: info: Scanning category user_interface Jun 02 06:59:46 amp volumio[13660]: info: Scanning into folder /data/plugins/ Jun 02 06:59:46 amp volumio[13660]: info: Scanning category music_service Jun 02 06:59:46 amp volumio[13660]: info: Plugin folders cleanup completed Jun 02 06:59:46 amp volumio[13660]: info: ------------------------------------------- Jun 02 06:59:46 amp volumio[13660]: info: ----- Core plugins startup ---- Jun 02 06:59:46 amp volumio[13660]: info: ------------------------------------------- Jun 02 06:59:46 amp volumio[13660]: info: Loading plugins from folder /volumio/app/plugins/ Jun 02 06:59:46 amp volumio[13660]: info: Adding plugin upnp to MyMusic Plugins Jun 02 06:59:46 amp volumio[13660]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 02 06:59:46 amp volumio[13660]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 02 06:59:46 amp volumio[13660]: info: Loading plugins from folder /data/plugins/ Jun 02 06:59:46 amp volumio[13660]: info: Loading plugin "system"... Jun 02 06:59:46 amp volumio[13660]: info: Loading plugin "appearance"... Jun 02 06:59:47 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 02 06:59:47 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Jun 02 06:59:47 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Jun 02 06:59:47 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Jun 02 06:59:47 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Jun 02 06:59:47 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 06:59:47 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jun 02 06:59:47 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 06:59:47 amp systemd[1]: Started go-librespot Daemon. Jun 02 06:59:47 amp go-librespot[13686]: go-librespot daemon starting... Jun 02 06:59:47 amp go-librespot[13686]: time="2026-06-02T06:59:47-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 06:59:47 amp go-librespot[13686]: time="2026-06-02T06:59:47-04:00" level=debug msg="app state loaded" Jun 02 06:59:47 amp go-librespot[13686]: time="2026-06-02T06:59:47-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 06:59:47 amp volumio[13660]: info: Loading plugin "network"... Jun 02 06:59:47 amp go-librespot[13686]: time="2026-06-02T06:59:47-04: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" Jun 02 06:59:47 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 06:59:47 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 06:59:47 amp volumio[13660]: info: Refreshing Cached IP Addresses Jun 02 06:59:47 amp sudo[13697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 06:59:47 amp sudo[13697]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:47 amp sudo[13695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 06:59:47 amp sudo[13695]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:47 amp sudo[13697]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:47 amp volumio[13660]: info: Loading plugin "services"... Jun 02 06:59:47 amp sudo[13695]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:47 amp volumio[13660]: info: Loading plugin "volumio5onboarding"... Jun 02 06:59:47 amp sudo[13709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 02 06:59:47 amp sudo[13709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:47 amp volumio[13660]: info: Loading plugin "alsa_controller"... Jun 02 06:59:47 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 06:59:47 amp volumio[13660]: info: Loading plugin "wizard"... Jun 02 06:59:47 amp volumio[13660]: info: Loading plugin "networkfs"... Jun 02 06:59:47 amp volumio[13660]: info: Starting Udev Watcher for removable devices Jun 02 06:59:47 amp sudo[13723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=anjenaire,password=Music06107,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.221/Flac /mnt/NAS/FlacFiles Jun 02 06:59:47 amp sudo[13723]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:47 amp volumio[13660]: info: Ignoring mount for partition: boot Jun 02 06:59:47 amp volumio[13660]: info: Ignoring mount for partition: volumio Jun 02 06:59:47 amp volumio[13660]: info: Ignoring mount for partition: volumio_data Jun 02 06:59:47 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 06:59:47 amp volumio[13660]: info: Loading plugin "volumio_command_line_client"... Jun 02 06:59:47 amp sudo[13723]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:47 amp kernel: CIFS: Attempting to mount //192.168.1.221/Flac Jun 02 06:59:47 amp kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 02 06:59:47 amp kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 02 06:59:47 amp volumio[13660]: info: Loading plugin "upnp"... Jun 02 06:59:47 amp volumio[13660]: info: [1780397987883] Starting Upmpd Daemon Jun 02 06:59:47 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 06:59:47 amp volumio[13660]: info: Loading plugin "my_music"... Jun 02 06:59:47 amp volumio[13660]: info: Loading plugin "mpd"... Jun 02 06:59:48 amp volumio[13660]: info: Loading plugin "upnp_browser"... Jun 02 06:59:48 amp volumio[13660]: info: Starting UPNP Browser Jun 02 06:59:48 amp volumio[13660]: info: Loading plugin "alarm-clock"... Jun 02 06:59:48 amp volumio[13660]: info: Loading plugin "airplay_emulation"... Jun 02 06:59:48 amp volumio[13660]: info: Starting Shairport Sync Jun 02 06:59:48 amp volumio[13660]: info: Loading plugin "last_100"... Jun 02 06:59:48 amp volumio[13660]: info: Loading plugin "webradio"... Jun 02 06:59:48 amp volumio[13660]: info: Loading plugin "i2s_dacs"... Jun 02 06:59:48 amp volumio[13660]: info: Loading plugin "volumiodiscovery"... Jun 02 06:59:48 amp volumio[13660]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:48 amp volumio[13660]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:48 amp node[13660]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:48 amp volumio[13660]: *** WARNING *** For more information see Jun 02 06:59:48 amp volumio[13660]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:48 amp volumio[13660]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:48 amp volumio[13660]: *** WARNING *** For more information see Jun 02 06:59:48 amp node[13660]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:48 amp node[13660]: *** WARNING *** For more information see Jun 02 06:59:48 amp node[13660]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:48 amp node[13660]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:48 amp node[13660]: *** WARNING *** For more information see Jun 02 06:59:48 amp volumio[13660]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 02 06:59:48 amp volumio[13660]: info: Discovery: Started advertising with name: Amp Jun 02 06:59:48 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 06:59:48 amp volumio[13660]: info: Loading plugin "calmradio"... Jun 02 06:59:48 amp volumio[13660]: info: Loading plugin "spop"... Jun 02 06:59:49 amp sudo[13709]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:49 amp volumio[13660]: info: Loading plugin "squeezelite_mc"... Jun 02 06:59:49 amp volumio[13660]: info: Loading plugin "outputs"... Jun 02 06:59:49 amp volumio[13660]: info: Loading plugin "albumart"... Jun 02 06:59:49 amp volumio[13660]: info: Plugin example_plugin is not enabled Jun 02 06:59:49 amp volumio[13660]: info: Loading plugin "inputs"... Jun 02 06:59:49 amp volumio[13660]: info: Loading plugin "updater_comm"... Jun 02 06:59:49 amp volumio[13660]: info: Plugin mpdemulation is not enabled Jun 02 06:59:49 amp volumio[13660]: info: Loading plugin "rest_api"... Jun 02 06:59:50 amp volumio[13660]: info: Loading plugin "websocket"... Jun 02 06:59:50 amp volumio[13660]: info: Starting Socket.io Server version 2.3.0 Jun 02 06:59:50 amp volumio[13660]: info: Loading plugin "lms"... Jun 02 06:59:50 amp volumio[13660]: info: Loading plugin "motherearthradio"... Jun 02 06:59:50 amp volumio[13660]: Forking 3 albumart workers Jun 02 06:59:50 amp volumio[13660]: info: Applying required configuration parameters for plugin motherearthradio Jun 02 06:59:50 amp volumio[13660]: info: [1780397990287] [MotherEarth] API delay: 5 Jun 02 06:59:50 amp volumio[13660]: info: Loading plugin "podcast"... Jun 02 06:59:50 amp volumio[13660]: info: ControllerPodcast::constructor Jun 02 06:59:50 amp volumio[13660]: info: Loading i18n strings for locale en Jun 02 06:59:50 amp volumio[13660]: Updating browse sources language Jun 02 06:59:50 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:50 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 06:59:50 amp volumio[13660]: info: CoreCommandRouter::initPlayerControls Jun 02 06:59:50 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:50 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:50 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 06:59:50 amp volumio[13660]: Express server listening on port 3000 Jun 02 06:59:50 amp volumio[13660]: [Metrics] WebUI: 4s 553.07ms Jun 02 06:59:50 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 02 06:59:50 amp volumio[13660]: info: CoreStateMachine::resetVolumioState Jun 02 06:59:50 amp volumio[13660]: info: CoreStateMachine::getcurrentVolume Jun 02 06:59:50 amp volumio[13660]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 06:59:50 amp volumio[13660]: Starting albumart workers Jun 02 06:59:50 amp volumio[13660]: Starting albumart workers Jun 02 06:59:50 amp volumio[13660]: Starting albumart workers Jun 02 06:59:50 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 02 06:59:50 amp volumio[13660]: info: Volumio Network Manager: Network status updated: 1 Jun 02 06:59:50 amp volumio[13660]: info: Cannot mount NAS FlacFiles at system boot, trial number 1 ,retrying in 5 seconds Jun 02 06:59:50 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 06:59:50 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jun 02 06:59:50 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 06:59:50 amp volumio[13660]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 06:59:50 amp systemd[1]: Started go-librespot Daemon. Jun 02 06:59:50 amp go-librespot[13761]: go-librespot daemon starting... Jun 02 06:59:50 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 02 06:59:50 amp go-librespot[13761]: time="2026-06-02T06:59:50-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 06:59:50 amp go-librespot[13761]: time="2026-06-02T06:59:50-04:00" level=debug msg="app state loaded" Jun 02 06:59:50 amp go-librespot[13761]: time="2026-06-02T06:59:50-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 06:59:50 amp go-librespot[13761]: time="2026-06-02T06:59:50-04: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" Jun 02 06:59:50 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 06:59:50 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 06:59:50 amp volumio[13660]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 06:59:50 amp volumio[13660]: info: Received Get System Info Jun 02 06:59:50 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 06:59:50 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 06:59:50 amp volumio[13660]: info: Discovery: Getting this device information Jun 02 06:59:50 amp volumio[13660]: info: CoreCommandRouter::volumioGetState Jun 02 06:59:50 amp volumio[13660]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:50 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:51 amp volumio[13660]: info: Reloading queue from file Jun 02 06:59:51 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 02 06:59:51 amp volumio[13660]: info: VolumeController:: Volume=100 Mute =false Jun 02 06:59:51 amp volumio[13660]: info: CoreStateMachine::pushState Jun 02 06:59:51 amp volumio[13660]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:51 amp volumio[13660]: info: CoreStateMachine::updateTrackBlock Jun 02 06:59:51 amp volumio[13660]: info: CorePlayQueue::getTrackBlock Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 06:59:51 amp volumio[13660]: info: CoreStateMachine::setRepeat true single undefined Jun 02 06:59:51 amp volumio[13660]: info: CoreStateMachine::pushState Jun 02 06:59:51 amp volumio[13660]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 06:59:51 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Jun 02 06:59:51 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Jun 02 06:59:51 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Jun 02 06:59:51 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:51 amp volumio[13660]: info: CoreStateMachine::setRandom true Jun 02 06:59:51 amp volumio[13660]: info: CoreStateMachine::pushState Jun 02 06:59:51 amp volumio[13660]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:51 amp volumio[13660]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 02 06:59:51 amp volumio[13660]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jun 02 06:59:51 amp volumio[13660]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 02 06:59:51 amp volumio[13660]: info: Setting Device type: Raspberry PI Jun 02 06:59:51 amp volumio[13660]: info: Completed loading Core Plugins Jun 02 06:59:51 amp volumio[13660]: info: Preparing to generate the ALSA configuration file Jun 02 06:59:51 amp volumio[13660]: info: Asound.conf file unchanged, so no further update is needed Jun 02 06:59:51 amp volumio[13660]: info: Output device has changed, restarting MPD Jun 02 06:59:51 amp volumio[13660]: info: Output device has changed, restarting Shairport Sync Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:51 amp volumio[13660]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 06:59:51 amp volumio[13660]: info: ___________ START PLUGINS ___________ Jun 02 06:59:51 amp volumio[13660]: info: ControllerMpd::onStart: Initializing MPD Jun 02 06:59:51 amp volumio[13660]: info: Creating MPD Configuration file Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:51 amp volumio[13660]: info: [1780397991326] CoreMusicLibrary::Adding element Media Servers Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:51 amp volumio-remote-updater[619]: [2026-06-02 06:59:51] [connect] Successful connection Jun 02 06:59:51 amp sudo[13811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 06:59:51 amp volumio[13660]: info: UPNP Browser: Client initialized successfully Jun 02 06:59:51 amp sudo[13811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:51 amp sudo[13809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Jun 02 06:59:51 amp sudo[13809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:51 amp sudo[13802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 06:59:51 amp sudo[13811]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:51 amp sudo[13802]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:51 amp sudo[13813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 06:59:51 amp sudo[13813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:51 amp sudo[13804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 06:59:51 amp sudo[13804]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:51 amp sudo[13802]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:51 amp systemd[1]: Stopping Music Player Daemon... Jun 02 06:59:51 amp sudo[13809]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:51 amp volumio[13660]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:51 amp systemd[1]: mpd.service: Succeeded. Jun 02 06:59:51 amp systemd[1]: Stopped Music Player Daemon. Jun 02 06:59:51 amp volumio[13660]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:51 amp volumio[13660]: info: [1780397991771] CoreMusicLibrary::Adding element Last_100 Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:51 amp volumio[13660]: info: [1780397991774] CoreMusicLibrary::Adding element Webradio Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 06:59:51 amp volumio[13660]: info: Initializing BBC Radios Jun 02 06:59:51 amp systemd[1]: Starting Music Player Daemon... Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:51 amp volumio[13660]: info: Adding Calm Radio to Browse Sources Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:51 amp volumio[13660]: info: [1780397991840] CoreMusicLibrary::Adding element Calm Radio Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:51 amp volumio[13660]: Cannot find translation for source Calm Radio Jun 02 06:59:51 amp volumio[13660]: info: Creating Spotify config file Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:51 amp sudo[13823]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 06:59:51 amp sudo[13823]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:51 amp sudo[13823]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:51 amp volumio[13660]: info: [squeezelite_mc] Starting proxy server... Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:51 amp volumio[13660]: info: [1780397991941] CoreMusicLibrary::Adding element Mother Earth Radio Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:51 amp volumio[13660]: Cannot find translation for source Calm Radio Jun 02 06:59:51 amp volumio[13660]: Cannot find translation for source Mother Earth Radio Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:51 amp volumio[13660]: info: [1780397991948] CoreMusicLibrary::Adding element Podcast Jun 02 06:59:51 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:51 amp volumio[13660]: Cannot find translation for source Calm Radio Jun 02 06:59:51 amp volumio[13660]: Cannot find translation for source Mother Earth Radio Jun 02 06:59:51 amp volumio[13660]: Cannot find translation for source Podcast Jun 02 06:59:51 amp sudo[13839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jun 02 06:59:51 amp sudo[13839]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:51 amp volumio[13660]: info: Volumio Calling Home Jun 02 06:59:51 amp systemd[1]: Stopping Logitech Media Server Daemon... Jun 02 06:59:52 amp squeezeboxserver[13615]: [26-06-02 06:59:46.4752] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Jun 02 06:59:52 amp squeezeboxserver[13615]: [26-06-02 06:59:46.8387] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Jun 02 06:59:52 amp squeezeboxserver[13615]: [26-06-02 06:59:46.9721] Slim::Utils::Misc::msg (1325) Warning: [06:59:46.9718] DBIx::Class::ResultSet::update_or_create(): Query returned more than one row. SQL that returns multiple rows is DEPRECATED for ->find and ->single at /usr/share/perl5/Slim/Schema.pm line 1927 Jun 02 06:59:52 amp volumio[13660]: info: [squeezelite_mc] Proxy server started on port 35687 Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 06:59:52 amp systemd[1]: logitechmediaserver.service: Succeeded. Jun 02 06:59:52 amp systemd[1]: Stopped Logitech Media Server Daemon. Jun 02 06:59:52 amp volumio[13660]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jun 02 06:59:52 amp volumio[13660]: info: Received Get System Info Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 06:59:52 amp volumio[13660]: info: Discovery: Getting this device information Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::volumioGetState Jun 02 06:59:52 amp volumio[13660]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 06:59:52 amp volumio[13660]: info: MPD Permissions set Jun 02 06:59:52 amp volumio[13660]: info: MPD Permissions set Jun 02 06:59:52 amp volumio[13660]: info: VolumeController:: Volume=100 Mute =false Jun 02 06:59:52 amp volumio[13660]: info: CoreStateMachine::pushState Jun 02 06:59:52 amp volumio[13660]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:52 amp systemd[1]: Starting Logitech Media Server Daemon... Jun 02 06:59:52 amp volumio[13660]: info: Discovery: adding 8f54fa7c-5571-482e-ac8b-f7e2f68efffc Jun 02 06:59:52 amp volumio[13660]: info: Discovery: Found device Amp Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::volumioGetState Jun 02 06:59:52 amp volumio[13660]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 02 06:59:52 amp systemd[1]: Started Logitech Media Server Daemon. Jun 02 06:59:52 amp volumio-remote-updater[619]: [2026-06-02 06:59:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1780397990 101 Jun 02 06:59:52 amp volumio[13660]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jun 02 06:59:52 amp sudo[13839]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:52 amp volumio[13660]: info: Spotify config file written Jun 02 06:59:52 amp sudo[13850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 02 06:59:52 amp sudo[13850]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:52 amp volumio[13660]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 02 06:59:52 amp volumio[13660]: info: logitechmediaserver started Jun 02 06:59:52 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp systemd[1]: Started go-librespot Daemon. Jun 02 06:59:52 amp go-librespot[13852]: go-librespot daemon starting... Jun 02 06:59:52 amp sudo[13850]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: No need to fix Spotify hosts Jun 02 06:59:52 amp go-librespot[13852]: time="2026-06-02T06:59:52-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 06:59:52 amp go-librespot[13852]: time="2026-06-02T06:59:52-04:00" level=debug msg="app state loaded" Jun 02 06:59:52 amp go-librespot[13852]: time="2026-06-02T06:59:52-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 06:59:52 amp go-librespot[13852]: time="2026-06-02T06:59:52-04: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" Jun 02 06:59:52 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 06:59:52 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 06:59:52 amp volumio[13660]: info: VolumeController:: Volume=100 Mute =false Jun 02 06:59:52 amp volumio[13660]: info: CoreStateMachine::pushState Jun 02 06:59:52 amp volumio[13660]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:52 amp volumio[13660]: info: [squeezelite_mc] Server discovery started Jun 02 06:59:52 amp volumio[13660]: info: [squeezelite_mc] Player finder started Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 06:59:52 amp volumio[13660]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 02 06:59:52 amp volumio[13660]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 06:59:52 amp volumio[13660]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 02 06:59:52 amp volumio[13660]: at doSend (dgram.js:692:16) Jun 02 06:59:52 amp volumio[13660]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 02 06:59:52 amp volumio[13660]: at afterDns (dgram.js:638:5) Jun 02 06:59:52 amp volumio[13660]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 02 06:59:52 amp volumio[13660]: errno: -101, Jun 02 06:59:52 amp volumio[13660]: code: 'ENETUNREACH', Jun 02 06:59:52 amp volumio[13660]: syscall: 'send', Jun 02 06:59:52 amp volumio[13660]: address: '255.255.255.255', Jun 02 06:59:52 amp volumio[13660]: port: 3483 Jun 02 06:59:52 amp volumio[13660]: } Jun 02 06:59:52 amp volumio[13660]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 06:59:52 amp mpd[13837]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 06:59:52 amp mpd[13837]: output: No 'audio_output' defined in config file Jun 02 06:59:52 amp mpd[13837]: output: Successfully detected a sndio audio device Jun 02 06:59:52 amp mpd[13837]: zeroconf: No global port, disabling zeroconf Jun 02 06:59:52 amp systemd[1]: Started Music Player Daemon. Jun 02 06:59:52 amp sudo[13804]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:52 amp sudo[13813]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:52 amp sudo[13880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-02 06:58 Jun 02 06:59:52 amp sudo[13880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:52 amp sudo[13880]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:52 amp volumio-remote-updater[619]: [2026-06-02 06:59:52] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 02 06:59:52 amp volumio-remote-updater[619]: [2026-06-02 06:59:52] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 02 06:59:52 amp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 02 06:59:52 amp volumio5-onboarding[1271]: time=2026-06-02T06:59:52.978-04:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Jun 02 06:59:52 amp volumio5-onboarding[1271]: time=2026-06-02T06:59:52.979-04:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Jun 02 06:59:52 amp systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 02 06:59:53 amp systemd[1]: Started dynamicswap service. Jun 02 06:59:53 amp systemd[1]: dynamicswap.service: Succeeded. Jun 02 06:59:53 amp systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 02 06:59:53 amp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 39. Jun 02 06:59:53 amp systemd[1]: Stopped Volumio Backend Module. Jun 02 06:59:53 amp systemd[1]: Started Volumio Backend Module. Jun 02 06:59:53 amp systemd[1]: Started dynamicswap service. Jun 02 06:59:53 amp systemd[1]: dynamicswap.service: Succeeded. Jun 02 06:59:53 amp volumio5-onboarding[1271]: time=2026-06-02T06:59:53.981-04:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Jun 02 06:59:54 amp volumio[13894]: info: ------------------------------------------- Jun 02 06:59:54 amp volumio[13894]: info: ----- Volumio3 ---- Jun 02 06:59:54 amp volumio[13894]: info: ------------------------------------------- Jun 02 06:59:54 amp volumio[13894]: info: ----- System startup ---- Jun 02 06:59:54 amp volumio[13894]: info: ------------------------------------------- Jun 02 06:59:54 amp volumio5-onboarding[1271]: time=2026-06-02T06:59:54.528-04:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 02 06:59:54 amp volumio[13894]: info: MYVOLUMIO Environment detected Jun 02 06:59:54 amp volumio[13894]: info: Plugin folders cleanup Jun 02 06:59:54 amp volumio[13894]: info: Scanning into folder /volumio/app/plugins/ Jun 02 06:59:54 amp volumio[13894]: info: Scanning category audio_interface Jun 02 06:59:54 amp volumio[13894]: info: Scanning category miscellanea Jun 02 06:59:54 amp volumio[13894]: info: Scanning category music_service Jun 02 06:59:54 amp volumio[13894]: info: Scanning category plugins.json Jun 02 06:59:54 amp volumio[13894]: info: Scanning category system_controller Jun 02 06:59:54 amp volumio[13894]: info: Scanning category user_interface Jun 02 06:59:54 amp volumio[13894]: info: Scanning into folder /data/plugins/ Jun 02 06:59:54 amp volumio[13894]: info: Scanning category music_service Jun 02 06:59:54 amp volumio[13894]: info: Plugin folders cleanup completed Jun 02 06:59:54 amp volumio[13894]: info: ------------------------------------------- Jun 02 06:59:54 amp volumio[13894]: info: ----- Core plugins startup ---- Jun 02 06:59:54 amp volumio[13894]: info: ------------------------------------------- Jun 02 06:59:54 amp volumio[13894]: info: Loading plugins from folder /volumio/app/plugins/ Jun 02 06:59:54 amp volumio[13894]: info: Adding plugin upnp to MyMusic Plugins Jun 02 06:59:54 amp volumio[13894]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 02 06:59:54 amp volumio[13894]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 02 06:59:54 amp volumio[13894]: info: Loading plugins from folder /data/plugins/ Jun 02 06:59:54 amp volumio[13894]: info: Loading plugin "system"... Jun 02 06:59:54 amp volumio[13894]: info: Loading plugin "appearance"... Jun 02 06:59:55 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 06:59:55 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jun 02 06:59:55 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 06:59:55 amp volumio[13894]: info: Loading plugin "network"... Jun 02 06:59:55 amp volumio[13894]: info: Refreshing Cached IP Addresses Jun 02 06:59:55 amp sudo[13923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 06:59:55 amp sudo[13923]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:55 amp systemd[1]: Started go-librespot Daemon. Jun 02 06:59:55 amp go-librespot[13921]: go-librespot daemon starting... Jun 02 06:59:55 amp sudo[13923]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:55 amp sudo[13925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 06:59:55 amp sudo[13925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:55 amp volumio[13894]: info: Loading plugin "services"... Jun 02 06:59:55 amp sudo[13925]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:55 amp volumio[13894]: info: Loading plugin "volumio5onboarding"... Jun 02 06:59:55 amp volumio[13894]: info: Loading plugin "alsa_controller"... Jun 02 06:59:55 amp sudo[13935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 02 06:59:55 amp go-librespot[13921]: time="2026-06-02T06:59:55-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 06:59:55 amp go-librespot[13921]: time="2026-06-02T06:59:55-04:00" level=debug msg="app state loaded" Jun 02 06:59:55 amp sudo[13935]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:55 amp go-librespot[13921]: time="2026-06-02T06:59:55-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 06:59:55 amp go-librespot[13921]: time="2026-06-02T06:59:55-04: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" Jun 02 06:59:55 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 06:59:55 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 06:59:55 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 06:59:55 amp volumio[13894]: info: Loading plugin "wizard"... Jun 02 06:59:55 amp volumio[13894]: info: Loading plugin "networkfs"... Jun 02 06:59:55 amp volumio[13894]: info: Starting Udev Watcher for removable devices Jun 02 06:59:55 amp sudo[13956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=anjenaire,password=Music06107,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.221/Flac /mnt/NAS/FlacFiles Jun 02 06:59:55 amp sudo[13956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:55 amp volumio[13894]: info: Ignoring mount for partition: boot Jun 02 06:59:55 amp sudo[13956]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:55 amp volumio[13894]: info: Ignoring mount for partition: volumio Jun 02 06:59:55 amp volumio[13894]: info: Ignoring mount for partition: volumio_data Jun 02 06:59:55 amp kernel: CIFS: Attempting to mount //192.168.1.221/Flac Jun 02 06:59:55 amp kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 02 06:59:55 amp kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 02 06:59:55 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 06:59:55 amp volumio[13894]: info: Loading plugin "volumio_command_line_client"... Jun 02 06:59:55 amp volumio[13894]: info: Loading plugin "upnp"... Jun 02 06:59:55 amp volumio[13894]: info: [1780397995819] Starting Upmpd Daemon Jun 02 06:59:55 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 06:59:55 amp volumio[13894]: info: Loading plugin "my_music"... Jun 02 06:59:55 amp volumio[13894]: info: Loading plugin "mpd"... Jun 02 06:59:56 amp volumio[13894]: info: Loading plugin "upnp_browser"... Jun 02 06:59:56 amp volumio[13894]: info: Starting UPNP Browser Jun 02 06:59:56 amp volumio[13894]: info: Loading plugin "alarm-clock"... Jun 02 06:59:56 amp volumio[13894]: info: Loading plugin "airplay_emulation"... Jun 02 06:59:56 amp volumio[13894]: info: Starting Shairport Sync Jun 02 06:59:56 amp volumio[13894]: info: Loading plugin "last_100"... Jun 02 06:59:56 amp volumio[13894]: info: Loading plugin "webradio"... Jun 02 06:59:56 amp volumio[13894]: info: Loading plugin "i2s_dacs"... Jun 02 06:59:56 amp volumio[13894]: info: Loading plugin "volumiodiscovery"... Jun 02 06:59:56 amp volumio[13894]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:56 amp node[13894]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:56 amp volumio[13894]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:56 amp node[13894]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:56 amp volumio[13894]: *** WARNING *** For more information see Jun 02 06:59:56 amp node[13894]: *** WARNING *** For more information see Jun 02 06:59:56 amp volumio[13894]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:56 amp node[13894]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 06:59:56 amp volumio[13894]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:56 amp node[13894]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 06:59:56 amp volumio[13894]: *** WARNING *** For more information see Jun 02 06:59:56 amp node[13894]: *** WARNING *** For more information see Jun 02 06:59:56 amp volumio[13894]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 02 06:59:56 amp volumio[13894]: info: Discovery: Started advertising with name: Amp Jun 02 06:59:56 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 06:59:56 amp volumio[13894]: info: Loading plugin "calmradio"... Jun 02 06:59:56 amp volumio[13894]: info: Loading plugin "spop"... Jun 02 06:59:57 amp sudo[13935]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:57 amp volumio[13894]: info: Loading plugin "squeezelite_mc"... Jun 02 06:59:57 amp volumio[13894]: info: Loading plugin "outputs"... Jun 02 06:59:57 amp volumio[13894]: info: Loading plugin "albumart"... Jun 02 06:59:57 amp volumio[13894]: info: Plugin example_plugin is not enabled Jun 02 06:59:57 amp volumio[13894]: info: Loading plugin "inputs"... Jun 02 06:59:57 amp volumio[13894]: info: Loading plugin "updater_comm"... Jun 02 06:59:57 amp volumio[13894]: info: Plugin mpdemulation is not enabled Jun 02 06:59:57 amp volumio[13894]: info: Loading plugin "rest_api"... Jun 02 06:59:57 amp volumio[13894]: info: Loading plugin "websocket"... Jun 02 06:59:57 amp volumio[13894]: info: Starting Socket.io Server version 2.3.0 Jun 02 06:59:57 amp volumio[13894]: info: Loading plugin "lms"... Jun 02 06:59:58 amp volumio[13894]: info: Loading plugin "motherearthradio"... Jun 02 06:59:58 amp volumio[13894]: Forking 3 albumart workers Jun 02 06:59:58 amp volumio[13894]: info: Applying required configuration parameters for plugin motherearthradio Jun 02 06:59:58 amp volumio[13894]: info: [1780397998189] [MotherEarth] API delay: 5 Jun 02 06:59:58 amp volumio[13894]: info: Loading plugin "podcast"... Jun 02 06:59:58 amp volumio[13894]: info: ControllerPodcast::constructor Jun 02 06:59:58 amp volumio[13894]: info: Loading i18n strings for locale en Jun 02 06:59:58 amp volumio[13894]: Updating browse sources language Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::initPlayerControls Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 06:59:58 amp volumio[13894]: Express server listening on port 3000 Jun 02 06:59:58 amp volumio[13894]: [Metrics] WebUI: 4s 527.83ms Jun 02 06:59:58 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 02 06:59:58 amp volumio[13894]: Starting albumart workers Jun 02 06:59:58 amp volumio[13894]: info: CoreStateMachine::resetVolumioState Jun 02 06:59:58 amp volumio[13894]: info: CoreStateMachine::getcurrentVolume Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 06:59:58 amp volumio[13894]: info: Volumio Network Manager: Network status updated: 1 Jun 02 06:59:58 amp volumio[13894]: Starting albumart workers Jun 02 06:59:58 amp volumio[13894]: info: Cannot mount NAS FlacFiles at system boot, trial number 1 ,retrying in 5 seconds Jun 02 06:59:58 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 02 06:59:58 amp volumio[13894]: Starting albumart workers Jun 02 06:59:58 amp volumio[13894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 06:59:58 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 02 06:59:58 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 06:59:58 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jun 02 06:59:58 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:58 amp volumio[13894]: info: Reloading queue from file Jun 02 06:59:58 amp volumio[13894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 06:59:58 amp volumio[13894]: info: CoreStateMachine::setRepeat true single undefined Jun 02 06:59:58 amp volumio[13894]: info: CoreStateMachine::pushState Jun 02 06:59:58 amp volumio[13894]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:58 amp volumio[13894]: info: CoreStateMachine::setRandom true Jun 02 06:59:58 amp volumio[13894]: info: CoreStateMachine::pushState Jun 02 06:59:58 amp volumio[13894]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:58 amp volumio[13894]: info: Setting Device type: Raspberry PI Jun 02 06:59:58 amp systemd[1]: Started go-librespot Daemon. Jun 02 06:59:58 amp go-librespot[13996]: go-librespot daemon starting... Jun 02 06:59:58 amp volumio[13894]: info: Completed loading Core Plugins Jun 02 06:59:58 amp volumio[13894]: info: Preparing to generate the ALSA configuration file Jun 02 06:59:58 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 02 06:59:58 amp go-librespot[13996]: time="2026-06-02T06:59:58-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 06:59:58 amp go-librespot[13996]: time="2026-06-02T06:59:58-04:00" level=debug msg="app state loaded" Jun 02 06:59:58 amp go-librespot[13996]: time="2026-06-02T06:59:58-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 06:59:58 amp go-librespot[13996]: time="2026-06-02T06:59:58-04: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" Jun 02 06:59:58 amp volumio[13894]: info: VolumeController:: Volume=100 Mute =false Jun 02 06:59:58 amp volumio[13894]: info: CoreStateMachine::pushState Jun 02 06:59:58 amp volumio[13894]: info: CorePlayQueue::getTrack 0 Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::volumioPushState Jun 02 06:59:58 amp volumio[13894]: info: CoreStateMachine::updateTrackBlock Jun 02 06:59:58 amp volumio[13894]: info: CorePlayQueue::getTrackBlock Jun 02 06:59:58 amp volumio[13894]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 06:59:58 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 06:59:58 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 06:59:58 amp volumio[13894]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 02 06:59:58 amp volumio[13894]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jun 02 06:59:58 amp volumio[13894]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 02 06:59:59 amp volumio[13894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 06:59:59 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Jun 02 06:59:59 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Jun 02 06:59:59 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Jun 02 06:59:59 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Jun 02 06:59:59 amp volumio[13894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 06:59:59 amp volumio[13894]: info: Asound.conf file unchanged, so no further update is needed Jun 02 06:59:59 amp volumio[13894]: info: Output device has changed, restarting MPD Jun 02 06:59:59 amp volumio[13894]: info: Output device has changed, restarting Shairport Sync Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:59 amp volumio[13894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 06:59:59 amp volumio[13894]: info: ___________ START PLUGINS ___________ Jun 02 06:59:59 amp volumio[13894]: info: ControllerMpd::onStart: Initializing MPD Jun 02 06:59:59 amp volumio[13894]: info: Creating MPD Configuration file Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:59 amp volumio[13894]: info: [1780397999227] CoreMusicLibrary::Adding element Media Servers Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:59 amp volumio-remote-updater[619]: [2026-06-02 06:59:59] [connect] Successful connection Jun 02 06:59:59 amp sudo[14020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 06:59:59 amp sudo[14041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Jun 02 06:59:59 amp sudo[14018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 06:59:59 amp sudo[14041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:59 amp sudo[14020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:59 amp sudo[14018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:59 amp sudo[14043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 06:59:59 amp sudo[14043]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:59 amp volumio[13894]: info: UPNP Browser: Client initialized successfully Jun 02 06:59:59 amp sudo[14045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 06:59:59 amp sudo[14045]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:59 amp sudo[14043]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:59 amp sudo[14018]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:59 amp systemd[1]: Stopping Music Player Daemon... Jun 02 06:59:59 amp sudo[14041]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:59 amp systemd[1]: mpd.service: Succeeded. Jun 02 06:59:59 amp systemd[1]: Stopped Music Player Daemon. Jun 02 06:59:59 amp volumio[13894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:59 amp volumio[13894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:59 amp volumio[13894]: info: [1780397999677] CoreMusicLibrary::Adding element Last_100 Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:59 amp volumio[13894]: info: [1780397999679] CoreMusicLibrary::Adding element Webradio Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 06:59:59 amp volumio[13894]: info: Initializing BBC Radios Jun 02 06:59:59 amp systemd[1]: Starting Music Player Daemon... Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 06:59:59 amp volumio[13894]: info: Adding Calm Radio to Browse Sources Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:59 amp volumio[13894]: info: [1780397999743] CoreMusicLibrary::Adding element Calm Radio Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:59 amp volumio[13894]: Cannot find translation for source Calm Radio Jun 02 06:59:59 amp sudo[14054]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 06:59:59 amp sudo[14054]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:59 amp volumio[13894]: info: Creating Spotify config file Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 06:59:59 amp sudo[14054]: pam_unix(sudo:session): session closed for user root Jun 02 06:59:59 amp volumio[13894]: info: [squeezelite_mc] Starting proxy server... Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:59 amp volumio[13894]: info: [1780397999844] CoreMusicLibrary::Adding element Mother Earth Radio Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:59 amp volumio[13894]: Cannot find translation for source Calm Radio Jun 02 06:59:59 amp volumio[13894]: Cannot find translation for source Mother Earth Radio Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 06:59:59 amp volumio[13894]: info: [1780397999851] CoreMusicLibrary::Adding element Podcast Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 06:59:59 amp volumio[13894]: Cannot find translation for source Calm Radio Jun 02 06:59:59 amp volumio[13894]: Cannot find translation for source Mother Earth Radio Jun 02 06:59:59 amp volumio[13894]: Cannot find translation for source Podcast Jun 02 06:59:59 amp sudo[14072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jun 02 06:59:59 amp sudo[14072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 06:59:59 amp volumio[13894]: info: Volumio Calling Home Jun 02 06:59:59 amp systemd[1]: Stopping Logitech Media Server Daemon... Jun 02 06:59:59 amp squeezeboxserver[13847]: [26-06-02 06:59:54.3507] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Jun 02 06:59:59 amp squeezeboxserver[13847]: [26-06-02 06:59:54.7135] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Jun 02 06:59:59 amp squeezeboxserver[13847]: [26-06-02 06:59:54.8460] Slim::Utils::Misc::msg (1325) Warning: [06:59:54.8457] DBIx::Class::ResultSet::update_or_create(): Query returned more than one row. SQL that returns multiple rows is DEPRECATED for ->find and ->single at /usr/share/perl5/Slim/Schema.pm line 1927 Jun 02 06:59:59 amp volumio[13894]: info: [squeezelite_mc] Proxy server started on port 42097 Jun 02 06:59:59 amp volumio[13894]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 06:59:59 amp systemd[1]: logitechmediaserver.service: Succeeded. Jun 02 06:59:59 amp systemd[1]: Stopped Logitech Media Server Daemon. Jun 02 07:00:00 amp volumio[13894]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jun 02 07:00:00 amp volumio[13894]: info: Discovery: adding 8f54fa7c-5571-482e-ac8b-f7e2f68efffc Jun 02 07:00:00 amp volumio[13894]: info: Discovery: Found device Amp Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::volumioGetState Jun 02 07:00:00 amp volumio[13894]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:00 amp volumio[13894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 07:00:00 amp volumio-remote-updater[619]: [2026-06-02 07:00:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1780397997 101 Jun 02 07:00:00 amp volumio[13894]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jun 02 07:00:00 amp volumio[13894]: info: MPD Permissions set Jun 02 07:00:00 amp volumio[13894]: info: MPD Permissions set Jun 02 07:00:00 amp volumio[13894]: info: VolumeController:: Volume=100 Mute =false Jun 02 07:00:00 amp volumio[13894]: info: CoreStateMachine::pushState Jun 02 07:00:00 amp volumio[13894]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:00 amp volumio[13894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Jun 02 07:00:00 amp volumio[13894]: info: Received Get System Info Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 07:00:00 amp volumio[13894]: info: Discovery: Getting this device information Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::volumioGetState Jun 02 07:00:00 amp volumio[13894]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 07:00:00 amp systemd[1]: Starting Logitech Media Server Daemon... Jun 02 07:00:00 amp systemd[1]: Started Logitech Media Server Daemon. Jun 02 07:00:00 amp sudo[14072]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:00 amp volumio[13894]: info: Spotify config file written Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 02 07:00:00 amp sudo[14084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 02 07:00:00 amp sudo[14084]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:00 amp volumio[13894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 07:00:00 amp systemd[1]: Started go-librespot Daemon. Jun 02 07:00:00 amp go-librespot[14086]: go-librespot daemon starting... Jun 02 07:00:00 amp sudo[14084]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp go-librespot[14086]: time="2026-06-02T07:00:00-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 07:00:00 amp go-librespot[14086]: time="2026-06-02T07:00:00-04:00" level=debug msg="app state loaded" Jun 02 07:00:00 amp go-librespot[14086]: time="2026-06-02T07:00:00-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 07:00:00 amp go-librespot[14086]: time="2026-06-02T07:00:00-04: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" Jun 02 07:00:00 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 07:00:00 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: No need to fix Spotify hosts Jun 02 07:00:00 amp volumio[13894]: info: VolumeController:: Volume=100 Mute =false Jun 02 07:00:00 amp volumio[13894]: info: CoreStateMachine::pushState Jun 02 07:00:00 amp volumio[13894]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:00 amp volumio[13894]: info: [squeezelite_mc] Server discovery started Jun 02 07:00:00 amp volumio[13894]: info: [squeezelite_mc] Player finder started Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 07:00:00 amp volumio[13894]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 02 07:00:00 amp volumio[13894]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 07:00:00 amp volumio[13894]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 02 07:00:00 amp volumio[13894]: at doSend (dgram.js:692:16) Jun 02 07:00:00 amp volumio[13894]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 02 07:00:00 amp volumio[13894]: at afterDns (dgram.js:638:5) Jun 02 07:00:00 amp volumio[13894]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 02 07:00:00 amp volumio[13894]: errno: -101, Jun 02 07:00:00 amp volumio[13894]: code: 'ENETUNREACH', Jun 02 07:00:00 amp volumio[13894]: syscall: 'send', Jun 02 07:00:00 amp volumio[13894]: address: '255.255.255.255', Jun 02 07:00:00 amp volumio[13894]: port: 3483 Jun 02 07:00:00 amp volumio[13894]: } Jun 02 07:00:00 amp volumio[13894]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 07:00:00 amp mpd[14070]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 07:00:00 amp mpd[14070]: output: No 'audio_output' defined in config file Jun 02 07:00:00 amp mpd[14070]: output: Successfully detected a sndio audio device Jun 02 07:00:00 amp mpd[14070]: zeroconf: No global port, disabling zeroconf Jun 02 07:00:00 amp systemd[1]: Started Music Player Daemon. Jun 02 07:00:00 amp sudo[14045]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:00 amp sudo[14020]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:00 amp sudo[14114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-02 06:59 Jun 02 07:00:00 amp sudo[14114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:00 amp sudo[14114]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:00 amp volumio5-onboarding[1271]: time=2026-06-02T07:00:00.892-04:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Jun 02 07:00:00 amp volumio5-onboarding[1271]: time=2026-06-02T07:00:00.893-04:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Jun 02 07:00:00 amp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 02 07:00:00 amp volumio-remote-updater[619]: [2026-06-02 07:00:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 02 07:00:00 amp volumio-remote-updater[619]: [2026-06-02 07:00:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 02 07:00:00 amp systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 02 07:00:00 amp systemd[1]: Started dynamicswap service. Jun 02 07:00:00 amp systemd[1]: dynamicswap.service: Succeeded. Jun 02 07:00:01 amp systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 02 07:00:01 amp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 40. Jun 02 07:00:01 amp systemd[1]: Started dynamicswap service. Jun 02 07:00:01 amp systemd[1]: Stopped Volumio Backend Module. Jun 02 07:00:01 amp systemd[1]: Started Volumio Backend Module. Jun 02 07:00:01 amp volumio5-onboarding[1271]: time=2026-06-02T07:00:01.125-04:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 02 07:00:01 amp systemd[1]: dynamicswap.service: Succeeded. Jun 02 07:00:02 amp volumio[14129]: info: ------------------------------------------- Jun 02 07:00:02 amp volumio[14129]: info: ----- Volumio3 ---- Jun 02 07:00:02 amp volumio[14129]: info: ------------------------------------------- Jun 02 07:00:02 amp volumio[14129]: info: ----- System startup ---- Jun 02 07:00:02 amp volumio[14129]: info: ------------------------------------------- Jun 02 07:00:02 amp volumio[14129]: info: MYVOLUMIO Environment detected Jun 02 07:00:02 amp volumio[14129]: info: Plugin folders cleanup Jun 02 07:00:02 amp volumio[14129]: info: Scanning into folder /volumio/app/plugins/ Jun 02 07:00:02 amp volumio[14129]: info: Scanning category audio_interface Jun 02 07:00:02 amp volumio[14129]: info: Scanning category miscellanea Jun 02 07:00:02 amp volumio[14129]: info: Scanning category music_service Jun 02 07:00:02 amp volumio[14129]: info: Scanning category plugins.json Jun 02 07:00:02 amp volumio[14129]: info: Scanning category system_controller Jun 02 07:00:02 amp volumio[14129]: info: Scanning category user_interface Jun 02 07:00:02 amp volumio[14129]: info: Scanning into folder /data/plugins/ Jun 02 07:00:02 amp volumio[14129]: info: Scanning category music_service Jun 02 07:00:02 amp volumio[14129]: info: Plugin folders cleanup completed Jun 02 07:00:02 amp volumio[14129]: info: ------------------------------------------- Jun 02 07:00:02 amp volumio[14129]: info: ----- Core plugins startup ---- Jun 02 07:00:02 amp volumio[14129]: info: ------------------------------------------- Jun 02 07:00:02 amp volumio[14129]: info: Loading plugins from folder /volumio/app/plugins/ Jun 02 07:00:02 amp volumio[14129]: info: Adding plugin upnp to MyMusic Plugins Jun 02 07:00:02 amp volumio[14129]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 02 07:00:02 amp volumio[14129]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 02 07:00:02 amp volumio[14129]: info: Loading plugins from folder /data/plugins/ Jun 02 07:00:02 amp volumio[14129]: info: Loading plugin "system"... Jun 02 07:00:02 amp volumio[14129]: info: Loading plugin "appearance"... Jun 02 07:00:03 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 07:00:03 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jun 02 07:00:03 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "network"... Jun 02 07:00:03 amp volumio[14129]: info: Refreshing Cached IP Addresses Jun 02 07:00:03 amp sudo[14156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 07:00:03 amp sudo[14156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:03 amp systemd[1]: Started go-librespot Daemon. Jun 02 07:00:03 amp go-librespot[14154]: go-librespot daemon starting... Jun 02 07:00:03 amp sudo[14158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 07:00:03 amp sudo[14158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:03 amp sudo[14156]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:03 amp sudo[14158]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:03 amp go-librespot[14154]: time="2026-06-02T07:00:03-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 07:00:03 amp go-librespot[14154]: time="2026-06-02T07:00:03-04:00" level=debug msg="app state loaded" Jun 02 07:00:03 amp go-librespot[14154]: time="2026-06-02T07:00:03-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 07:00:03 amp go-librespot[14154]: time="2026-06-02T07:00:03-04: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" Jun 02 07:00:03 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 07:00:03 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "services"... Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "volumio5onboarding"... Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "alsa_controller"... Jun 02 07:00:03 amp sudo[14177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 02 07:00:03 amp sudo[14177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:03 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "wizard"... Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "networkfs"... Jun 02 07:00:03 amp volumio[14129]: info: Starting Udev Watcher for removable devices Jun 02 07:00:03 amp sudo[14190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=anjenaire,password=Music06107,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.221/Flac /mnt/NAS/FlacFiles Jun 02 07:00:03 amp sudo[14190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:03 amp volumio[14129]: info: Ignoring mount for partition: boot Jun 02 07:00:03 amp sudo[14190]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:03 amp volumio[14129]: info: Ignoring mount for partition: volumio Jun 02 07:00:03 amp volumio[14129]: info: Ignoring mount for partition: volumio_data Jun 02 07:00:03 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "volumio_command_line_client"... Jun 02 07:00:03 amp kernel: CIFS: Attempting to mount //192.168.1.221/Flac Jun 02 07:00:03 amp kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 02 07:00:03 amp kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "upnp"... Jun 02 07:00:03 amp volumio[14129]: info: [1780398003597] Starting Upmpd Daemon Jun 02 07:00:03 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "my_music"... Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "mpd"... Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "upnp_browser"... Jun 02 07:00:03 amp volumio[14129]: info: Starting UPNP Browser Jun 02 07:00:03 amp volumio[14129]: info: Loading plugin "alarm-clock"... Jun 02 07:00:04 amp volumio[14129]: info: Loading plugin "airplay_emulation"... Jun 02 07:00:04 amp volumio[14129]: info: Starting Shairport Sync Jun 02 07:00:04 amp volumio[14129]: info: Loading plugin "last_100"... Jun 02 07:00:04 amp volumio[14129]: info: Loading plugin "webradio"... Jun 02 07:00:04 amp volumio[14129]: info: Loading plugin "i2s_dacs"... Jun 02 07:00:04 amp volumio[14129]: info: Loading plugin "volumiodiscovery"... Jun 02 07:00:04 amp volumio[14129]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 07:00:04 amp volumio[14129]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 07:00:04 amp node[14129]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 07:00:04 amp volumio[14129]: *** WARNING *** For more information see Jun 02 07:00:04 amp volumio[14129]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 07:00:04 amp volumio[14129]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 07:00:04 amp volumio[14129]: *** WARNING *** For more information see Jun 02 07:00:04 amp node[14129]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 07:00:04 amp node[14129]: *** WARNING *** For more information see Jun 02 07:00:04 amp node[14129]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 07:00:04 amp node[14129]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 07:00:04 amp node[14129]: *** WARNING *** For more information see Jun 02 07:00:04 amp volumio[14129]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 02 07:00:04 amp volumio[14129]: info: Discovery: Started advertising with name: Amp Jun 02 07:00:04 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 07:00:04 amp volumio[14129]: info: Loading plugin "calmradio"... Jun 02 07:00:04 amp volumio[14129]: info: Loading plugin "spop"... Jun 02 07:00:04 amp sudo[14177]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:05 amp volumio[14129]: info: Loading plugin "squeezelite_mc"... Jun 02 07:00:05 amp volumio[14129]: info: Loading plugin "outputs"... Jun 02 07:00:05 amp volumio[14129]: info: Loading plugin "albumart"... Jun 02 07:00:05 amp volumio[14129]: info: Plugin example_plugin is not enabled Jun 02 07:00:05 amp volumio[14129]: info: Loading plugin "inputs"... Jun 02 07:00:05 amp volumio[14129]: info: Loading plugin "updater_comm"... Jun 02 07:00:05 amp volumio[14129]: info: Plugin mpdemulation is not enabled Jun 02 07:00:05 amp volumio[14129]: info: Loading plugin "rest_api"... Jun 02 07:00:05 amp volumio[14129]: info: Loading plugin "websocket"... Jun 02 07:00:05 amp volumio[14129]: info: Starting Socket.io Server version 2.3.0 Jun 02 07:00:05 amp volumio[14129]: info: Loading plugin "lms"... Jun 02 07:00:05 amp volumio[14129]: info: Loading plugin "motherearthradio"... Jun 02 07:00:05 amp volumio[14129]: Forking 3 albumart workers Jun 02 07:00:05 amp volumio[14129]: info: Applying required configuration parameters for plugin motherearthradio Jun 02 07:00:05 amp volumio[14129]: info: [1780398005991] [MotherEarth] API delay: 5 Jun 02 07:00:05 amp volumio[14129]: info: Loading plugin "podcast"... Jun 02 07:00:06 amp volumio[14129]: info: ControllerPodcast::constructor Jun 02 07:00:06 amp volumio[14129]: info: Loading i18n strings for locale en Jun 02 07:00:06 amp volumio[14129]: Updating browse sources language Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:06 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::initPlayerControls Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 07:00:06 amp volumio[14129]: Express server listening on port 3000 Jun 02 07:00:06 amp volumio[14129]: [Metrics] WebUI: 4s 574.36ms Jun 02 07:00:06 amp volumio[14129]: Starting albumart workers Jun 02 07:00:06 amp volumio[14129]: Starting albumart workers Jun 02 07:00:06 amp volumio[14129]: info: CoreStateMachine::resetVolumioState Jun 02 07:00:06 amp volumio[14129]: info: CoreStateMachine::getcurrentVolume Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 07:00:06 amp volumio[14129]: Starting albumart workers Jun 02 07:00:06 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 02 07:00:06 amp volumio[14129]: info: Volumio Network Manager: Network status updated: 1 Jun 02 07:00:06 amp volumio[14129]: info: Cannot mount NAS FlacFiles at system boot, trial number 1 ,retrying in 5 seconds Jun 02 07:00:06 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 07:00:06 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jun 02 07:00:06 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 07:00:06 amp volumio[14129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 07:00:06 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 02 07:00:06 amp systemd[1]: Started go-librespot Daemon. Jun 02 07:00:06 amp go-librespot[14228]: go-librespot daemon starting... Jun 02 07:00:06 amp go-librespot[14228]: time="2026-06-02T07:00:06-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 07:00:06 amp go-librespot[14228]: time="2026-06-02T07:00:06-04:00" level=debug msg="app state loaded" Jun 02 07:00:06 amp go-librespot[14228]: time="2026-06-02T07:00:06-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 07:00:06 amp go-librespot[14228]: time="2026-06-02T07:00:06-04: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" Jun 02 07:00:06 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 07:00:06 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: Reloading queue from file Jun 02 07:00:06 amp volumio[14129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 07:00:06 amp volumio[14129]: info: CoreStateMachine::setRepeat true single undefined Jun 02 07:00:06 amp volumio[14129]: info: CoreStateMachine::pushState Jun 02 07:00:06 amp volumio[14129]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:06 amp volumio[14129]: info: CoreStateMachine::setRandom true Jun 02 07:00:06 amp volumio[14129]: info: CoreStateMachine::pushState Jun 02 07:00:06 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 02 07:00:06 amp volumio[14129]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:06 amp volumio[14129]: info: Setting Device type: Raspberry PI Jun 02 07:00:06 amp volumio[14129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 07:00:06 amp volumio[14129]: info: Completed loading Core Plugins Jun 02 07:00:06 amp volumio[14129]: info: Preparing to generate the ALSA configuration file Jun 02 07:00:06 amp volumio[14129]: info: VolumeController:: Volume=100 Mute =false Jun 02 07:00:06 amp volumio[14129]: info: CoreStateMachine::pushState Jun 02 07:00:06 amp volumio[14129]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:06 amp volumio[14129]: info: CoreStateMachine::updateTrackBlock Jun 02 07:00:06 amp volumio[14129]: info: CorePlayQueue::getTrackBlock Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 07:00:06 amp volumio[14129]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 02 07:00:06 amp volumio[14129]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jun 02 07:00:06 amp volumio[14129]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 02 07:00:06 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Jun 02 07:00:06 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Jun 02 07:00:06 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Jun 02 07:00:06 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Jun 02 07:00:06 amp volumio[14129]: info: Asound.conf file unchanged, so no further update is needed Jun 02 07:00:06 amp volumio[14129]: info: Output device has changed, restarting MPD Jun 02 07:00:06 amp volumio[14129]: info: Output device has changed, restarting Shairport Sync Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 07:00:06 amp volumio[14129]: info: ___________ START PLUGINS ___________ Jun 02 07:00:06 amp volumio[14129]: info: ControllerMpd::onStart: Initializing MPD Jun 02 07:00:06 amp volumio[14129]: info: Creating MPD Configuration file Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:06 amp volumio[14129]: info: [1780398006983] CoreMusicLibrary::Adding element Media Servers Jun 02 07:00:06 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:07 amp volumio-remote-updater[619]: [2026-06-02 07:00:07] [connect] Successful connection Jun 02 07:00:07 amp volumio[14129]: info: UPNP Browser: Client initialized successfully Jun 02 07:00:07 amp sudo[14269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 07:00:07 amp sudo[14269]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:07 amp sudo[14252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 07:00:07 amp sudo[14276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 07:00:07 amp sudo[14252]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:07 amp sudo[14274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Jun 02 07:00:07 amp sudo[14276]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:07 amp sudo[14274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:07 amp sudo[14278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:07 amp sudo[14252]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:07 amp sudo[14278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:07 amp sudo[14276]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:07 amp systemd[1]: Stopping Music Player Daemon... Jun 02 07:00:07 amp sudo[14274]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:07 amp volumio[14129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:07 amp systemd[1]: mpd.service: Succeeded. Jun 02 07:00:07 amp systemd[1]: Stopped Music Player Daemon. Jun 02 07:00:07 amp volumio[14129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:07 amp volumio[14129]: info: [1780398007476] CoreMusicLibrary::Adding element Last_100 Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:07 amp volumio[14129]: info: [1780398007478] CoreMusicLibrary::Adding element Webradio Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 07:00:07 amp volumio[14129]: info: Initializing BBC Radios Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:07 amp volumio[14129]: info: Adding Calm Radio to Browse Sources Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:07 amp volumio[14129]: info: [1780398007538] CoreMusicLibrary::Adding element Calm Radio Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:07 amp volumio[14129]: Cannot find translation for source Calm Radio Jun 02 07:00:07 amp systemd[1]: Starting Music Player Daemon... Jun 02 07:00:07 amp volumio[14129]: info: Creating Spotify config file Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:07 amp sudo[14290]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 07:00:07 amp sudo[14290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:07 amp sudo[14290]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:07 amp volumio[14129]: info: [squeezelite_mc] Starting proxy server... Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:07 amp volumio[14129]: info: [1780398007638] CoreMusicLibrary::Adding element Mother Earth Radio Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:07 amp volumio[14129]: Cannot find translation for source Calm Radio Jun 02 07:00:07 amp volumio[14129]: Cannot find translation for source Mother Earth Radio Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:07 amp volumio[14129]: info: [1780398007644] CoreMusicLibrary::Adding element Podcast Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:07 amp volumio[14129]: Cannot find translation for source Calm Radio Jun 02 07:00:07 amp volumio[14129]: Cannot find translation for source Mother Earth Radio Jun 02 07:00:07 amp volumio[14129]: Cannot find translation for source Podcast Jun 02 07:00:07 amp sudo[14304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jun 02 07:00:07 amp volumio[14129]: info: Volumio Calling Home Jun 02 07:00:07 amp sudo[14304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:07 amp systemd[1]: Stopping Logitech Media Server Daemon... Jun 02 07:00:07 amp squeezeboxserver[14082]: [26-06-02 07:00:02.2968] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Jun 02 07:00:07 amp squeezeboxserver[14082]: [26-06-02 07:00:02.6555] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Jun 02 07:00:07 amp squeezeboxserver[14082]: [26-06-02 07:00:02.7901] Slim::Utils::Misc::msg (1325) Warning: [07:00:02.7899] DBIx::Class::ResultSet::update_or_create(): Query returned more than one row. SQL that returns multiple rows is DEPRECATED for ->find and ->single at /usr/share/perl5/Slim/Schema.pm line 1927 Jun 02 07:00:07 amp systemd[1]: logitechmediaserver.service: Succeeded. Jun 02 07:00:07 amp systemd[1]: Stopped Logitech Media Server Daemon. Jun 02 07:00:07 amp volumio[14129]: info: [squeezelite_mc] Proxy server started on port 37209 Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 07:00:07 amp volumio[14129]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jun 02 07:00:07 amp systemd[1]: Starting Logitech Media Server Daemon... Jun 02 07:00:07 amp volumio[14129]: info: Received Get System Info Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 07:00:07 amp volumio[14129]: info: Discovery: Getting this device information Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::volumioGetState Jun 02 07:00:07 amp volumio[14129]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 07:00:07 amp volumio[14129]: info: MPD Permissions set Jun 02 07:00:07 amp volumio[14129]: info: MPD Permissions set Jun 02 07:00:07 amp systemd[1]: Started Logitech Media Server Daemon. Jun 02 07:00:07 amp sudo[14304]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:07 amp volumio[14129]: info: Discovery: adding 8f54fa7c-5571-482e-ac8b-f7e2f68efffc Jun 02 07:00:07 amp volumio[14129]: info: Discovery: Found device Amp Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::volumioGetState Jun 02 07:00:07 amp volumio[14129]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 02 07:00:07 amp volumio-remote-updater[619]: [2026-06-02 07:00:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1780398005 101 Jun 02 07:00:07 amp volumio[14129]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jun 02 07:00:07 amp volumio[14129]: info: logitechmediaserver started Jun 02 07:00:07 amp volumio[14129]: info: Spotify config file written Jun 02 07:00:07 amp sudo[14316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 02 07:00:07 amp sudo[14316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:07 amp volumio[14129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 02 07:00:07 amp volumio[14129]: info: VolumeController:: Volume=100 Mute =false Jun 02 07:00:07 amp volumio[14129]: info: CoreStateMachine::pushState Jun 02 07:00:07 amp volumio[14129]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:07 amp volumio[14129]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: No need to fix Spotify hosts Jun 02 07:00:08 amp go-librespot[14319]: go-librespot daemon starting... Jun 02 07:00:08 amp systemd[1]: Started go-librespot Daemon. Jun 02 07:00:08 amp sudo[14316]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:08 amp go-librespot[14319]: time="2026-06-02T07:00:08-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 07:00:08 amp go-librespot[14319]: time="2026-06-02T07:00:08-04:00" level=debug msg="app state loaded" Jun 02 07:00:08 amp go-librespot[14319]: time="2026-06-02T07:00:08-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 07:00:08 amp go-librespot[14319]: time="2026-06-02T07:00:08-04: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" Jun 02 07:00:08 amp volumio[14129]: info: VolumeController:: Volume=100 Mute =false Jun 02 07:00:08 amp volumio[14129]: info: CoreStateMachine::pushState Jun 02 07:00:08 amp volumio[14129]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:08 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 07:00:08 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 07:00:08 amp volumio[14129]: info: [squeezelite_mc] Server discovery started Jun 02 07:00:08 amp volumio[14129]: info: [squeezelite_mc] Player finder started Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 07:00:08 amp volumio[14129]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 02 07:00:08 amp volumio[14129]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 07:00:08 amp volumio[14129]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 02 07:00:08 amp volumio[14129]: at doSend (dgram.js:692:16) Jun 02 07:00:08 amp volumio[14129]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 02 07:00:08 amp volumio[14129]: at afterDns (dgram.js:638:5) Jun 02 07:00:08 amp volumio[14129]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 02 07:00:08 amp volumio[14129]: errno: -101, Jun 02 07:00:08 amp volumio[14129]: code: 'ENETUNREACH', Jun 02 07:00:08 amp volumio[14129]: syscall: 'send', Jun 02 07:00:08 amp volumio[14129]: address: '255.255.255.255', Jun 02 07:00:08 amp volumio[14129]: port: 3483 Jun 02 07:00:08 amp volumio[14129]: } Jun 02 07:00:08 amp volumio[14129]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 07:00:08 amp mpd[14302]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 07:00:08 amp mpd[14302]: output: No 'audio_output' defined in config file Jun 02 07:00:08 amp mpd[14302]: output: Successfully detected a sndio audio device Jun 02 07:00:08 amp mpd[14302]: zeroconf: No global port, disabling zeroconf Jun 02 07:00:08 amp systemd[1]: Started Music Player Daemon. Jun 02 07:00:08 amp sudo[14278]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:08 amp sudo[14269]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:08 amp sudo[14346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-02 06:59 Jun 02 07:00:08 amp sudo[14346]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:08 amp sudo[14346]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:08 amp volumio-remote-updater[619]: [2026-06-02 07:00:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 02 07:00:08 amp volumio-remote-updater[619]: [2026-06-02 07:00:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 02 07:00:08 amp volumio5-onboarding[1271]: time=2026-06-02T07:00:08.738-04:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Jun 02 07:00:08 amp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 02 07:00:08 amp volumio5-onboarding[1271]: time=2026-06-02T07:00:08.741-04:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Jun 02 07:00:08 amp systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 02 07:00:08 amp systemd[1]: Started dynamicswap service. Jun 02 07:00:08 amp systemd[1]: dynamicswap.service: Succeeded. Jun 02 07:00:08 amp volumio5-onboarding[1271]: time=2026-06-02T07:00:08.888-04:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 02 07:00:09 amp systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 02 07:00:09 amp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 41. Jun 02 07:00:09 amp systemd[1]: Stopped Volumio Backend Module. Jun 02 07:00:09 amp systemd[1]: Started Volumio Backend Module. Jun 02 07:00:09 amp systemd[1]: Started dynamicswap service. Jun 02 07:00:09 amp systemd[1]: dynamicswap.service: Succeeded. Jun 02 07:00:09 amp volumio5-onboarding[1271]: time=2026-06-02T07:00:09.742-04:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Jun 02 07:00:10 amp volumio[14359]: info: ------------------------------------------- Jun 02 07:00:10 amp volumio[14359]: info: ----- Volumio3 ---- Jun 02 07:00:10 amp volumio[14359]: info: ------------------------------------------- Jun 02 07:00:10 amp volumio[14359]: info: ----- System startup ---- Jun 02 07:00:10 amp volumio[14359]: info: ------------------------------------------- Jun 02 07:00:10 amp volumio[14359]: info: MYVOLUMIO Environment detected Jun 02 07:00:10 amp volumio[14359]: info: Plugin folders cleanup Jun 02 07:00:10 amp volumio[14359]: info: Scanning into folder /volumio/app/plugins/ Jun 02 07:00:10 amp volumio[14359]: info: Scanning category audio_interface Jun 02 07:00:10 amp volumio[14359]: info: Scanning category miscellanea Jun 02 07:00:10 amp volumio[14359]: info: Scanning category music_service Jun 02 07:00:10 amp volumio[14359]: info: Scanning category plugins.json Jun 02 07:00:10 amp volumio[14359]: info: Scanning category system_controller Jun 02 07:00:10 amp volumio[14359]: info: Scanning category user_interface Jun 02 07:00:10 amp volumio[14359]: info: Scanning into folder /data/plugins/ Jun 02 07:00:10 amp volumio[14359]: info: Scanning category music_service Jun 02 07:00:10 amp volumio[14359]: info: Plugin folders cleanup completed Jun 02 07:00:10 amp volumio[14359]: info: ------------------------------------------- Jun 02 07:00:10 amp volumio[14359]: info: ----- Core plugins startup ---- Jun 02 07:00:10 amp volumio[14359]: info: ------------------------------------------- Jun 02 07:00:10 amp volumio[14359]: info: Loading plugins from folder /volumio/app/plugins/ Jun 02 07:00:10 amp volumio[14359]: info: Adding plugin upnp to MyMusic Plugins Jun 02 07:00:10 amp volumio[14359]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 02 07:00:10 amp volumio[14359]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 02 07:00:10 amp volumio[14359]: info: Loading plugins from folder /data/plugins/ Jun 02 07:00:10 amp volumio[14359]: info: Loading plugin "system"... Jun 02 07:00:10 amp volumio[14359]: info: Loading plugin "appearance"... Jun 02 07:00:11 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 07:00:11 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jun 02 07:00:11 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "network"... Jun 02 07:00:11 amp systemd[1]: Started go-librespot Daemon. Jun 02 07:00:11 amp volumio[14359]: info: Refreshing Cached IP Addresses Jun 02 07:00:11 amp go-librespot[14385]: go-librespot daemon starting... Jun 02 07:00:11 amp go-librespot[14385]: time="2026-06-02T07:00:11-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 07:00:11 amp go-librespot[14385]: time="2026-06-02T07:00:11-04:00" level=debug msg="app state loaded" Jun 02 07:00:11 amp go-librespot[14385]: time="2026-06-02T07:00:11-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 07:00:11 amp sudo[14393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 07:00:11 amp sudo[14393]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:11 amp go-librespot[14385]: time="2026-06-02T07:00:11-04: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" Jun 02 07:00:11 amp sudo[14393]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:11 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 07:00:11 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 07:00:11 amp sudo[14396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 07:00:11 amp sudo[14396]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:11 amp sudo[14396]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "services"... Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "volumio5onboarding"... Jun 02 07:00:11 amp sudo[14404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "alsa_controller"... Jun 02 07:00:11 amp sudo[14404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:11 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "wizard"... Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "networkfs"... Jun 02 07:00:11 amp volumio[14359]: info: Starting Udev Watcher for removable devices Jun 02 07:00:11 amp sudo[14421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=anjenaire,password=Music06107,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.221/Flac /mnt/NAS/FlacFiles Jun 02 07:00:11 amp sudo[14421]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:11 amp volumio[14359]: info: Ignoring mount for partition: boot Jun 02 07:00:11 amp volumio[14359]: info: Ignoring mount for partition: volumio Jun 02 07:00:11 amp volumio[14359]: info: Ignoring mount for partition: volumio_data Jun 02 07:00:11 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "volumio_command_line_client"... Jun 02 07:00:11 amp sudo[14421]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:11 amp kernel: CIFS: Attempting to mount //192.168.1.221/Flac Jun 02 07:00:11 amp kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 02 07:00:11 amp kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "upnp"... Jun 02 07:00:11 amp volumio[14359]: info: [1780398011603] Starting Upmpd Daemon Jun 02 07:00:11 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "my_music"... Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "mpd"... Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "upnp_browser"... Jun 02 07:00:11 amp volumio[14359]: info: Starting UPNP Browser Jun 02 07:00:11 amp volumio[14359]: info: Loading plugin "alarm-clock"... Jun 02 07:00:12 amp volumio[14359]: info: Loading plugin "airplay_emulation"... Jun 02 07:00:12 amp volumio[14359]: info: Starting Shairport Sync Jun 02 07:00:12 amp volumio[14359]: info: Loading plugin "last_100"... Jun 02 07:00:12 amp volumio[14359]: info: Loading plugin "webradio"... Jun 02 07:00:12 amp volumio[14359]: info: Loading plugin "i2s_dacs"... Jun 02 07:00:12 amp volumio[14359]: info: Loading plugin "volumiodiscovery"... Jun 02 07:00:12 amp volumio[14359]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 07:00:12 amp volumio[14359]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 07:00:12 amp node[14359]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 02 07:00:12 amp volumio[14359]: *** WARNING *** For more information see Jun 02 07:00:12 amp volumio[14359]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 07:00:12 amp volumio[14359]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 07:00:12 amp volumio[14359]: *** WARNING *** For more information see Jun 02 07:00:12 amp node[14359]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 07:00:12 amp node[14359]: *** WARNING *** For more information see Jun 02 07:00:12 amp node[14359]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 02 07:00:12 amp node[14359]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 02 07:00:12 amp node[14359]: *** WARNING *** For more information see Jun 02 07:00:12 amp volumio[14359]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 02 07:00:12 amp volumio[14359]: info: Discovery: Started advertising with name: Amp Jun 02 07:00:12 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 07:00:12 amp volumio[14359]: info: Loading plugin "calmradio"... Jun 02 07:00:12 amp volumio[14359]: info: Loading plugin "spop"... Jun 02 07:00:12 amp sudo[14404]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:13 amp volumio[14359]: info: Loading plugin "squeezelite_mc"... Jun 02 07:00:13 amp volumio[14359]: info: Loading plugin "outputs"... Jun 02 07:00:13 amp volumio[14359]: info: Loading plugin "albumart"... Jun 02 07:00:13 amp volumio[14359]: info: Plugin example_plugin is not enabled Jun 02 07:00:13 amp volumio[14359]: info: Loading plugin "inputs"... Jun 02 07:00:13 amp volumio[14359]: info: Loading plugin "updater_comm"... Jun 02 07:00:13 amp volumio[14359]: info: Plugin mpdemulation is not enabled Jun 02 07:00:13 amp volumio[14359]: info: Loading plugin "rest_api"... Jun 02 07:00:13 amp volumio[14359]: info: Loading plugin "websocket"... Jun 02 07:00:13 amp volumio[14359]: info: Starting Socket.io Server version 2.3.0 Jun 02 07:00:13 amp volumio[14359]: info: Loading plugin "lms"... Jun 02 07:00:13 amp volumio[14359]: info: Loading plugin "motherearthradio"... Jun 02 07:00:13 amp volumio[14359]: Forking 3 albumart workers Jun 02 07:00:13 amp volumio[14359]: info: Applying required configuration parameters for plugin motherearthradio Jun 02 07:00:13 amp volumio[14359]: info: [1780398013964] [MotherEarth] API delay: 5 Jun 02 07:00:13 amp volumio[14359]: info: Loading plugin "podcast"... Jun 02 07:00:14 amp volumio[14359]: info: ControllerPodcast::constructor Jun 02 07:00:14 amp volumio[14359]: info: Loading i18n strings for locale en Jun 02 07:00:14 amp volumio[14359]: Updating browse sources language Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::initPlayerControls Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:14 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 07:00:14 amp volumio[14359]: Express server listening on port 3000 Jun 02 07:00:14 amp volumio[14359]: [Metrics] WebUI: 4s 557.70ms Jun 02 07:00:14 amp volumio[14359]: Starting albumart workers Jun 02 07:00:14 amp volumio[14359]: Starting albumart workers Jun 02 07:00:14 amp volumio[14359]: Starting albumart workers Jun 02 07:00:14 amp volumio[14359]: info: CoreStateMachine::resetVolumioState Jun 02 07:00:14 amp volumio[14359]: info: CoreStateMachine::getcurrentVolume Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 07:00:14 amp volumio[14359]: info: Volumio Network Manager: Network status updated: 1 Jun 02 07:00:14 amp volumio[14359]: info: Cannot mount NAS FlacFiles at system boot, trial number 1 ,retrying in 5 seconds Jun 02 07:00:14 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 02 07:00:14 amp volumio[14359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 07:00:14 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 02 07:00:14 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jun 02 07:00:14 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 07:00:14 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 02 07:00:14 amp systemd[1]: Started go-librespot Daemon. Jun 02 07:00:14 amp go-librespot[14461]: go-librespot daemon starting... Jun 02 07:00:14 amp go-librespot[14461]: time="2026-06-02T07:00:14-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 07:00:14 amp go-librespot[14461]: time="2026-06-02T07:00:14-04:00" level=debug msg="app state loaded" Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:14 amp volumio[14359]: info: Reloading queue from file Jun 02 07:00:14 amp go-librespot[14461]: time="2026-06-02T07:00:14-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 07:00:14 amp go-librespot[14461]: time="2026-06-02T07:00:14-04: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" Jun 02 07:00:14 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 07:00:14 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 07:00:14 amp volumio[14359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 07:00:14 amp volumio[14359]: info: CoreStateMachine::setRepeat true single undefined Jun 02 07:00:14 amp volumio[14359]: info: CoreStateMachine::pushState Jun 02 07:00:14 amp volumio[14359]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:14 amp volumio[14359]: info: CoreStateMachine::setRandom true Jun 02 07:00:14 amp volumio[14359]: info: CoreStateMachine::pushState Jun 02 07:00:14 amp volumio[14359]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:14 amp volumio[14359]: info: Setting Device type: Raspberry PI Jun 02 07:00:14 amp volumio[14359]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 02 07:00:14 amp volumio[14359]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jun 02 07:00:14 amp volumio[14359]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 02 07:00:14 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 02 07:00:14 amp volumio[14359]: info: Completed loading Core Plugins Jun 02 07:00:14 amp volumio[14359]: info: Preparing to generate the ALSA configuration file Jun 02 07:00:14 amp volumio[14359]: info: VolumeController:: Volume=100 Mute =false Jun 02 07:00:14 amp volumio[14359]: info: CoreStateMachine::pushState Jun 02 07:00:14 amp volumio[14359]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:14 amp volumio[14359]: info: CoreStateMachine::updateTrackBlock Jun 02 07:00:14 amp volumio[14359]: info: CorePlayQueue::getTrackBlock Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 07:00:14 amp volumio[14359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 07:00:14 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Jun 02 07:00:14 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Jun 02 07:00:14 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Jun 02 07:00:14 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Jun 02 07:00:14 amp volumio[14359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 07:00:14 amp volumio[14359]: info: Asound.conf file unchanged, so no further update is needed Jun 02 07:00:14 amp volumio[14359]: info: Output device has changed, restarting MPD Jun 02 07:00:14 amp volumio[14359]: info: Output device has changed, restarting Shairport Sync Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:14 amp volumio[14359]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 07:00:14 amp volumio[14359]: info: ___________ START PLUGINS ___________ Jun 02 07:00:14 amp volumio[14359]: info: ControllerMpd::onStart: Initializing MPD Jun 02 07:00:14 amp volumio[14359]: info: Creating MPD Configuration file Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:14 amp volumio[14359]: info: [1780398014997] CoreMusicLibrary::Adding element Media Servers Jun 02 07:00:14 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:15 amp volumio-remote-updater[619]: [2026-06-02 07:00:15] [connect] Successful connection Jun 02 07:00:15 amp sudo[14501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 07:00:15 amp sudo[14503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 07:00:15 amp sudo[14508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Jun 02 07:00:15 amp sudo[14501]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:15 amp sudo[14503]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:15 amp sudo[14508]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:15 amp sudo[14510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 07:00:15 amp sudo[14510]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:15 amp sudo[14501]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:15 amp sudo[14512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 07:00:15 amp sudo[14512]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:15 amp sudo[14510]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:15 amp volumio[14359]: info: UPNP Browser: Client initialized successfully Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:15 amp sudo[14508]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:15 amp systemd[1]: Stopping Music Player Daemon... Jun 02 07:00:15 amp systemd[1]: mpd.service: Succeeded. Jun 02 07:00:15 amp systemd[1]: Stopped Music Player Daemon. Jun 02 07:00:15 amp volumio[14359]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:15 amp systemd[1]: Starting Music Player Daemon... Jun 02 07:00:15 amp volumio[14359]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:15 amp volumio[14359]: info: [1780398015480] CoreMusicLibrary::Adding element Last_100 Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:15 amp volumio[14359]: info: [1780398015482] CoreMusicLibrary::Adding element Webradio Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 07:00:15 amp volumio[14359]: info: Initializing BBC Radios Jun 02 07:00:15 amp sudo[14522]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 07:00:15 amp sudo[14522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:15 amp sudo[14522]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 07:00:15 amp volumio[14359]: info: Adding Calm Radio to Browse Sources Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:15 amp volumio[14359]: info: [1780398015563] CoreMusicLibrary::Adding element Calm Radio Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:15 amp volumio[14359]: Cannot find translation for source Calm Radio Jun 02 07:00:15 amp volumio[14359]: info: Creating Spotify config file Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:15 amp volumio[14359]: info: [squeezelite_mc] Starting proxy server... Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:15 amp volumio[14359]: info: [1780398015665] CoreMusicLibrary::Adding element Mother Earth Radio Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:15 amp volumio[14359]: Cannot find translation for source Calm Radio Jun 02 07:00:15 amp volumio[14359]: Cannot find translation for source Mother Earth Radio Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 07:00:15 amp volumio[14359]: info: [1780398015672] CoreMusicLibrary::Adding element Podcast Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 07:00:15 amp volumio[14359]: Cannot find translation for source Calm Radio Jun 02 07:00:15 amp volumio[14359]: Cannot find translation for source Mother Earth Radio Jun 02 07:00:15 amp volumio[14359]: Cannot find translation for source Podcast Jun 02 07:00:15 amp volumio[14359]: info: Volumio Calling Home Jun 02 07:00:15 amp sudo[14582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jun 02 07:00:15 amp sudo[14582]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:15 amp systemd[1]: Stopping Logitech Media Server Daemon... Jun 02 07:00:15 amp squeezeboxserver[14312]: [26-06-02 07:00:10.0402] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Jun 02 07:00:15 amp squeezeboxserver[14312]: [26-06-02 07:00:10.3989] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Jun 02 07:00:15 amp squeezeboxserver[14312]: [26-06-02 07:00:10.5302] Slim::Utils::Misc::msg (1325) Warning: [07:00:10.5299] DBIx::Class::ResultSet::update_or_create(): Query returned more than one row. SQL that returns multiple rows is DEPRECATED for ->find and ->single at /usr/share/perl5/Slim/Schema.pm line 1927 Jun 02 07:00:15 amp volumio[14359]: info: [squeezelite_mc] Proxy server started on port 43463 Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::volumioRetrievevolume Jun 02 07:00:15 amp systemd[1]: logitechmediaserver.service: Succeeded. Jun 02 07:00:15 amp systemd[1]: Stopped Logitech Media Server Daemon. Jun 02 07:00:15 amp volumio[14359]: info: Discovery: adding 8f54fa7c-5571-482e-ac8b-f7e2f68efffc Jun 02 07:00:15 amp volumio[14359]: info: Discovery: Found device Amp Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::volumioGetState Jun 02 07:00:15 amp volumio[14359]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:15 amp systemd[1]: Starting Logitech Media Server Daemon... Jun 02 07:00:15 amp systemd[1]: Started Logitech Media Server Daemon. Jun 02 07:00:15 amp volumio[14359]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jun 02 07:00:15 amp sudo[14582]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:15 amp volumio[14359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Jun 02 07:00:15 amp volumio-remote-updater[619]: [2026-06-02 07:00:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1780398013 101 Jun 02 07:00:15 amp volumio[14359]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 02 07:00:15 amp volumio[14359]: info: MPD Permissions set Jun 02 07:00:15 amp volumio[14359]: info: MPD Permissions set Jun 02 07:00:15 amp volumio[14359]: info: VolumeController:: Volume=100 Mute =false Jun 02 07:00:15 amp volumio[14359]: info: CoreStateMachine::pushState Jun 02 07:00:15 amp volumio[14359]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:15 amp volumio[14359]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:15 amp volumio[14359]: info: logitechmediaserver started Jun 02 07:00:15 amp volumio[14359]: info: Spotify config file written Jun 02 07:00:15 amp sudo[14593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 02 07:00:15 amp sudo[14593]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:15 amp volumio[14359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 02 07:00:16 amp volumio[14359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Jun 02 07:00:16 amp volumio[14359]: info: VolumeController:: Volume=100 Mute =false Jun 02 07:00:16 amp volumio[14359]: info: CoreStateMachine::pushState Jun 02 07:00:16 amp volumio[14359]: info: CorePlayQueue::getTrack 0 Jun 02 07:00:16 amp volumio[14359]: info: CoreCommandRouter::volumioPushState Jun 02 07:00:16 amp systemd[1]: Stopped go-librespot Daemon. Jun 02 07:00:16 amp volumio[14359]: info: [squeezelite_mc] Server discovery started Jun 02 07:00:16 amp volumio[14359]: info: [squeezelite_mc] Player finder started Jun 02 07:00:16 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:16 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:16 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 07:00:16 amp volumio[14359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 07:00:16 amp volumio[14359]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 02 07:00:16 amp volumio[14359]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 07:00:16 amp volumio[14359]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 02 07:00:16 amp volumio[14359]: at doSend (dgram.js:692:16) Jun 02 07:00:16 amp volumio[14359]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 02 07:00:16 amp volumio[14359]: at afterDns (dgram.js:638:5) Jun 02 07:00:16 amp volumio[14359]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 02 07:00:16 amp volumio[14359]: errno: -101, Jun 02 07:00:16 amp volumio[14359]: code: 'ENETUNREACH', Jun 02 07:00:16 amp volumio[14359]: syscall: 'send', Jun 02 07:00:16 amp volumio[14359]: address: '255.255.255.255', Jun 02 07:00:16 amp volumio[14359]: port: 3483 Jun 02 07:00:16 amp volumio[14359]: } Jun 02 07:00:16 amp volumio[14359]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 07:00:16 amp systemd[1]: Started go-librespot Daemon. Jun 02 07:00:16 amp go-librespot[14595]: go-librespot daemon starting... Jun 02 07:00:16 amp sudo[14593]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:16 amp go-librespot[14595]: time="2026-06-02T07:00:16-04:00" level=info msg="running go-librespot 0.4.0" Jun 02 07:00:16 amp go-librespot[14595]: time="2026-06-02T07:00:16-04:00" level=debug msg="app state loaded" Jun 02 07:00:16 amp go-librespot[14595]: time="2026-06-02T07:00:16-04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 07:00:16 amp go-librespot[14595]: time="2026-06-02T07:00:16-04: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" Jun 02 07:00:16 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 07:00:16 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 07:00:16 amp mpd[14580]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 07:00:16 amp mpd[14580]: output: No 'audio_output' defined in config file Jun 02 07:00:16 amp mpd[14580]: output: Successfully detected a sndio audio device Jun 02 07:00:16 amp mpd[14580]: zeroconf: No global port, disabling zeroconf Jun 02 07:00:16 amp systemd[1]: Started Music Player Daemon. Jun 02 07:00:16 amp sudo[14503]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:16 amp sudo[14512]: pam_unix(sudo:session): session closed for user root Jun 02 07:00:16 amp sudo[14615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-02 06:59 Jun 02 07:00:16 amp sudo[14615]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 07:00:16 amp dhcpcd[1044]: eth0: dhcp_sendudp: Network is unreachable PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"