-- Logs begin at Sun 2026-01-11 12:45:10 EST, end at Sun 2026-01-11 14:35:10 EST. -- Jan 11 14:34:08 amp go-librespot[19293]: time="2026-01-11T14:34:08-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:08 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:08 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:08 amp volumio[9228]: (node:9228) UnhandledPromiseRejectionWarning: Error: socket hang up Jan 11 14:34:08 amp volumio[9228]: at connResetException (internal/errors.js:607:14) Jan 11 14:34:08 amp volumio[9228]: at Socket.socketOnEnd (_http_client.js:493:23) Jan 11 14:34:08 amp volumio[9228]: at Socket.emit (events.js:327:22) Jan 11 14:34:08 amp volumio[9228]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 11 14:34:08 amp volumio[9228]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 11 14:34:08 amp volumio[9228]: (node:9228) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 53) Jan 11 14:34:08 amp volumio[9228]: info: Connection to go-librespot Websocket closed Jan 11 14:34:11 amp volumio[9228]: info: Initializing connection to go-librespot Websocket Jan 11 14:34:11 amp volumio[9228]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 14:34:11 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:34:11 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Jan 11 14:34:11 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:11 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:11 amp go-librespot[19394]: go-librespot daemon starting... Jan 11 14:34:11 amp go-librespot[19394]: time="2026-01-11T14:34:11-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:11 amp go-librespot[19394]: time="2026-01-11T14:34:11-05:00" level=debug msg="app state loaded" Jan 11 14:34:11 amp go-librespot[19394]: time="2026-01-11T14:34:11-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:14 amp volumio[9228]: info: Initializing connection to go-librespot Websocket Jan 11 14:34:14 amp go-librespot[19394]: time="2026-01-11T14:34:14-05:00" level=debug msg="new websocket client" Jan 11 14:34:14 amp volumio[9228]: info: Connection to go-librespot Websocket established Jan 11 14:34:16 amp ntpd[975]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 11 14:34:17 amp volumio[9228]: info: Getting Spotify volume Jan 11 14:34:17 amp volumio[9228]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 11 14:34:17 amp volumio[9228]: info: CoreCommandRouter::volumioGetState Jan 11 14:34:17 amp volumio[9228]: info: CorePlayQueue::getTrack 0 Jan 11 14:34:17 amp volumio[9228]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 11 14:34:18 amp volumio[9228]: 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"} Jan 11 14:34:18 amp volumio[9228]: 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"} Jan 11 14:34:18 amp volumio[9228]: 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"} Jan 11 14:34:18 amp volumio[9228]: 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"} Jan 11 14:34:18 amp volumio[9228]: 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. Jan 11 14:34:18 amp volumio[9228]: at Telnet.clientCloseHandler (/data/plugins/music_service/squeezelite_mc/node_modules/lms-cli-notifications/dist/cjs/NotificationListener.js:228:32) Jan 11 14:34:18 amp volumio[9228]: at Object.onceWrapper (events.js:421:28) Jan 11 14:34:18 amp volumio[9228]: at Telnet.emit (events.js:327:22) Jan 11 14:34:18 amp volumio[9228]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:173:22) Jan 11 14:34:18 amp volumio[9228]: at Socket.emit (events.js:315:20) Jan 11 14:34:18 amp volumio[9228]: at TCP. (net.js:673:12) Jan 11 14:34:18 amp volumio[9228]: 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. Jan 11 14:34:18 amp volumio[9228]: at Telnet.clientCloseHandler (/data/plugins/music_service/squeezelite_mc/node_modules/lms-cli-notifications/dist/cjs/NotificationListener.js:228:32) Jan 11 14:34:18 amp volumio[9228]: at Object.onceWrapper (events.js:421:28) Jan 11 14:34:18 amp volumio[9228]: at Telnet.emit (events.js:327:22) Jan 11 14:34:18 amp volumio[9228]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:173:22) Jan 11 14:34:18 amp volumio[9228]: at Socket.emit (events.js:315:20) Jan 11 14:34:18 amp volumio[9228]: at TCP. (net.js:673:12) Jan 11 14:34:22 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jan 11 14:34:22 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 14:34:22 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 14:34:22 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 14:34:25 amp wpa_supplicant[915]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="NiceWillow" Jan 11 14:34:25 amp wpa_supplicant[915]: wlan0: Trying to associate with SSID 'NiceWillow' Jan 11 14:34:25 amp dhcpcd[1039]: wlan0: carrier lost Jan 11 14:34:25 amp avahi-daemon[625]: Withdrawing address record for 169.254.47.209 on wlan0. Jan 11 14:34:25 amp avahi-daemon[625]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.47.209. Jan 11 14:34:25 amp avahi-daemon[625]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 11 14:34:25 amp dhcpcd[1039]: wlan0: deleting route to 169.254.0.0/16 Jan 11 14:34:25 amp dhcpcd[1039]: wlan0: deleting default route Jan 11 14:34:25 amp volumio[9228]: info: Discovery: A device disappeared from network Jan 11 14:34:25 amp volumio[9228]: info: Discovery: Device amp disappeared from network Jan 11 14:34:25 amp go-librespot[19394]: time="2026-01-11T14:34:25-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:25 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:25 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:25 amp volumio[9228]: (node:9228) UnhandledPromiseRejectionWarning: Error: socket hang up Jan 11 14:34:25 amp volumio[9228]: at connResetException (internal/errors.js:607:14) Jan 11 14:34:25 amp volumio[9228]: at Socket.socketOnEnd (_http_client.js:493:23) Jan 11 14:34:25 amp volumio[9228]: at Socket.emit (events.js:327:22) Jan 11 14:34:25 amp volumio[9228]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 11 14:34:25 amp volumio[9228]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 11 14:34:25 amp volumio[9228]: (node:9228) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 54) Jan 11 14:34:25 amp volumio[9228]: info: Connection to go-librespot Websocket closed Jan 11 14:34:26 amp ntpd[975]: Deleting interface #10 wlan0, 169.254.47.209#123, interface stats: received=0, sent=43, dropped=0, active_time=2149 secs Jan 11 14:34:26 amp ntpd[975]: 141.11.89.193 local addr 169.254.47.209 -> Jan 11 14:34:26 amp ntpd[975]: 193.29.63.226 local addr 169.254.47.209 -> Jan 11 14:34:26 amp ntpd[975]: 158.51.99.19 local addr 169.254.47.209 -> Jan 11 14:34:26 amp ntpd[975]: 172.104.28.175 local addr 169.254.47.209 -> Jan 11 14:34:26 amp ntpd[975]: 192.168.1.1 local addr 169.254.47.209 -> Jan 11 14:34:26 amp ntpd[975]: 162.159.200.123 local addr 169.254.47.209 -> Jan 11 14:34:28 amp volumio[9228]: info: Initializing connection to go-librespot Websocket Jan 11 14:34:28 amp volumio[9228]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 14:34:28 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:34:28 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Jan 11 14:34:28 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:28 amp volumio[9228]: info: Volumio Network Manager: Network status updated: 1 Jan 11 14:34:28 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:28 amp go-librespot[19483]: go-librespot daemon starting... Jan 11 14:34:28 amp go-librespot[19483]: time="2026-01-11T14:34:28-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:28 amp go-librespot[19483]: time="2026-01-11T14:34:28-05:00" level=debug msg="app state loaded" Jan 11 14:34:28 amp go-librespot[19483]: time="2026-01-11T14:34:28-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:28 amp go-librespot[19483]: time="2026-01-11T14:34:28-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:28 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:28 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:30 amp ntpd[975]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 11 14:34:30 amp ntpd[975]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 11 14:34:30 amp ntpd[975]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 11 14:34:30 amp ntpd[975]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 11 14:34:30 amp ntpd[975]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 11 14:34:31 amp wpa_supplicant[915]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=d0:cb:dd:2f:b6:46 status_code=16 Jan 11 14:34:31 amp wpa_supplicant[915]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="NiceWillow" auth_failures=203 duration=964 reason=CONN_FAILED Jan 11 14:34:31 amp volumio[9228]: info: Initializing connection to go-librespot Websocket Jan 11 14:34:31 amp volumio[9228]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 14:34:31 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:34:31 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Jan 11 14:34:31 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:31 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:31 amp go-librespot[19506]: go-librespot daemon starting... Jan 11 14:34:31 amp go-librespot[19506]: time="2026-01-11T14:34:31-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:31 amp go-librespot[19506]: time="2026-01-11T14:34:31-05:00" level=debug msg="app state loaded" Jan 11 14:34:31 amp go-librespot[19506]: time="2026-01-11T14:34:31-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:31 amp go-librespot[19506]: time="2026-01-11T14:34:31-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:31 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:31 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:34 amp volumio[9228]: info: Initializing connection to go-librespot Websocket Jan 11 14:34:34 amp volumio[9228]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 14:34:35 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:34:35 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Jan 11 14:34:35 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:35 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:35 amp go-librespot[19514]: go-librespot daemon starting... Jan 11 14:34:35 amp go-librespot[19514]: time="2026-01-11T14:34:35-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:35 amp go-librespot[19514]: time="2026-01-11T14:34:35-05:00" level=debug msg="app state loaded" Jan 11 14:34:35 amp go-librespot[19514]: time="2026-01-11T14:34:35-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:35 amp go-librespot[19514]: time="2026-01-11T14:34:35-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:35 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:35 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:37 amp volumio[9228]: info: Initializing connection to go-librespot Websocket Jan 11 14:34:37 amp volumio[9228]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 14:34:38 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:34:38 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Jan 11 14:34:38 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:38 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:38 amp go-librespot[19523]: go-librespot daemon starting... Jan 11 14:34:38 amp go-librespot[19523]: time="2026-01-11T14:34:38-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:38 amp go-librespot[19523]: time="2026-01-11T14:34:38-05:00" level=debug msg="app state loaded" Jan 11 14:34:38 amp go-librespot[19523]: time="2026-01-11T14:34:38-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:38 amp go-librespot[19523]: time="2026-01-11T14:34:38-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:38 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:38 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:38 amp ntpd[975]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 11 14:34:40 amp volumio[9228]: info: Initializing connection to go-librespot Websocket Jan 11 14:34:40 amp volumio[9228]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 14:34:41 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:34:41 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Jan 11 14:34:41 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:41 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:41 amp go-librespot[19544]: go-librespot daemon starting... Jan 11 14:34:41 amp go-librespot[19544]: time="2026-01-11T14:34:41-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:41 amp go-librespot[19544]: time="2026-01-11T14:34:41-05:00" level=debug msg="app state loaded" Jan 11 14:34:41 amp go-librespot[19544]: time="2026-01-11T14:34:41-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:41 amp go-librespot[19544]: time="2026-01-11T14:34:41-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:41 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:41 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:43 amp volumio[9228]: info: Initializing connection to go-librespot Websocket Jan 11 14:34:43 amp volumio[9228]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 14:34:44 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:34:44 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Jan 11 14:34:44 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:44 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:44 amp go-librespot[19551]: go-librespot daemon starting... Jan 11 14:34:44 amp go-librespot[19551]: time="2026-01-11T14:34:44-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:44 amp go-librespot[19551]: time="2026-01-11T14:34:44-05:00" level=debug msg="app state loaded" Jan 11 14:34:44 amp go-librespot[19551]: time="2026-01-11T14:34:44-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:44 amp go-librespot[19551]: time="2026-01-11T14:34:44-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:44 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:44 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:44 amp ntpd[975]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 11 14:34:46 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jan 11 14:34:46 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 14:34:46 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 14:34:46 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 14:34:46 amp volumio[9228]: info: Initializing connection to go-librespot Websocket Jan 11 14:34:46 amp volumio[9228]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 14:34:48 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:34:48 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Jan 11 14:34:48 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:48 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:48 amp go-librespot[19562]: go-librespot daemon starting... Jan 11 14:34:48 amp go-librespot[19562]: time="2026-01-11T14:34:48-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:48 amp go-librespot[19562]: time="2026-01-11T14:34:48-05:00" level=debug msg="app state loaded" Jan 11 14:34:48 amp go-librespot[19562]: time="2026-01-11T14:34:48-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:48 amp go-librespot[19562]: time="2026-01-11T14:34:48-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:48 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:48 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:48 amp volumio[9228]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:34:48 amp volumio[9228]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 11 14:34:48 amp volumio[9228]: at doSend (dgram.js:692:16) Jan 11 14:34:48 amp volumio[9228]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 11 14:34:48 amp volumio[9228]: at afterDns (dgram.js:638:5) Jan 11 14:34:48 amp volumio[9228]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 11 14:34:48 amp volumio[9228]: errno: -101, Jan 11 14:34:48 amp volumio[9228]: code: 'ENETUNREACH', Jan 11 14:34:48 amp volumio[9228]: syscall: 'send', Jan 11 14:34:48 amp volumio[9228]: address: '255.255.255.255', Jan 11 14:34:48 amp volumio[9228]: port: 3483 Jan 11 14:34:48 amp volumio[9228]: } Jan 11 14:34:48 amp volumio[9228]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:34:49 amp sudo[19592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 14:33 Jan 11 14:34:49 amp sudo[19592]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:49 amp sudo[19592]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:49 amp volumio-remote-updater[592]: [2026-01-11 14:34:49] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 11 14:34:49 amp volumio-remote-updater[592]: [2026-01-11 14:34:49] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 11 14:34:49 amp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:49 amp systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 11 14:34:49 amp systemd[1]: Started dynamicswap service. Jan 11 14:34:49 amp systemd[1]: dynamicswap.service: Succeeded. Jan 11 14:34:49 amp systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 11 14:34:49 amp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Jan 11 14:34:49 amp systemd[1]: Started dynamicswap service. Jan 11 14:34:49 amp systemd[1]: Stopped Volumio Backend Module. Jan 11 14:34:49 amp systemd[1]: Started Volumio Backend Module. Jan 11 14:34:49 amp systemd[1]: dynamicswap.service: Succeeded. Jan 11 14:34:50 amp volumio[19607]: info: ------------------------------------------- Jan 11 14:34:50 amp volumio[19607]: info: ----- Volumio3 ---- Jan 11 14:34:50 amp volumio[19607]: info: ------------------------------------------- Jan 11 14:34:50 amp volumio[19607]: info: ----- System startup ---- Jan 11 14:34:50 amp volumio[19607]: info: ------------------------------------------- Jan 11 14:34:50 amp volumio[19607]: info: MYVOLUMIO Environment detected Jan 11 14:34:50 amp volumio[19607]: info: Plugin folders cleanup Jan 11 14:34:50 amp volumio[19607]: info: Scanning into folder /volumio/app/plugins/ Jan 11 14:34:50 amp volumio[19607]: info: Scanning category audio_interface Jan 11 14:34:50 amp volumio[19607]: info: Scanning category miscellanea Jan 11 14:34:50 amp volumio[19607]: info: Scanning category music_service Jan 11 14:34:50 amp volumio[19607]: info: Scanning category plugins.json Jan 11 14:34:50 amp volumio[19607]: info: Scanning category system_controller Jan 11 14:34:50 amp volumio[19607]: info: Scanning category user_interface Jan 11 14:34:50 amp volumio[19607]: info: Scanning into folder /data/plugins/ Jan 11 14:34:50 amp volumio[19607]: info: Scanning category music_service Jan 11 14:34:50 amp volumio[19607]: info: Plugin folders cleanup completed Jan 11 14:34:50 amp volumio[19607]: info: ------------------------------------------- Jan 11 14:34:50 amp volumio[19607]: info: ----- Core plugins startup ---- Jan 11 14:34:50 amp volumio[19607]: info: ------------------------------------------- Jan 11 14:34:50 amp volumio[19607]: info: Loading plugins from folder /volumio/app/plugins/ Jan 11 14:34:50 amp volumio[19607]: info: Adding plugin upnp to MyMusic Plugins Jan 11 14:34:50 amp volumio[19607]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 11 14:34:50 amp volumio[19607]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 11 14:34:50 amp volumio[19607]: info: Loading plugins from folder /data/plugins/ Jan 11 14:34:50 amp volumio[19607]: info: Loading plugin "system"... Jan 11 14:34:51 amp volumio[19607]: info: Loading plugin "appearance"... Jan 11 14:34:51 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:34:51 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Jan 11 14:34:51 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:51 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:51 amp go-librespot[19633]: go-librespot daemon starting... Jan 11 14:34:51 amp go-librespot[19633]: time="2026-01-11T14:34:51-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:51 amp go-librespot[19633]: time="2026-01-11T14:34:51-05:00" level=debug msg="app state loaded" Jan 11 14:34:51 amp go-librespot[19633]: time="2026-01-11T14:34:51-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:51 amp go-librespot[19633]: time="2026-01-11T14:34:51-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:51 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:51 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:51 amp volumio[19607]: info: Loading plugin "network"... Jan 11 14:34:51 amp volumio[19607]: info: Refreshing Cached IP Addresses Jan 11 14:34:51 amp sudo[19641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 14:34:51 amp sudo[19641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:51 amp sudo[19641]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:51 amp sudo[19643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 14:34:51 amp sudo[19643]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:51 amp sudo[19643]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:51 amp volumio[19607]: info: Loading plugin "services"... Jan 11 14:34:51 amp volumio[19607]: info: Loading plugin "alsa_controller"... Jan 11 14:34:51 amp sudo[19654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 11 14:34:51 amp sudo[19654]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:51 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 14:34:51 amp volumio[19607]: info: Loading plugin "wizard"... Jan 11 14:34:51 amp volumio[19607]: info: Loading plugin "networkfs"... Jan 11 14:34:51 amp volumio[19607]: info: Cannot mount NAS FlacFiles at system boot, trial number 1 ,retrying in 5 seconds Jan 11 14:34:51 amp volumio[19607]: info: Starting Udev Watcher for removable devices Jan 11 14:34:51 amp volumio[19607]: info: Ignoring mount for partition: boot Jan 11 14:34:51 amp volumio[19607]: info: Ignoring mount for partition: volumio Jan 11 14:34:51 amp volumio[19607]: info: Ignoring mount for partition: volumio_data Jan 11 14:34:51 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 14:34:51 amp volumio[19607]: info: Loading plugin "volumio_command_line_client"... Jan 11 14:34:51 amp volumio[19607]: info: Loading plugin "upnp"... Jan 11 14:34:51 amp volumio[19607]: info: [1768160091757] Starting Upmpd Daemon Jan 11 14:34:51 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 14:34:51 amp volumio[19607]: info: Loading plugin "my_music"... Jan 11 14:34:51 amp volumio[19607]: info: Loading plugin "mpd"... Jan 11 14:34:51 amp volumio[19607]: info: Loading plugin "upnp_browser"... Jan 11 14:34:52 amp volumio[19607]: info: Starting UPNP Browser Jan 11 14:34:52 amp volumio[19607]: info: Loading plugin "alarm-clock"... Jan 11 14:34:52 amp volumio[19607]: info: Loading plugin "airplay_emulation"... Jan 11 14:34:52 amp volumio[19607]: info: Starting Shairport Sync Jan 11 14:34:52 amp volumio[19607]: info: Loading plugin "last_100"... Jan 11 14:34:52 amp volumio[19607]: info: Loading plugin "webradio"... Jan 11 14:34:52 amp volumio[19607]: info: Loading plugin "i2s_dacs"... Jan 11 14:34:52 amp volumio[19607]: info: Loading plugin "volumiodiscovery"... Jan 11 14:34:52 amp volumio[19607]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 14:34:52 amp volumio[19607]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:34:52 amp node[19607]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 14:34:52 amp volumio[19607]: *** WARNING *** For more information see Jan 11 14:34:52 amp volumio[19607]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 14:34:52 amp volumio[19607]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:34:52 amp volumio[19607]: *** WARNING *** For more information see Jan 11 14:34:52 amp node[19607]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:34:52 amp node[19607]: *** WARNING *** For more information see Jan 11 14:34:52 amp node[19607]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 14:34:52 amp node[19607]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:34:52 amp node[19607]: *** WARNING *** For more information see Jan 11 14:34:52 amp volumio[19607]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 11 14:34:52 amp volumio[19607]: info: Discovery: Started advertising with name: Amp Jan 11 14:34:52 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 14:34:52 amp volumio[19607]: info: Loading plugin "calmradio"... Jan 11 14:34:52 amp volumio[19607]: info: Loading plugin "spop"... Jan 11 14:34:53 amp volumio[19607]: info: Loading plugin "squeezelite_mc"... Jan 11 14:34:53 amp volumio[19607]: info: Loading plugin "outputs"... Jan 11 14:34:53 amp volumio[19607]: info: Loading plugin "albumart"... Jan 11 14:34:53 amp volumio[19607]: info: Plugin example_plugin is not enabled Jan 11 14:34:53 amp volumio[19607]: info: Loading plugin "inputs"... Jan 11 14:34:53 amp volumio[19607]: info: Loading plugin "updater_comm"... Jan 11 14:34:53 amp volumio[19607]: info: Plugin mpdemulation is not enabled Jan 11 14:34:53 amp volumio[19607]: info: Loading plugin "rest_api"... Jan 11 14:34:53 amp volumio[19607]: info: Loading plugin "websocket"... Jan 11 14:34:53 amp volumio[19607]: info: Starting Socket.io Server version 2.3.0 Jan 11 14:34:53 amp volumio[19607]: info: Loading plugin "lms"... Jan 11 14:34:53 amp volumio[19607]: Forking 3 albumart workers Jan 11 14:34:53 amp volumio[19607]: info: Loading plugin "motherearthradio"... Jan 11 14:34:54 amp volumio[19607]: info: Applying required configuration parameters for plugin motherearthradio Jan 11 14:34:54 amp volumio[19607]: info: [1768160094115] [MotherEarth] API delay: 5 Jan 11 14:34:54 amp volumio[19607]: info: Loading plugin "podcast"... Jan 11 14:34:54 amp volumio[19607]: info: ControllerPodcast::constructor Jan 11 14:34:54 amp volumio[19607]: info: Loading i18n strings for locale en Jan 11 14:34:54 amp volumio[19607]: Updating browse sources language Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:34:54 amp volumio-remote-updater[592]: [2026-01-11 14:34:54] [connect] Successful connection Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::initPlayerControls Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:34:54 amp volumio[19607]: Express server listening on port 3000 Jan 11 14:34:54 amp volumio[19607]: [Metrics] WebUI: 4s 249.05ms Jan 11 14:34:54 amp volumio[19607]: Starting albumart workers Jan 11 14:34:54 amp volumio[19607]: Starting albumart workers Jan 11 14:34:54 amp volumio[19607]: Starting albumart workers Jan 11 14:34:54 amp volumio[19607]: info: CoreStateMachine::resetVolumioState Jan 11 14:34:54 amp volumio[19607]: info: CoreStateMachine::getcurrentVolume Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 14:34:54 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:34:54 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Jan 11 14:34:54 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:54 amp volumio[19607]: info: Volumio Network Manager: Network status updated: 1 Jan 11 14:34:54 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:54 amp go-librespot[19703]: go-librespot daemon starting... Jan 11 14:34:54 amp volumio-remote-updater[592]: [2026-01-11 14:34:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1768160094 101 Jan 11 14:34:54 amp go-librespot[19703]: time="2026-01-11T14:34:54-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:54 amp go-librespot[19703]: time="2026-01-11T14:34:54-05:00" level=debug msg="app state loaded" Jan 11 14:34:54 amp go-librespot[19703]: time="2026-01-11T14:34:54-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:54 amp go-librespot[19703]: time="2026-01-11T14:34:54-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:54 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:54 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:54 amp volumio[19607]: 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 Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:34:54 amp volumio[19607]: info: Reloading queue from file Jan 11 14:34:54 amp volumio[19607]: info: CoreStateMachine::setRepeat true single undefined Jan 11 14:34:54 amp volumio[19607]: info: CoreStateMachine::pushState Jan 11 14:34:54 amp volumio[19607]: info: CorePlayQueue::getTrack 0 Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::volumioPushState Jan 11 14:34:54 amp volumio[19607]: info: CoreStateMachine::setRandom true Jan 11 14:34:54 amp volumio[19607]: info: CoreStateMachine::pushState Jan 11 14:34:54 amp volumio[19607]: info: CorePlayQueue::getTrack 0 Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::volumioPushState Jan 11 14:34:54 amp volumio[19607]: info: Setting Device type: Raspberry PI Jan 11 14:34:54 amp volumio[19607]: info: VolumeController:: Volume=100 Mute =false Jan 11 14:34:54 amp volumio[19607]: info: CoreStateMachine::pushState Jan 11 14:34:54 amp volumio[19607]: info: CorePlayQueue::getTrack 0 Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::volumioPushState Jan 11 14:34:54 amp volumio[19607]: info: CoreStateMachine::updateTrackBlock Jan 11 14:34:54 amp volumio[19607]: info: CorePlayQueue::getTrackBlock Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 14:34:54 amp volumio[19607]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 11 14:34:54 amp volumio[19607]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jan 11 14:34:54 amp volumio[19607]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 11 14:34:54 amp volumio[19607]: info: Completed loading Core Plugins Jan 11 14:34:54 amp volumio[19607]: info: Preparing to generate the ALSA configuration file Jan 11 14:34:54 amp volumio[19607]: info: Asound.conf file unchanged, so no further update is needed Jan 11 14:34:54 amp volumio[19607]: info: Output device has changed, restarting MPD Jan 11 14:34:54 amp sudo[19739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 14:34:54 amp sudo[19739]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:54 amp volumio[19607]: info: Output device has changed, restarting Shairport Sync Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:54 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:34:54 amp sudo[19739]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:55 amp sudo[19745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 14:34:55 amp sudo[19745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:55 amp systemd[1]: Stopping Music Player Daemon... Jan 11 14:34:55 amp volumio[19607]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 14:34:55 amp volumio[19607]: info: ___________ START PLUGINS ___________ Jan 11 14:34:55 amp systemd[1]: mpd.service: Succeeded. Jan 11 14:34:55 amp systemd[1]: Stopped Music Player Daemon. Jan 11 14:34:55 amp volumio[19607]: info: ControllerMpd::onStart: Initializing MPD Jan 11 14:34:55 amp volumio[19607]: info: Creating MPD Configuration file Jan 11 14:34:55 amp sudo[19752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 14:34:55 amp sudo[19752]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:55 amp systemd[1]: Starting Music Player Daemon... Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:34:55 amp volumio[19607]: info: [1768160095085] CoreMusicLibrary::Adding element Media Servers Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:34:55 amp volumio[19607]: info: UPNP Browser: Client initialized successfully Jan 11 14:34:55 amp sudo[19752]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:55 amp sudo[19755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 14:34:55 amp sudo[19755]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:34:55 amp systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 11 14:34:55 amp systemd[1]: mpd.service: Succeeded. Jan 11 14:34:55 amp systemd[1]: Stopped Music Player Daemon. Jan 11 14:34:55 amp systemd[1]: Starting Music Player Daemon... Jan 11 14:34:55 amp volumio[19607]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:34:55 amp volumio[19607]: info: [1768160095187] CoreMusicLibrary::Adding element Last_100 Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:34:55 amp volumio[19607]: info: [1768160095189] CoreMusicLibrary::Adding element Webradio Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:34:55 amp sudo[19760]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 14:34:55 amp sudo[19760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:55 amp volumio[19607]: info: Initializing BBC Radios Jan 11 14:34:55 amp sudo[19760]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: Adding Calm Radio to Browse Sources Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:34:55 amp volumio[19607]: info: [1768160095259] CoreMusicLibrary::Adding element Calm Radio Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:34:55 amp volumio[19607]: Cannot find translation for source Calm Radio Jan 11 14:34:55 amp volumio[19607]: info: Creating Spotify config file Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: [squeezelite_mc] Starting proxy server... Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:34:55 amp volumio[19607]: info: [1768160095364] CoreMusicLibrary::Adding element Mother Earth Radio Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:34:55 amp volumio[19607]: Cannot find translation for source Calm Radio Jan 11 14:34:55 amp volumio[19607]: Cannot find translation for source Mother Earth Radio Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:34:55 amp volumio[19607]: info: [1768160095371] CoreMusicLibrary::Adding element Podcast Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:34:55 amp volumio[19607]: Cannot find translation for source Calm Radio Jan 11 14:34:55 amp volumio[19607]: Cannot find translation for source Mother Earth Radio Jan 11 14:34:55 amp volumio[19607]: Cannot find translation for source Podcast Jan 11 14:34:55 amp volumio[19607]: info: Volumio Calling Home Jan 11 14:34:55 amp sudo[19777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jan 11 14:34:55 amp sudo[19777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:55 amp systemd[1]: Stopping Logitech Media Server Daemon... Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:25:18.9318] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:25:18.9328] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:25:48.9321] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:25:48.9332] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:26:18.9319] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:26:18.9336] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:26:48.9318] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:26:48.9350] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:27:18.9339] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:27:18.9395] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:27:48.9370] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:27:48.9380] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:28:18.9370] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:28:18.9391] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:28:48.9385] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:28:48.9407] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:29:18.9397] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:29:18.9407] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:29:48.9394] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:29:48.9425] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:30:18.9415] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:30:18.9425] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:30:48.9413] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:30:48.9445] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:31:18.9420] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:31:18.9438] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:31:48.9418] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:31:48.9441] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:32:18.9446] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:32:18.9456] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:32:48.9434] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:32:48.9465] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:33:18.9439] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:33:18.9475] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:33:48.9464] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:33:48.9476] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:34:18.9443] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp squeezeboxserver[9402]: [26-01-11 14:34:18.9473] Slim::Plugin::CLI::Plugin::cli_socket_accept (250) Access to CLI is restricted to the local network or localhost: 169.254.47.209 Jan 11 14:34:55 amp sudo[19654]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:55 amp systemd[1]: logitechmediaserver.service: Succeeded. Jan 11 14:34:55 amp systemd[1]: Stopped Logitech Media Server Daemon. Jan 11 14:34:55 amp volumio[19607]: info: [squeezelite_mc] Proxy server started on port 40927 Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 14:34:55 amp volumio[19607]: info: Discovery: adding 8f54fa7c-5571-482e-ac8b-f7e2f68efffc Jan 11 14:34:55 amp volumio[19607]: info: Discovery: Found device Amp Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::volumioGetState Jan 11 14:34:55 amp volumio[19607]: info: CorePlayQueue::getTrack 0 Jan 11 14:34:55 amp volumio[19607]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jan 11 14:34:55 amp systemd[1]: Starting Logitech Media Server Daemon... Jan 11 14:34:55 amp volumio[19607]: info: MPD Permissions set Jan 11 14:34:55 amp volumio[19607]: info: MPD Permissions set Jan 11 14:34:55 amp volumio[19607]: info: VolumeController:: Volume=100 Mute =false Jan 11 14:34:55 amp volumio[19607]: info: CoreStateMachine::pushState Jan 11 14:34:55 amp volumio[19607]: info: CorePlayQueue::getTrack 0 Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::volumioPushState Jan 11 14:34:55 amp systemd[1]: Started Logitech Media Server Daemon. Jan 11 14:34:55 amp sudo[19777]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:55 amp volumio[19607]: info: Spotify config file written Jan 11 14:34:55 amp sudo[19788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 14:34:55 amp volumio[19607]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 11 14:34:55 amp sudo[19788]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:55 amp volumio[19607]: info: logitechmediaserver started Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp go-librespot[19791]: go-librespot daemon starting... Jan 11 14:34:55 amp sudo[19788]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: No need to fix Spotify hosts Jan 11 14:34:55 amp go-librespot[19791]: time="2026-01-11T14:34:55-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:55 amp go-librespot[19791]: time="2026-01-11T14:34:55-05:00" level=debug msg="app state loaded" Jan 11 14:34:55 amp go-librespot[19791]: time="2026-01-11T14:34:55-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:55 amp go-librespot[19791]: time="2026-01-11T14:34:55-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:55 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:55 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:55 amp volumio[19607]: info: VolumeController:: Volume=100 Mute =false Jan 11 14:34:55 amp volumio[19607]: info: CoreStateMachine::pushState Jan 11 14:34:55 amp volumio[19607]: info: CorePlayQueue::getTrack 0 Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::volumioPushState Jan 11 14:34:55 amp volumio[19607]: info: [squeezelite_mc] Server discovery started Jan 11 14:34:55 amp volumio[19607]: info: [squeezelite_mc] Player finder started Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:34:55 amp volumio[19607]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 11 14:34:55 amp volumio[19607]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:34:55 amp volumio[19607]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 11 14:34:55 amp volumio[19607]: at doSend (dgram.js:692:16) Jan 11 14:34:55 amp volumio[19607]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 11 14:34:55 amp volumio[19607]: at afterDns (dgram.js:638:5) Jan 11 14:34:55 amp volumio[19607]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 11 14:34:55 amp volumio[19607]: errno: -101, Jan 11 14:34:55 amp volumio[19607]: code: 'ENETUNREACH', Jan 11 14:34:55 amp volumio[19607]: syscall: 'send', Jan 11 14:34:55 amp volumio[19607]: address: '255.255.255.255', Jan 11 14:34:55 amp volumio[19607]: port: 3483 Jan 11 14:34:55 amp volumio[19607]: } Jan 11 14:34:55 amp volumio[19607]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:34:56 amp mpd[19765]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 14:34:56 amp mpd[19765]: output: No 'audio_output' defined in config file Jan 11 14:34:56 amp mpd[19765]: output: Successfully detected a sndio audio device Jan 11 14:34:56 amp mpd[19765]: zeroconf: No global port, disabling zeroconf Jan 11 14:34:56 amp systemd[1]: Started Music Player Daemon. Jan 11 14:34:56 amp sudo[19755]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:56 amp sudo[19745]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:56 amp sudo[19817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 14:33 Jan 11 14:34:56 amp sudo[19817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:56 amp sudo[19817]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:56 amp volumio-remote-updater[592]: [2026-01-11 14:34:56] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 11 14:34:56 amp volumio-remote-updater[592]: [2026-01-11 14:34:56] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 11 14:34:56 amp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:56 amp systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 11 14:34:56 amp systemd[1]: Started dynamicswap service. Jan 11 14:34:56 amp systemd[1]: dynamicswap.service: Succeeded. Jan 11 14:34:56 amp systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 11 14:34:56 amp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Jan 11 14:34:56 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jan 11 14:34:56 amp systemd[1]: Started dynamicswap service. Jan 11 14:34:56 amp systemd[1]: Stopped Volumio Backend Module. Jan 11 14:34:56 amp systemd[1]: Started Volumio Backend Module. Jan 11 14:34:56 amp systemd[1]: dynamicswap.service: Succeeded. Jan 11 14:34:56 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 14:34:56 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 14:34:56 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 14:34:57 amp volumio[19831]: info: ------------------------------------------- Jan 11 14:34:57 amp volumio[19831]: info: ----- Volumio3 ---- Jan 11 14:34:57 amp volumio[19831]: info: ------------------------------------------- Jan 11 14:34:57 amp volumio[19831]: info: ----- System startup ---- Jan 11 14:34:57 amp volumio[19831]: info: ------------------------------------------- Jan 11 14:34:58 amp volumio[19831]: info: MYVOLUMIO Environment detected Jan 11 14:34:58 amp volumio[19831]: info: Plugin folders cleanup Jan 11 14:34:58 amp volumio[19831]: info: Scanning into folder /volumio/app/plugins/ Jan 11 14:34:58 amp volumio[19831]: info: Scanning category audio_interface Jan 11 14:34:58 amp volumio[19831]: info: Scanning category miscellanea Jan 11 14:34:58 amp volumio[19831]: info: Scanning category music_service Jan 11 14:34:58 amp volumio[19831]: info: Scanning category plugins.json Jan 11 14:34:58 amp volumio[19831]: info: Scanning category system_controller Jan 11 14:34:58 amp volumio[19831]: info: Scanning category user_interface Jan 11 14:34:58 amp volumio[19831]: info: Scanning into folder /data/plugins/ Jan 11 14:34:58 amp volumio[19831]: info: Scanning category music_service Jan 11 14:34:58 amp volumio[19831]: info: Plugin folders cleanup completed Jan 11 14:34:58 amp volumio[19831]: info: ------------------------------------------- Jan 11 14:34:58 amp volumio[19831]: info: ----- Core plugins startup ---- Jan 11 14:34:58 amp volumio[19831]: info: ------------------------------------------- Jan 11 14:34:58 amp volumio[19831]: info: Loading plugins from folder /volumio/app/plugins/ Jan 11 14:34:58 amp volumio[19831]: info: Adding plugin upnp to MyMusic Plugins Jan 11 14:34:58 amp volumio[19831]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 11 14:34:58 amp volumio[19831]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 11 14:34:58 amp volumio[19831]: info: Loading plugins from folder /data/plugins/ Jan 11 14:34:58 amp volumio[19831]: info: Loading plugin "system"... Jan 11 14:34:58 amp volumio[19831]: info: Loading plugin "appearance"... Jan 11 14:34:58 amp volumio[19831]: info: Loading plugin "network"... Jan 11 14:34:58 amp volumio[19831]: info: Refreshing Cached IP Addresses Jan 11 14:34:58 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:34:58 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Jan 11 14:34:58 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:34:58 amp sudo[19858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 14:34:58 amp sudo[19858]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:58 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:34:58 amp volumio[19831]: info: Loading plugin "services"... Jan 11 14:34:58 amp volumio[19831]: info: Loading plugin "alsa_controller"... Jan 11 14:34:58 amp sudo[19861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 14:34:58 amp sudo[19861]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:58 amp sudo[19858]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:58 amp sudo[19861]: pam_unix(sudo:session): session closed for user root Jan 11 14:34:58 amp sudo[19870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 11 14:34:58 amp sudo[19870]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:34:58 amp go-librespot[19860]: go-librespot daemon starting... Jan 11 14:34:58 amp go-librespot[19860]: time="2026-01-11T14:34:58-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:34:58 amp go-librespot[19860]: time="2026-01-11T14:34:58-05:00" level=debug msg="app state loaded" Jan 11 14:34:58 amp go-librespot[19860]: time="2026-01-11T14:34:58-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:34:58 amp go-librespot[19860]: time="2026-01-11T14:34:58-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:34:58 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:34:58 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:34:58 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 14:34:58 amp volumio[19831]: info: Loading plugin "wizard"... Jan 11 14:34:58 amp volumio[19831]: info: Loading plugin "networkfs"... Jan 11 14:34:58 amp volumio[19831]: info: Cannot mount NAS FlacFiles at system boot, trial number 1 ,retrying in 5 seconds Jan 11 14:34:58 amp volumio[19831]: info: Starting Udev Watcher for removable devices Jan 11 14:34:59 amp volumio[19831]: info: Ignoring mount for partition: boot Jan 11 14:34:59 amp volumio[19831]: info: Ignoring mount for partition: volumio Jan 11 14:34:59 amp volumio[19831]: info: Ignoring mount for partition: volumio_data Jan 11 14:34:59 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "volumio_command_line_client"... Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "upnp"... Jan 11 14:34:59 amp volumio[19831]: info: [1768160099014] Starting Upmpd Daemon Jan 11 14:34:59 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "my_music"... Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "mpd"... Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "upnp_browser"... Jan 11 14:34:59 amp volumio[19831]: info: Starting UPNP Browser Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "alarm-clock"... Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "airplay_emulation"... Jan 11 14:34:59 amp volumio[19831]: info: Starting Shairport Sync Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "last_100"... Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "webradio"... Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "i2s_dacs"... Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "volumiodiscovery"... Jan 11 14:34:59 amp volumio[19831]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 14:34:59 amp volumio[19831]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:34:59 amp node[19831]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 14:34:59 amp volumio[19831]: *** WARNING *** For more information see Jan 11 14:34:59 amp volumio[19831]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 14:34:59 amp volumio[19831]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:34:59 amp volumio[19831]: *** WARNING *** For more information see Jan 11 14:34:59 amp node[19831]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:34:59 amp node[19831]: *** WARNING *** For more information see Jan 11 14:34:59 amp node[19831]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 14:34:59 amp node[19831]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:34:59 amp node[19831]: *** WARNING *** For more information see Jan 11 14:34:59 amp volumio[19831]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 11 14:34:59 amp volumio[19831]: info: Discovery: Started advertising with name: Amp Jan 11 14:34:59 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 14:34:59 amp volumio[19831]: info: Loading plugin "calmradio"... Jan 11 14:35:00 amp volumio[19831]: info: Loading plugin "spop"... Jan 11 14:35:00 amp volumio[19831]: info: Loading plugin "squeezelite_mc"... Jan 11 14:35:01 amp volumio[19831]: info: Loading plugin "outputs"... Jan 11 14:35:01 amp volumio[19831]: info: Loading plugin "albumart"... Jan 11 14:35:01 amp volumio[19831]: info: Plugin example_plugin is not enabled Jan 11 14:35:01 amp volumio[19831]: info: Loading plugin "inputs"... Jan 11 14:35:01 amp volumio[19831]: info: Loading plugin "updater_comm"... Jan 11 14:35:01 amp volumio[19831]: info: Plugin mpdemulation is not enabled Jan 11 14:35:01 amp volumio[19831]: info: Loading plugin "rest_api"... Jan 11 14:35:01 amp volumio[19831]: info: Loading plugin "websocket"... Jan 11 14:35:01 amp volumio[19831]: info: Starting Socket.io Server version 2.3.0 Jan 11 14:35:01 amp volumio[19831]: info: Loading plugin "lms"... Jan 11 14:35:01 amp volumio[19831]: info: Loading plugin "motherearthradio"... Jan 11 14:35:01 amp volumio[19831]: Forking 3 albumart workers Jan 11 14:35:01 amp volumio-remote-updater[592]: [2026-01-11 14:35:01] [connect] Successful connection Jan 11 14:35:01 amp volumio[19831]: info: Applying required configuration parameters for plugin motherearthradio Jan 11 14:35:01 amp volumio[19831]: info: [1768160101399] [MotherEarth] API delay: 5 Jan 11 14:35:01 amp volumio[19831]: info: Loading plugin "podcast"... Jan 11 14:35:01 amp volumio[19831]: info: ControllerPodcast::constructor Jan 11 14:35:01 amp volumio[19831]: info: Loading i18n strings for locale en Jan 11 14:35:01 amp volumio[19831]: Updating browse sources language Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::initPlayerControls Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:35:01 amp volumio[19831]: Express server listening on port 3000 Jan 11 14:35:01 amp volumio[19831]: [Metrics] WebUI: 4s 469.32ms Jan 11 14:35:01 amp volumio[19831]: info: CoreStateMachine::resetVolumioState Jan 11 14:35:01 amp volumio[19831]: info: CoreStateMachine::getcurrentVolume Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 14:35:01 amp volumio[19831]: Starting albumart workers Jan 11 14:35:01 amp volumio[19831]: Starting albumart workers Jan 11 14:35:01 amp volumio[19831]: info: Volumio Network Manager: Network status updated: 1 Jan 11 14:35:01 amp volumio[19831]: Starting albumart workers Jan 11 14:35:01 amp volumio-remote-updater[592]: [2026-01-11 14:35:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1768160101 101 Jan 11 14:35:01 amp volumio[19831]: 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 Jan 11 14:35:01 amp volumio[19831]: info: VolumeController:: Volume=100 Mute =false Jan 11 14:35:01 amp volumio[19831]: info: CoreStateMachine::pushState Jan 11 14:35:01 amp volumio[19831]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::volumioPushState Jan 11 14:35:01 amp volumio[19831]: info: CoreStateMachine::updateTrackBlock Jan 11 14:35:01 amp volumio[19831]: info: CorePlayQueue::getTrackBlock Jan 11 14:35:01 amp volumio[19831]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:02 amp volumio[19831]: info: Reloading queue from file Jan 11 14:35:02 amp volumio[19831]: info: CoreStateMachine::setRepeat true single undefined Jan 11 14:35:02 amp volumio[19831]: info: CoreStateMachine::pushState Jan 11 14:35:02 amp volumio[19831]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::volumioPushState Jan 11 14:35:02 amp volumio[19831]: info: CoreStateMachine::setRandom true Jan 11 14:35:02 amp volumio[19831]: info: CoreStateMachine::pushState Jan 11 14:35:02 amp volumio[19831]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::volumioPushState Jan 11 14:35:02 amp volumio[19831]: info: Setting Device type: Raspberry PI Jan 11 14:35:02 amp volumio[19831]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 11 14:35:02 amp volumio[19831]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jan 11 14:35:02 amp volumio[19831]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 11 14:35:02 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:35:02 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Jan 11 14:35:02 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:35:02 amp volumio[19831]: info: Completed loading Core Plugins Jan 11 14:35:02 amp volumio[19831]: info: Preparing to generate the ALSA configuration file Jan 11 14:35:02 amp volumio[19831]: info: VolumeController:: Volume=100 Mute =false Jan 11 14:35:02 amp volumio[19831]: info: CoreStateMachine::pushState Jan 11 14:35:02 amp volumio[19831]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::volumioPushState Jan 11 14:35:02 amp volumio[19831]: info: Asound.conf file unchanged, so no further update is needed Jan 11 14:35:02 amp volumio[19831]: info: Output device has changed, restarting MPD Jan 11 14:35:02 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:35:02 amp go-librespot[19934]: go-librespot daemon starting... Jan 11 14:35:02 amp go-librespot[19934]: time="2026-01-11T14:35:02-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:35:02 amp go-librespot[19934]: time="2026-01-11T14:35:02-05:00" level=debug msg="app state loaded" Jan 11 14:35:02 amp go-librespot[19934]: time="2026-01-11T14:35:02-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:35:02 amp volumio[19831]: info: Output device has changed, restarting Shairport Sync Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:02 amp go-librespot[19934]: time="2026-01-11T14:35:02-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:35:02 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:35:02 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:35:02 amp sudo[19944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 14:35:02 amp sudo[19944]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:02 amp sudo[19946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 14:35:02 amp sudo[19946]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:02 amp sudo[19944]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:02 amp systemd[1]: Stopping Music Player Daemon... Jan 11 14:35:02 amp systemd[1]: mpd.service: Succeeded. Jan 11 14:35:02 amp systemd[1]: Stopped Music Player Daemon. Jan 11 14:35:02 amp volumio[19831]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 14:35:02 amp volumio[19831]: info: ___________ START PLUGINS ___________ Jan 11 14:35:02 amp volumio[19831]: info: ControllerMpd::onStart: Initializing MPD Jan 11 14:35:02 amp volumio[19831]: info: Creating MPD Configuration file Jan 11 14:35:02 amp sudo[19962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 14:35:02 amp sudo[19962]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:02 amp volumio[19831]: info: [1768160102345] CoreMusicLibrary::Adding element Media Servers Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:02 amp sudo[19962]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:02 amp sudo[19969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 14:35:02 amp systemd[1]: Starting Music Player Daemon... Jan 11 14:35:02 amp sudo[19969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:02 amp volumio[19831]: info: UPNP Browser: Client initialized successfully Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:02 amp systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 11 14:35:02 amp systemd[1]: mpd.service: Succeeded. Jan 11 14:35:02 amp systemd[1]: Stopped Music Player Daemon. Jan 11 14:35:02 amp systemd[1]: Starting Music Player Daemon... Jan 11 14:35:02 amp volumio[19831]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:02 amp sudo[19984]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 14:35:02 amp sudo[19984]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:02 amp sudo[19984]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:02 amp volumio[19831]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:02 amp volumio[19831]: info: [1768160102482] CoreMusicLibrary::Adding element Last_100 Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:02 amp volumio[19831]: info: [1768160102486] CoreMusicLibrary::Adding element Webradio Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 14:35:02 amp volumio[19831]: info: Initializing BBC Radios Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:02 amp volumio[19831]: info: Adding Calm Radio to Browse Sources Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:02 amp volumio[19831]: info: [1768160102555] CoreMusicLibrary::Adding element Calm Radio Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:02 amp volumio[19831]: Cannot find translation for source Calm Radio Jan 11 14:35:02 amp volumio[19831]: info: Creating Spotify config file Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:02 amp volumio[19831]: info: [squeezelite_mc] Starting proxy server... Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:02 amp volumio[19831]: info: [1768160102660] CoreMusicLibrary::Adding element Mother Earth Radio Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:02 amp volumio[19831]: Cannot find translation for source Calm Radio Jan 11 14:35:02 amp volumio[19831]: Cannot find translation for source Mother Earth Radio Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:02 amp volumio[19831]: info: [1768160102667] CoreMusicLibrary::Adding element Podcast Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:02 amp volumio[19831]: Cannot find translation for source Calm Radio Jan 11 14:35:02 amp volumio[19831]: Cannot find translation for source Mother Earth Radio Jan 11 14:35:02 amp volumio[19831]: Cannot find translation for source Podcast Jan 11 14:35:02 amp volumio[19831]: info: Volumio Calling Home Jan 11 14:35:02 amp sudo[20003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jan 11 14:35:02 amp sudo[20003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:02 amp systemd[1]: Stopping Logitech Media Server Daemon... Jan 11 14:35:02 amp squeezeboxserver[19785]: [26-01-11 14:34:57.7941] 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 Jan 11 14:35:02 amp squeezeboxserver[19785]: [26-01-11 14:34:58.1606] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Jan 11 14:35:02 amp squeezeboxserver[19785]: [26-01-11 14:34:58.2980] Slim::Utils::Misc::msg (1325) Warning: [14:34:58.2977] 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 Jan 11 14:35:02 amp volumio[19831]: info: [squeezelite_mc] Proxy server started on port 44671 Jan 11 14:35:02 amp systemd[1]: logitechmediaserver.service: Succeeded. Jan 11 14:35:02 amp systemd[1]: Stopped Logitech Media Server Daemon. Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 14:35:02 amp volumio[19831]: info: Discovery: adding 8f54fa7c-5571-482e-ac8b-f7e2f68efffc Jan 11 14:35:02 amp volumio[19831]: info: Discovery: Found device Amp Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::volumioGetState Jan 11 14:35:02 amp volumio[19831]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:02 amp volumio[19831]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jan 11 14:35:02 amp volumio[19831]: info: MPD Permissions set Jan 11 14:35:02 amp systemd[1]: Starting Logitech Media Server Daemon... Jan 11 14:35:02 amp systemd[1]: Started Logitech Media Server Daemon. Jan 11 14:35:02 amp sudo[20003]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:02 amp volumio[19831]: info: Spotify config file written Jan 11 14:35:02 amp sudo[20014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 14:35:02 amp sudo[20014]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:02 amp volumio[19831]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:02 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:35:02 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:35:02 amp sudo[20014]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:02 amp go-librespot[20017]: go-librespot daemon starting... Jan 11 14:35:02 amp ntpd[975]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 11 14:35:02 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:03 amp go-librespot[20017]: time="2026-01-11T14:35:03-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:35:03 amp go-librespot[20017]: time="2026-01-11T14:35:03-05:00" level=debug msg="app state loaded" Jan 11 14:35:03 amp go-librespot[20017]: time="2026-01-11T14:35:03-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:35:03 amp go-librespot[20017]: time="2026-01-11T14:35:03-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:35:03 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:35:03 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: No need to fix Spotify hosts Jan 11 14:35:03 amp volumio[19831]: info: VolumeController:: Volume=100 Mute =false Jan 11 14:35:03 amp volumio[19831]: info: CoreStateMachine::pushState Jan 11 14:35:03 amp volumio[19831]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::volumioPushState Jan 11 14:35:03 amp volumio[19831]: info: [squeezelite_mc] Server discovery started Jan 11 14:35:03 amp volumio[19831]: info: [squeezelite_mc] Player finder started Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:35:03 amp volumio[19831]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 11 14:35:03 amp sudo[19870]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:03 amp volumio[19831]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:35:03 amp volumio[19831]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 11 14:35:03 amp volumio[19831]: at doSend (dgram.js:692:16) Jan 11 14:35:03 amp volumio[19831]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 11 14:35:03 amp volumio[19831]: at afterDns (dgram.js:638:5) Jan 11 14:35:03 amp volumio[19831]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 11 14:35:03 amp volumio[19831]: errno: -101, Jan 11 14:35:03 amp volumio[19831]: code: 'ENETUNREACH', Jan 11 14:35:03 amp volumio[19831]: syscall: 'send', Jan 11 14:35:03 amp volumio[19831]: address: '255.255.255.255', Jan 11 14:35:03 amp volumio[19831]: port: 3483 Jan 11 14:35:03 amp volumio[19831]: } Jan 11 14:35:03 amp volumio[19831]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:35:03 amp mpd[19991]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 14:35:03 amp mpd[19991]: output: No 'audio_output' defined in config file Jan 11 14:35:03 amp mpd[19991]: output: Successfully detected a sndio audio device Jan 11 14:35:03 amp mpd[19991]: zeroconf: No global port, disabling zeroconf Jan 11 14:35:03 amp systemd[1]: Started Music Player Daemon. Jan 11 14:35:03 amp sudo[19969]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:03 amp sudo[19946]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:03 amp sudo[20044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 14:34 Jan 11 14:35:03 amp sudo[20044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:03 amp sudo[20044]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:03 amp volumio-remote-updater[592]: [2026-01-11 14:35:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 11 14:35:03 amp volumio-remote-updater[592]: [2026-01-11 14:35:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 11 14:35:03 amp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:35:03 amp systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 11 14:35:03 amp systemd[1]: Started dynamicswap service. Jan 11 14:35:03 amp systemd[1]: dynamicswap.service: Succeeded. Jan 11 14:35:03 amp systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 11 14:35:03 amp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. Jan 11 14:35:03 amp systemd[1]: Stopped Volumio Backend Module. Jan 11 14:35:03 amp systemd[1]: Started Volumio Backend Module. Jan 11 14:35:03 amp systemd[1]: Started dynamicswap service. Jan 11 14:35:03 amp systemd[1]: dynamicswap.service: Succeeded. Jan 11 14:35:04 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jan 11 14:35:04 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 14:35:04 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 14:35:04 amp kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 14:35:04 amp volumio[20057]: info: ------------------------------------------- Jan 11 14:35:04 amp volumio[20057]: info: ----- Volumio3 ---- Jan 11 14:35:04 amp volumio[20057]: info: ------------------------------------------- Jan 11 14:35:04 amp volumio[20057]: info: ----- System startup ---- Jan 11 14:35:04 amp volumio[20057]: info: ------------------------------------------- Jan 11 14:35:05 amp volumio[20057]: info: MYVOLUMIO Environment detected Jan 11 14:35:05 amp volumio[20057]: info: Plugin folders cleanup Jan 11 14:35:05 amp volumio[20057]: info: Scanning into folder /volumio/app/plugins/ Jan 11 14:35:05 amp volumio[20057]: info: Scanning category audio_interface Jan 11 14:35:05 amp volumio[20057]: info: Scanning category miscellanea Jan 11 14:35:05 amp volumio[20057]: info: Scanning category music_service Jan 11 14:35:05 amp volumio[20057]: info: Scanning category plugins.json Jan 11 14:35:05 amp volumio[20057]: info: Scanning category system_controller Jan 11 14:35:05 amp volumio[20057]: info: Scanning category user_interface Jan 11 14:35:05 amp volumio[20057]: info: Scanning into folder /data/plugins/ Jan 11 14:35:05 amp volumio[20057]: info: Scanning category music_service Jan 11 14:35:05 amp volumio[20057]: info: Plugin folders cleanup completed Jan 11 14:35:05 amp volumio[20057]: info: ------------------------------------------- Jan 11 14:35:05 amp volumio[20057]: info: ----- Core plugins startup ---- Jan 11 14:35:05 amp volumio[20057]: info: ------------------------------------------- Jan 11 14:35:05 amp volumio[20057]: info: Loading plugins from folder /volumio/app/plugins/ Jan 11 14:35:05 amp volumio[20057]: info: Adding plugin upnp to MyMusic Plugins Jan 11 14:35:05 amp volumio[20057]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 11 14:35:05 amp volumio[20057]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 11 14:35:05 amp volumio[20057]: info: Loading plugins from folder /data/plugins/ Jan 11 14:35:05 amp volumio[20057]: info: Loading plugin "system"... Jan 11 14:35:05 amp volumio[20057]: info: Loading plugin "appearance"... Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "network"... Jan 11 14:35:06 amp volumio[20057]: info: Refreshing Cached IP Addresses Jan 11 14:35:06 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:35:06 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Jan 11 14:35:06 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:35:06 amp sudo[20087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 14:35:06 amp sudo[20087]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "services"... Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "alsa_controller"... Jan 11 14:35:06 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:35:06 amp sudo[20087]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:06 amp sudo[20090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 14:35:06 amp go-librespot[20088]: go-librespot daemon starting... Jan 11 14:35:06 amp sudo[20090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:06 amp sudo[20090]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:06 amp sudo[20099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 11 14:35:06 amp sudo[20099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:06 amp go-librespot[20088]: time="2026-01-11T14:35:06-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:35:06 amp go-librespot[20088]: time="2026-01-11T14:35:06-05:00" level=debug msg="app state loaded" Jan 11 14:35:06 amp go-librespot[20088]: time="2026-01-11T14:35:06-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:35:06 amp go-librespot[20088]: time="2026-01-11T14:35:06-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:35:06 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:35:06 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:35:06 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "wizard"... Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "networkfs"... Jan 11 14:35:06 amp volumio[20057]: info: Cannot mount NAS FlacFiles at system boot, trial number 1 ,retrying in 5 seconds Jan 11 14:35:06 amp volumio[20057]: info: Starting Udev Watcher for removable devices Jan 11 14:35:06 amp volumio[20057]: info: Ignoring mount for partition: boot Jan 11 14:35:06 amp volumio[20057]: info: Ignoring mount for partition: volumio Jan 11 14:35:06 amp volumio[20057]: info: Ignoring mount for partition: volumio_data Jan 11 14:35:06 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "volumio_command_line_client"... Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "upnp"... Jan 11 14:35:06 amp volumio[20057]: info: [1768160106280] Starting Upmpd Daemon Jan 11 14:35:06 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "my_music"... Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "mpd"... Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "upnp_browser"... Jan 11 14:35:06 amp volumio[20057]: info: Starting UPNP Browser Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "alarm-clock"... Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "airplay_emulation"... Jan 11 14:35:06 amp volumio[20057]: info: Starting Shairport Sync Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "last_100"... Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "webradio"... Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "i2s_dacs"... Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "volumiodiscovery"... Jan 11 14:35:06 amp volumio[20057]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 14:35:06 amp volumio[20057]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:35:06 amp node[20057]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 14:35:06 amp volumio[20057]: *** WARNING *** For more information see Jan 11 14:35:06 amp volumio[20057]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 14:35:06 amp volumio[20057]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:35:06 amp volumio[20057]: *** WARNING *** For more information see Jan 11 14:35:06 amp node[20057]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:35:06 amp node[20057]: *** WARNING *** For more information see Jan 11 14:35:06 amp node[20057]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 14:35:06 amp node[20057]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 14:35:06 amp node[20057]: *** WARNING *** For more information see Jan 11 14:35:06 amp volumio[20057]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 11 14:35:06 amp volumio[20057]: info: Discovery: Started advertising with name: Amp Jan 11 14:35:06 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 14:35:06 amp volumio[20057]: info: Loading plugin "calmradio"... Jan 11 14:35:07 amp volumio[20057]: info: Loading plugin "spop"... Jan 11 14:35:07 amp volumio[20057]: info: Loading plugin "squeezelite_mc"... Jan 11 14:35:08 amp volumio[20057]: info: Loading plugin "outputs"... Jan 11 14:35:08 amp volumio[20057]: info: Loading plugin "albumart"... Jan 11 14:35:08 amp volumio[20057]: info: Plugin example_plugin is not enabled Jan 11 14:35:08 amp volumio[20057]: info: Loading plugin "inputs"... Jan 11 14:35:08 amp volumio[20057]: info: Loading plugin "updater_comm"... Jan 11 14:35:08 amp volumio[20057]: info: Plugin mpdemulation is not enabled Jan 11 14:35:08 amp volumio[20057]: info: Loading plugin "rest_api"... Jan 11 14:35:08 amp volumio[20057]: info: Loading plugin "websocket"... Jan 11 14:35:08 amp volumio[20057]: info: Starting Socket.io Server version 2.3.0 Jan 11 14:35:08 amp volumio[20057]: info: Loading plugin "lms"... Jan 11 14:35:08 amp volumio[20057]: info: Loading plugin "motherearthradio"... Jan 11 14:35:08 amp volumio[20057]: Forking 3 albumart workers Jan 11 14:35:08 amp volumio-remote-updater[592]: [2026-01-11 14:35:08] [connect] Successful connection Jan 11 14:35:08 amp volumio[20057]: info: Applying required configuration parameters for plugin motherearthradio Jan 11 14:35:08 amp volumio[20057]: info: [1768160108727] [MotherEarth] API delay: 5 Jan 11 14:35:08 amp volumio[20057]: info: Loading plugin "podcast"... Jan 11 14:35:08 amp volumio[20057]: info: ControllerPodcast::constructor Jan 11 14:35:08 amp volumio[20057]: info: Loading i18n strings for locale en Jan 11 14:35:08 amp volumio[20057]: Updating browse sources language Jan 11 14:35:08 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::initPlayerControls Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:35:09 amp volumio[20057]: Express server listening on port 3000 Jan 11 14:35:09 amp volumio[20057]: [Metrics] WebUI: 4s 555.45ms Jan 11 14:35:09 amp volumio[20057]: Starting albumart workers Jan 11 14:35:09 amp volumio[20057]: info: CoreStateMachine::resetVolumioState Jan 11 14:35:09 amp volumio[20057]: info: CoreStateMachine::getcurrentVolume Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 14:35:09 amp volumio[20057]: Starting albumart workers Jan 11 14:35:09 amp volumio[20057]: Starting albumart workers Jan 11 14:35:09 amp volumio[20057]: info: Volumio Network Manager: Network status updated: 1 Jan 11 14:35:09 amp volumio-remote-updater[592]: [2026-01-11 14:35:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1768160108 101 Jan 11 14:35:09 amp volumio[20057]: 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 Jan 11 14:35:09 amp systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 11 14:35:09 amp systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Jan 11 14:35:09 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: Reloading queue from file Jan 11 14:35:09 amp volumio[20057]: info: CoreStateMachine::setRepeat true single undefined Jan 11 14:35:09 amp volumio[20057]: info: CoreStateMachine::pushState Jan 11 14:35:09 amp volumio[20057]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioPushState Jan 11 14:35:09 amp volumio[20057]: info: CoreStateMachine::setRandom true Jan 11 14:35:09 amp volumio[20057]: info: CoreStateMachine::pushState Jan 11 14:35:09 amp volumio[20057]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioPushState Jan 11 14:35:09 amp volumio[20057]: info: Setting Device type: Raspberry PI Jan 11 14:35:09 amp volumio[20057]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 11 14:35:09 amp volumio[20057]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jan 11 14:35:09 amp volumio[20057]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 11 14:35:09 amp volumio[20057]: info: VolumeController:: Volume=100 Mute =false Jan 11 14:35:09 amp volumio[20057]: info: CoreStateMachine::pushState Jan 11 14:35:09 amp volumio[20057]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioPushState Jan 11 14:35:09 amp volumio[20057]: info: CoreStateMachine::updateTrackBlock Jan 11 14:35:09 amp volumio[20057]: info: CorePlayQueue::getTrackBlock Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 14:35:09 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:35:09 amp go-librespot[20158]: go-librespot daemon starting... Jan 11 14:35:09 amp go-librespot[20158]: time="2026-01-11T14:35:09-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:35:09 amp go-librespot[20158]: time="2026-01-11T14:35:09-05:00" level=debug msg="app state loaded" Jan 11 14:35:09 amp go-librespot[20158]: time="2026-01-11T14:35:09-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:35:09 amp go-librespot[20158]: time="2026-01-11T14:35:09-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:35:09 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:35:09 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:35:09 amp volumio[20057]: info: Completed loading Core Plugins Jan 11 14:35:09 amp volumio[20057]: info: Preparing to generate the ALSA configuration file Jan 11 14:35:09 amp volumio[20057]: info: Discovery: adding 8f54fa7c-5571-482e-ac8b-f7e2f68efffc Jan 11 14:35:09 amp volumio[20057]: info: Discovery: Found device Amp Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioGetState Jan 11 14:35:09 amp volumio[20057]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:09 amp volumio[20057]: info: Asound.conf file unchanged, so no further update is needed Jan 11 14:35:09 amp volumio[20057]: info: Output device has changed, restarting MPD Jan 11 14:35:09 amp sudo[20194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 14:35:09 amp sudo[20194]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:09 amp sudo[20194]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:09 amp volumio[20057]: info: Output device has changed, restarting Shairport Sync Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:09 amp sudo[20197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 14:35:09 amp sudo[20197]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:09 amp systemd[1]: Stopping Music Player Daemon... Jan 11 14:35:09 amp volumio[20057]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 14:35:09 amp volumio[20057]: info: ___________ START PLUGINS ___________ Jan 11 14:35:09 amp volumio[20057]: info: ControllerMpd::onStart: Initializing MPD Jan 11 14:35:09 amp volumio[20057]: info: Creating MPD Configuration file Jan 11 14:35:09 amp systemd[1]: mpd.service: Succeeded. Jan 11 14:35:09 amp systemd[1]: Stopped Music Player Daemon. Jan 11 14:35:09 amp sudo[20204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 14:35:09 amp sudo[20204]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:09 amp systemd[1]: Starting Music Player Daemon... Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:09 amp volumio[20057]: info: [1768160109696] CoreMusicLibrary::Adding element Media Servers Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:09 amp sudo[20204]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:09 amp volumio[20057]: info: UPNP Browser: Client initialized successfully Jan 11 14:35:09 amp sudo[20207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 14:35:09 amp sudo[20207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:09 amp systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 11 14:35:09 amp systemd[1]: mpd.service: Succeeded. Jan 11 14:35:09 amp systemd[1]: Stopped Music Player Daemon. Jan 11 14:35:09 amp systemd[1]: Starting Music Player Daemon... Jan 11 14:35:09 amp volumio[20057]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:09 amp volumio[20057]: info: [1768160109793] CoreMusicLibrary::Adding element Last_100 Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:09 amp volumio[20057]: info: [1768160109795] CoreMusicLibrary::Adding element Webradio Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 14:35:09 amp sudo[20210]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 14:35:09 amp sudo[20210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:09 amp volumio[20057]: info: Initializing BBC Radios Jan 11 14:35:09 amp sudo[20210]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: Adding Calm Radio to Browse Sources Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:09 amp volumio[20057]: info: [1768160109862] CoreMusicLibrary::Adding element Calm Radio Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:09 amp volumio[20057]: Cannot find translation for source Calm Radio Jan 11 14:35:09 amp volumio[20057]: info: Creating Spotify config file Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:09 amp volumio[20057]: info: [squeezelite_mc] Starting proxy server... Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:09 amp volumio[20057]: info: [1768160109988] CoreMusicLibrary::Adding element Mother Earth Radio Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:09 amp volumio[20057]: Cannot find translation for source Calm Radio Jan 11 14:35:09 amp volumio[20057]: Cannot find translation for source Mother Earth Radio Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 14:35:09 amp volumio[20057]: info: [1768160109996] CoreMusicLibrary::Adding element Podcast Jan 11 14:35:09 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 14:35:09 amp volumio[20057]: Cannot find translation for source Calm Radio Jan 11 14:35:09 amp volumio[20057]: Cannot find translation for source Mother Earth Radio Jan 11 14:35:09 amp volumio[20057]: Cannot find translation for source Podcast Jan 11 14:35:09 amp ntpd[975]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 11 14:35:10 amp volumio[20057]: info: Volumio Calling Home Jan 11 14:35:10 amp sudo[20255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jan 11 14:35:10 amp sudo[20255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:10 amp systemd[1]: Stopping Logitech Media Server Daemon... Jan 11 14:35:10 amp squeezeboxserver[20012]: [26-01-11 14:35:05.0835] 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 Jan 11 14:35:10 amp squeezeboxserver[20012]: [26-01-11 14:35:05.4459] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Jan 11 14:35:10 amp squeezeboxserver[20012]: [26-01-11 14:35:05.5810] Slim::Utils::Misc::msg (1325) Warning: [14:35:05.5807] 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 Jan 11 14:35:10 amp volumio[20057]: info: [squeezelite_mc] Proxy server started on port 32911 Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 14:35:10 amp systemd[1]: logitechmediaserver.service: Succeeded. Jan 11 14:35:10 amp systemd[1]: Stopped Logitech Media Server Daemon. Jan 11 14:35:10 amp volumio[20057]: info: VolumeController:: Volume=100 Mute =false Jan 11 14:35:10 amp volumio[20057]: info: CoreStateMachine::pushState Jan 11 14:35:10 amp volumio[20057]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::volumioPushState Jan 11 14:35:10 amp volumio[20057]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Jan 11 14:35:10 amp systemd[1]: Starting Logitech Media Server Daemon... Jan 11 14:35:10 amp volumio[20057]: info: MPD Permissions set Jan 11 14:35:10 amp volumio[20057]: info: MPD Permissions set Jan 11 14:35:10 amp systemd[1]: Started Logitech Media Server Daemon. Jan 11 14:35:10 amp sudo[20255]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:10 amp volumio[20057]: info: Spotify config file written Jan 11 14:35:10 amp volumio[20057]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp sudo[20285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 14:35:10 amp sudo[20285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp systemd[1]: Stopped go-librespot Daemon. Jan 11 14:35:10 amp systemd[1]: Started go-librespot Daemon. Jan 11 14:35:10 amp sudo[20285]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp go-librespot[20291]: go-librespot daemon starting... Jan 11 14:35:10 amp go-librespot[20291]: time="2026-01-11T14:35:10-05:00" level=info msg="running go-librespot 0.4.0" Jan 11 14:35:10 amp go-librespot[20291]: time="2026-01-11T14:35:10-05:00" level=debug msg="app state loaded" Jan 11 14:35:10 amp go-librespot[20291]: time="2026-01-11T14:35:10-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp go-librespot[20291]: time="2026-01-11T14:35:10-05:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jan 11 14:35:10 amp systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 11 14:35:10 amp volumio[20057]: info: No need to fix Spotify hosts Jan 11 14:35:10 amp systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 14:35:10 amp volumio[20057]: info: VolumeController:: Volume=100 Mute =false Jan 11 14:35:10 amp volumio[20057]: info: CoreStateMachine::pushState Jan 11 14:35:10 amp volumio[20057]: info: CorePlayQueue::getTrack 0 Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::volumioPushState Jan 11 14:35:10 amp volumio[20057]: info: [squeezelite_mc] Server discovery started Jan 11 14:35:10 amp volumio[20057]: info: [squeezelite_mc] Player finder started Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:35:10 amp volumio[20057]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jan 11 14:35:10 amp volumio[20057]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:35:10 amp volumio[20057]: Error: send ENETUNREACH 255.255.255.255:3483 Jan 11 14:35:10 amp volumio[20057]: at doSend (dgram.js:692:16) Jan 11 14:35:10 amp volumio[20057]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jan 11 14:35:10 amp volumio[20057]: at afterDns (dgram.js:638:5) Jan 11 14:35:10 amp volumio[20057]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jan 11 14:35:10 amp volumio[20057]: errno: -101, Jan 11 14:35:10 amp volumio[20057]: code: 'ENETUNREACH', Jan 11 14:35:10 amp volumio[20057]: syscall: 'send', Jan 11 14:35:10 amp volumio[20057]: address: '255.255.255.255', Jan 11 14:35:10 amp volumio[20057]: port: 3483 Jan 11 14:35:10 amp volumio[20057]: } Jan 11 14:35:10 amp volumio[20057]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:35:10 amp dhcpcd[1039]: eth0: dhcp_sendudp: Network is unreachable Jan 11 14:35:10 amp dhcpcd[1039]: eth0: adding route to 192.168.1.0/24 Jan 11 14:35:10 amp dhcpcd[1039]: eth0: adding default route via 192.168.1.1 Jan 11 14:35:10 amp mpd[20217]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 14:35:10 amp mpd[20217]: output: No 'audio_output' defined in config file Jan 11 14:35:10 amp mpd[20217]: output: Successfully detected a sndio audio device Jan 11 14:35:10 amp mpd[20217]: zeroconf: No global port, disabling zeroconf Jan 11 14:35:10 amp systemd[1]: Started Music Player Daemon. Jan 11 14:35:10 amp sudo[20197]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:10 amp sudo[20207]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:10 amp sudo[20099]: pam_unix(sudo:session): session closed for user root Jan 11 14:35:10 amp sudo[20340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 14:34 Jan 11 14:35:10 amp sudo[20340]: pam_unix(sudo:session): session opened for user root by (uid=0) 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"