Feb 12 17:27:00 schallmeister volumio[1945]: info: Getting Spotify volume Feb 12 17:27:00 schallmeister volumio[1945]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 17:27:00 schallmeister volumio[1945]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 17:27:00 schallmeister volumio[1945]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 12 17:27:00 schallmeister volumio[1945]: errno: -111, Feb 12 17:27:00 schallmeister volumio[1945]: code: 'ECONNREFUSED', Feb 12 17:27:00 schallmeister volumio[1945]: syscall: 'connect', Feb 12 17:27:00 schallmeister volumio[1945]: address: '127.0.0.1', Feb 12 17:27:00 schallmeister volumio[1945]: port: 9879, Feb 12 17:27:00 schallmeister volumio[1945]: response: undefined Feb 12 17:27:00 schallmeister volumio[1945]: } Feb 12 17:27:00 schallmeister volumio[1945]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 17:27:01 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2555. Feb 12 17:27:01 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:01 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:01 schallmeister go-librespot[11784]: go-librespot daemon starting... Feb 12 17:27:01 schallmeister go-librespot[11785]: time="2026-02-12T17:27:01+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:01 schallmeister go-librespot[11785]: time="2026-02-12T17:27:01+01:00" level=debug msg="app state loaded" Feb 12 17:27:01 schallmeister go-librespot[11785]: time="2026-02-12T17:27:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:01 schallmeister go-librespot[11785]: time="2026-02-12T17:27:01+01: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" Feb 12 17:27:01 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:01 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:03 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read stat ret = -110 Feb 12 17:27:03 schallmeister avahi-daemon[616]: New relevant interface wlan0.IPv4 for mDNS. Feb 12 17:27:03 schallmeister avahi-daemon[616]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Feb 12 17:27:03 schallmeister welcome[11770]: Resolved ip:[1] 192.168.211.1 Feb 12 17:27:03 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -110 Feb 12 17:27:03 schallmeister sudo[11765]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 12 17:27:03 schallmeister sudo[11765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 17:27:03 schallmeister sudo[11795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 17:26' Feb 12 17:27:03 schallmeister sudo[11795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:03 schallmeister systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 12 17:27:03 schallmeister systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 12 17:27:03 schallmeister sudo[11765]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:03 schallmeister wireless.js[636]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Feb 12 17:27:03 schallmeister wireless.js[636]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 2ms Feb 12 17:27:03 schallmeister wireless.js[636]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:19:b3:a3) Feb 12 17:27:03 schallmeister sudo[11806]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 12 17:27:03 schallmeister sudo[11806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:03 schallmeister dhcpcd[723]: wlan0: carrier acquired Feb 12 17:27:03 schallmeister hostapd[11769]: wlan0: interface state UNINITIALIZED->ENABLED Feb 12 17:27:03 schallmeister hostapd[11769]: wlan0: AP-ENABLED Feb 12 17:27:03 schallmeister systemd[1]: hostapd.service: Failed to parse PID from file /run/hostapd.pid: Invalid argument Feb 12 17:27:03 schallmeister dhcpcd[723]: wlan0: IAID eb:19:b3:a3 Feb 12 17:27:03 schallmeister sudo[11806]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:03 schallmeister systemd[1]: Started hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Feb 12 17:27:03 schallmeister sudo[11795]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:03 schallmeister sudo[11817]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 12 17:27:03 schallmeister sudo[11817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:03 schallmeister systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Feb 12 17:27:04 schallmeister dnsmasq[11830]: started, version 2.90 cachesize 150 Feb 12 17:27:04 schallmeister dnsmasq[11830]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Feb 12 17:27:04 schallmeister dnsmasq-dhcp[11830]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Feb 12 17:27:04 schallmeister dnsmasq[11830]: reading /etc/resolv.conf Feb 12 17:27:04 schallmeister dnsmasq[11830]: using nameserver 208.67.222.222#53 Feb 12 17:27:04 schallmeister dnsmasq[11830]: using nameserver 208.67.220.220#53 Feb 12 17:27:04 schallmeister dnsmasq[11830]: read /etc/hosts - 4 names Feb 12 17:27:04 schallmeister systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Feb 12 17:27:04 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:04] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 12 17:27:04 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:04] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 12 17:27:04 schallmeister volumio5-onboarding[1481]: time=2026-02-12T17:27:04.228+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="read tcp 127.0.0.1:40788->127.0.0.1:3000: read: connection reset by peer" Feb 12 17:27:04 schallmeister volumio5-onboarding[1481]: time=2026-02-12T17:27:04.232+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 12 17:27:04 schallmeister systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:04 schallmeister systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 12 17:27:04 schallmeister systemd[1]: volumio.service: Consumed 1min 16.101s CPU time. Feb 12 17:27:04 schallmeister systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 17:27:04 schallmeister systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Feb 12 17:27:04 schallmeister systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 17:27:04 schallmeister systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 12 17:27:04 schallmeister systemd[1]: volumio.service: Consumed 1min 16.101s CPU time. Feb 12 17:27:04 schallmeister systemd[1]: Started volumio.service - Volumio Backend Module. Feb 12 17:27:04 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000490. ret = -110 Feb 12 17:27:04 schallmeister dhcpcd[723]: wlan0: soliciting a DHCP lease Feb 12 17:27:04 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2556. Feb 12 17:27:04 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:04 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:04 schallmeister go-librespot[11865]: go-librespot daemon starting... Feb 12 17:27:04 schallmeister dhcpcd[723]: wlan0: soliciting an IPv6 router Feb 12 17:27:04 schallmeister go-librespot[11866]: time="2026-02-12T17:27:04+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:04 schallmeister go-librespot[11866]: time="2026-02-12T17:27:04+01:00" level=debug msg="app state loaded" Feb 12 17:27:04 schallmeister go-librespot[11866]: time="2026-02-12T17:27:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:04 schallmeister go-librespot[11866]: time="2026-02-12T17:27:04+01: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" Feb 12 17:27:04 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:04 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:05 schallmeister volumio5-onboarding[1481]: time=2026-02-12T17:27:05.235+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 12 17:27:06 schallmeister volumio5-onboarding[1481]: time=2026-02-12T17:27:06.237+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 12 17:27:06 schallmeister systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2009. Feb 12 17:27:06 schallmeister systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:27:07 schallmeister systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:27:07 schallmeister upmpdcli[11874]: Could not open config: /tmp/upmpdcli.conf Feb 12 17:27:07 schallmeister systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:07 schallmeister systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 12 17:27:07 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2557. Feb 12 17:27:07 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:08 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:08 schallmeister go-librespot[11881]: go-librespot daemon starting... Feb 12 17:27:08 schallmeister volumio[11854]: info: ------------------------------------------- Feb 12 17:27:08 schallmeister volumio[11854]: info: ----- Volumio3 ---- Feb 12 17:27:08 schallmeister volumio[11854]: info: ------------------------------------------- Feb 12 17:27:08 schallmeister volumio[11854]: info: ----- System startup ---- Feb 12 17:27:08 schallmeister volumio[11854]: info: ------------------------------------------- Feb 12 17:27:08 schallmeister go-librespot[11882]: time="2026-02-12T17:27:08+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:08 schallmeister go-librespot[11882]: time="2026-02-12T17:27:08+01:00" level=debug msg="app state loaded" Feb 12 17:27:08 schallmeister go-librespot[11882]: time="2026-02-12T17:27:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:08 schallmeister go-librespot[11882]: time="2026-02-12T17:27:08+01: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" Feb 12 17:27:08 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:08 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:09 schallmeister volumio[11854]: info: MYVOLUMIO Environment detected Feb 12 17:27:09 schallmeister volumio[11854]: info: Plugin folders cleanup Feb 12 17:27:09 schallmeister volumio[11854]: info: Scanning into folder /volumio/app/plugins/ Feb 12 17:27:09 schallmeister volumio[11854]: info: Scanning category audio_interface Feb 12 17:27:09 schallmeister volumio[11854]: info: Scanning category miscellanea Feb 12 17:27:09 schallmeister volumio[11854]: info: Scanning category music_service Feb 12 17:27:09 schallmeister volumio[11854]: info: Scanning category plugins.json Feb 12 17:27:09 schallmeister volumio[11854]: info: Scanning category system_controller Feb 12 17:27:09 schallmeister volumio[11854]: info: Scanning category user_interface Feb 12 17:27:09 schallmeister volumio[11854]: info: Scanning into folder /data/plugins/ Feb 12 17:27:09 schallmeister volumio[11854]: info: Scanning category music_service Feb 12 17:27:09 schallmeister volumio[11854]: info: Scanning category system_controller Feb 12 17:27:09 schallmeister volumio[11854]: info: Scanning category user_interface Feb 12 17:27:09 schallmeister volumio[11854]: info: Plugin folders cleanup completed Feb 12 17:27:09 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000498. ret = -110 Feb 12 17:27:09 schallmeister volumio[11854]: info: ------------------------------------------- Feb 12 17:27:09 schallmeister volumio[11854]: info: ----- Core plugins startup ---- Feb 12 17:27:09 schallmeister volumio[11854]: info: ------------------------------------------- Feb 12 17:27:09 schallmeister volumio[11854]: info: Loading plugins from folder /volumio/app/plugins/ Feb 12 17:27:09 schallmeister volumio[11854]: info: Adding plugin upnp to MyMusic Plugins Feb 12 17:27:09 schallmeister volumio[11854]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 12 17:27:09 schallmeister volumio[11854]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 12 17:27:09 schallmeister volumio[11854]: info: Loading plugins from folder /data/plugins/ Feb 12 17:27:09 schallmeister volumio[11854]: info: Loading plugin "system"... Feb 12 17:27:09 schallmeister volumio[11854]: info: Loading plugin "appearance"... Feb 12 17:27:09 schallmeister ifplugd(eth0)[1026]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Feb 12 17:27:09 schallmeister ntpd[993]: IO: Listen normally on 14 wlan0 192.168.211.1:123 Feb 12 17:27:09 schallmeister ntpd[993]: IO: new interface(s) found: waking up resolver Feb 12 17:27:09 schallmeister ntpd[993]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 12 17:27:09 schallmeister ntpd[993]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 12 17:27:09 schallmeister ntpd[993]: DNS: dns_check: DNS error: -11, System error Feb 12 17:27:09 schallmeister ntpd[993]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Feb 12 17:27:09 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:09] [connect] Successful connection Feb 12 17:27:09 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -110 Feb 12 17:27:09 schallmeister sudo[11817]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:09 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Regdomain already correct: DE Feb 12 17:27:09 schallmeister dhcpcd[11907]: dhcpcd not running Feb 12 17:27:09 schallmeister ifplugd(eth0)[1026]: client: dhcpcd not running Feb 12 17:27:10 schallmeister ifplugd(eth0)[1026]: Program executed successfully. Feb 12 17:27:10 schallmeister kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Feb 12 17:27:10 schallmeister ifplugd(eth0)[1026]: Link beat detected. Feb 12 17:27:10 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control off Feb 12 17:27:10 schallmeister dhcpcd[723]: eth0: carrier acquired Feb 12 17:27:10 schallmeister dhcpcd[723]: eth0: IAID eb:4c:e6:f6 Feb 12 17:27:10 schallmeister ifplugd(eth0)[1026]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 12 17:27:10 schallmeister ntpd[993]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 12 17:27:10 schallmeister ntpd[993]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 12 17:27:10 schallmeister ntpd[993]: DNS: dns_check: DNS error: -11, System error Feb 12 17:27:10 schallmeister ntpd[993]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Feb 12 17:27:10 schallmeister ifplugd(eth0)[1026]: client: sending commands to dhcpcd process Feb 12 17:27:10 schallmeister dhcpcd[723]: ps_ctl_dispatch: cannot handle another client Feb 12 17:27:10 schallmeister dhcpcd[723]: control_free: No such file or directory Feb 12 17:27:10 schallmeister systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 12 17:27:10 schallmeister systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Feb 12 17:27:10 schallmeister systemd[1]: welcome.service: Deactivated successfully. Feb 12 17:27:10 schallmeister systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 12 17:27:10 schallmeister systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 12 17:27:10 schallmeister systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 12 17:27:10 schallmeister dhcpcd[723]: eth0: soliciting an IPv6 router Feb 12 17:27:10 schallmeister welcome[11966]: Resolved ip:[1] 192.168.211.1 Feb 12 17:27:10 schallmeister systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 12 17:27:10 schallmeister systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 12 17:27:10 schallmeister ifplugd(eth0)[1026]: Program executed successfully. Feb 12 17:27:10 schallmeister dhcpcd[723]: eth0: rebinding lease of 131.234.232.90 Feb 12 17:27:11 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2558. Feb 12 17:27:11 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:11 schallmeister ntpd[993]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 12 17:27:11 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:11 schallmeister go-librespot[11980]: go-librespot daemon starting... Feb 12 17:27:11 schallmeister go-librespot[11982]: time="2026-02-12T17:27:11+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:11 schallmeister go-librespot[11982]: time="2026-02-12T17:27:11+01:00" level=debug msg="app state loaded" Feb 12 17:27:11 schallmeister go-librespot[11982]: time="2026-02-12T17:27:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:11 schallmeister go-librespot[11982]: time="2026-02-12T17:27:11+01: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" Feb 12 17:27:11 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:11 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:11 schallmeister volumio[11854]: info: Loading plugin "network"... Feb 12 17:27:11 schallmeister volumio[11854]: info: Refreshing Cached IP Addresses Feb 12 17:27:11 schallmeister volumio[11854]: info: Loading plugin "services"... Feb 12 17:27:11 schallmeister volumio[11854]: info: Loading plugin "volumio5onboarding"... Feb 12 17:27:11 schallmeister volumio[11854]: info: Loading plugin "alsa_controller"... Feb 12 17:27:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 17:27:12 schallmeister volumio[11854]: info: Loading plugin "wizard"... Feb 12 17:27:12 schallmeister volumio[11854]: info: Loading plugin "networkfs"... Feb 12 17:27:12 schallmeister volumio[11854]: info: Starting Udev Watcher for removable devices Feb 12 17:27:12 schallmeister volumio[11854]: info: Ignoring mount for partition: boot Feb 12 17:27:12 schallmeister volumio[11854]: info: Ignoring mount for partition: volumio Feb 12 17:27:12 schallmeister volumio[11854]: info: Ignoring mount for partition: volumio_data Feb 12 17:27:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 17:27:12 schallmeister volumio[11854]: info: Loading plugin "volumio_command_line_client"... Feb 12 17:27:12 schallmeister volumio[11854]: info: Plugin upnp is not enabled Feb 12 17:27:12 schallmeister volumio[11854]: info: Loading plugin "my_music"... Feb 12 17:27:12 schallmeister volumio[11854]: info: Loading plugin "mpd"... Feb 12 17:27:12 schallmeister volumio[11854]: info: Plugin upnp_browser is not enabled Feb 12 17:27:12 schallmeister volumio[11854]: info: Loading plugin "alarm-clock"... Feb 12 17:27:13 schallmeister volumio[11854]: info: Plugin airplay_emulation is not enabled Feb 12 17:27:13 schallmeister volumio[11854]: info: Loading plugin "last_100"... Feb 12 17:27:13 schallmeister volumio[11854]: info: Loading plugin "webradio"... Feb 12 17:27:13 schallmeister volumio5-onboarding[1481]: time=2026-02-12T17:27:13.693+01:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Feb 12 17:27:13 schallmeister dhcpcd[723]: eth0: probing address 131.234.232.90/25 Feb 12 17:27:14 schallmeister volumio5-onboarding[1481]: time=2026-02-12T17:27:14.369+01:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Feb 12 17:27:14 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2559. Feb 12 17:27:14 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:14 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:14 schallmeister go-librespot[12009]: go-librespot daemon starting... Feb 12 17:27:14 schallmeister go-librespot[12010]: time="2026-02-12T17:27:14+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:14 schallmeister go-librespot[12010]: time="2026-02-12T17:27:14+01:00" level=debug msg="app state loaded" Feb 12 17:27:14 schallmeister go-librespot[12010]: time="2026-02-12T17:27:14+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:14 schallmeister go-librespot[12010]: time="2026-02-12T17:27:14+01: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" Feb 12 17:27:14 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:14 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:15 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000478. ret = -110 Feb 12 17:27:15 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read stat ret = -110 Feb 12 17:27:15 schallmeister volumio[11854]: info: Loading plugin "i2s_dacs"... Feb 12 17:27:15 schallmeister volumio[11854]: info: I2S DAC not set, start Auto-detection Feb 12 17:27:15 schallmeister volumio[11854]: info: Loading plugin "volumiodiscovery"... Feb 12 17:27:15 schallmeister ntpd[993]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 12 17:27:15 schallmeister wireless.js[636]: WIRELESS.JS - INFO: === SNM TRANSITION === Feb 12 17:27:15 schallmeister ntpd[993]: DNS: dns_check: DNS error: -11, System error Feb 12 17:27:15 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Feb 12 17:27:15 schallmeister wireless.js[636]: WIRELESS.JS - INFO: New ethernet state: connected Feb 12 17:27:15 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Single Network Mode: enabled Feb 12 17:27:15 schallmeister ntpd[993]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Feb 12 17:27:15 schallmeister ntpd[993]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 12 17:27:15 schallmeister ntpd[993]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 12 17:27:15 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -110 Feb 12 17:27:15 schallmeister wireless.js[636]: WIRELESS.JS - INFO: First start: no Feb 12 17:27:15 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Feb 12 17:27:15 schallmeister wireless.js[636]: WIRELESS.JS - INFO: === END TRANSITION === Feb 12 17:27:15 schallmeister wireless.js[636]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Feb 12 17:27:15 schallmeister ntpd[993]: DNS: dns_check: DNS error: -11, System error Feb 12 17:27:15 schallmeister ntpd[993]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Feb 12 17:27:15 schallmeister sudo[11991]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 17:27:15 schallmeister sudo[11991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:15 schallmeister sudo[11993]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 17:27:15 schallmeister sudo[11991]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:15 schallmeister sudo[12001]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 12 17:27:15 schallmeister sudo[12001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:15 schallmeister sudo[11993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:15 schallmeister volumio[11854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 17:27:15 schallmeister volumio[11854]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 17:27:15 schallmeister volumio[11854]: *** WARNING *** For more information see Feb 12 17:27:15 schallmeister volumio[11854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 17:27:15 schallmeister volumio[11854]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 17:27:15 schallmeister volumio[11854]: *** WARNING *** For more information see Feb 12 17:27:15 schallmeister node[11854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 17:27:15 schallmeister node[11854]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 17:27:15 schallmeister node[11854]: *** WARNING *** For more information see Feb 12 17:27:15 schallmeister node[11854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 17:27:15 schallmeister node[11854]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 17:27:15 schallmeister node[11854]: *** WARNING *** For more information see Feb 12 17:27:15 schallmeister sudo[11993]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:16 schallmeister sudo[12022]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Feb 12 17:27:16 schallmeister volumio[11854]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 12 17:27:16 schallmeister sudo[12022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 17:27:16 schallmeister dhcpcd[12035]: dhcpcd not running Feb 12 17:27:16 schallmeister sudo[12022]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:16 schallmeister wireless.js[636]: dhcpcd not running Feb 12 17:27:16 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Feb 12 17:27:16 schallmeister volumio[11854]: info: Discovery: Started advertising with name: schallmeister Feb 12 17:27:16 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 17:27:16 schallmeister volumio[11854]: info: Loading plugin "spop"... Feb 12 17:27:16 schallmeister systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Feb 12 17:27:16 schallmeister dnsmasq[11830]: exiting on receipt of SIGTERM Feb 12 17:27:16 schallmeister systemd[1]: dnsmasq.service: Deactivated successfully. Feb 12 17:27:16 schallmeister systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Feb 12 17:27:16 schallmeister systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Feb 12 17:27:17 schallmeister volumio5-onboarding[1481]: time=2026-02-12T17:27:17.240+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:60448->127.0.0.1:3000: i/o timeout" Feb 12 17:27:17 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2560. Feb 12 17:27:17 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:18 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:18 schallmeister go-librespot[12046]: go-librespot daemon starting... Feb 12 17:27:18 schallmeister volumio[11854]: info: Plugin SleepWakePlugin is not enabled Feb 12 17:27:18 schallmeister volumio[11854]: info: Loading plugin "autostart"... Feb 12 17:27:18 schallmeister go-librespot[12047]: time="2026-02-12T17:27:18+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:18 schallmeister go-librespot[12047]: time="2026-02-12T17:27:18+01:00" level=debug msg="app state loaded" Feb 12 17:27:18 schallmeister go-librespot[12047]: time="2026-02-12T17:27:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:18 schallmeister go-librespot[12047]: time="2026-02-12T17:27:18+01: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" Feb 12 17:27:18 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:18 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:18 schallmeister volumio[11854]: info: Applying required configuration parameters for plugin autostart Feb 12 17:27:18 schallmeister volumio[11854]: info: AutoStart - onVolumioStart - read config.json Feb 12 17:27:18 schallmeister volumio[11854]: info: Loading plugin "outputs"... Feb 12 17:27:18 schallmeister volumio[11854]: info: Loading plugin "albumart"... Feb 12 17:27:18 schallmeister volumio[11854]: info: Plugin example_plugin is not enabled Feb 12 17:27:18 schallmeister volumio[11854]: info: Loading plugin "inputs"... Feb 12 17:27:18 schallmeister volumio[11854]: info: Loading plugin "updater_comm"... Feb 12 17:27:18 schallmeister volumio[11854]: info: Plugin mpdemulation is not enabled Feb 12 17:27:18 schallmeister volumio[11854]: info: Loading plugin "rest_api"... Feb 12 17:27:18 schallmeister volumio[11854]: info: Loading plugin "websocket"... Feb 12 17:27:18 schallmeister volumio[11854]: info: Starting Socket.io Server version 1.7.4 Feb 12 17:27:18 schallmeister volumio[11854]: info: Loading plugin "volusonic"... Feb 12 17:27:18 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x0000002c. ret = -110 Feb 12 17:27:19 schallmeister dhcpcd[723]: eth0: leased 131.234.232.90 for 3600 seconds Feb 12 17:27:19 schallmeister volumio[12056]: Forking 3 albumart workers Feb 12 17:27:19 schallmeister volumio5-onboarding[1481]: time=2026-02-12T17:27:19.782+01:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Feb 12 17:27:20 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x0000047c. ret = -110 Feb 12 17:27:20 schallmeister volumio5-onboarding[1481]: time=2026-02-12T17:27:20.554+01:00 level=ERROR msg="failed to update discovery on Ethernet info change" error="failed to get system info: could not get system info: context deadline exceeded" Feb 12 17:27:20 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -110 Feb 12 17:27:20 schallmeister kernel: ------------[ cut here ]------------ Feb 12 17:27:20 schallmeister kernel: WARNING: CPU: 3 PID: 6830 at drivers/net/phy/phy.c:1313 _phy_state_machine+0xa0/0x284 Feb 12 17:27:20 schallmeister kernel: phy_check_link_status+0x0/0xf8: returned: -5 Feb 12 17:27:20 schallmeister kernel: Modules linked in: cmac algif_hash aes_arm_bs crypto_simd cryptd aes_arm algif_skcipher af_alg bnep ip6t_REJECT nf_reject_ipv6 8021q garp stp llc xt_hl ip6t_rt ipt_REJECT nf_reject_ipv4 xt_LOG nf_log_syslog nft_limit nft_chain_nat xt_REDIRECT xt_limit nf_nat xt_addrtype xt_tcpudp xt_conntrack nf_conntrack snd_usb_audio nf_defrag_ipv6 nf_defrag_ipv4 nft_compat brcmfmac_wcc snd_hwdep nf_tables nfnetlink snd_usbmidi_lib binfmt_misc snd_seq_midi snd_seq_midi_event snd_seq brcmfmac snd_rawmidi snd_seq_device raspberrypi_hwmon brcmutil hci_uart btbcm bluetooth cfg80211 bcm2835_codec(C) bcm2835_v4l2(C) v4l2_mem2mem bcm2835_isp(C) bcm2835_mmal_vchiq(C) vc_sm_cma(C) videobuf2_dma_contig videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videodev ecdh_generic ecc videobuf2_common rfkill mc raspberrypi_gpiomem uio_pdrv_genirq uio i2c_dev dm_mod ip_tables x_tables ipv6 ads7846 fb_hx8357d(C) fb_st7789v(C) fb_st7735r(C) fb_ili9341(C) fb_ili9340(C) fbtft(C) panel_waveshare_dsi_v2 panel_waveshare_dsi panel_ilitek_ili9881c Feb 12 17:27:20 schallmeister kernel: panel_raspberrypi_touchscreen spi_bcm2835 snd_soc_bcm2835_i2s uas squashfs overlay nls_iso8859_1 fuse vc4 snd_soc_hdmi_codec drm_display_helper cec drm_dma_helper drm_kms_helper drm drm_panel_orientation_quirks snd_bcm2835(C) snd_soc_core snd_compress snd_pcm_dmaengine snd_pcm snd_timer backlight snd i2c_bcm2835 fixed Feb 12 17:27:20 schallmeister kernel: CPU: 3 UID: 0 PID: 6830 Comm: kworker/3:2 Tainted: G WC 6.12.47-v7+ #1904 Feb 12 17:27:20 schallmeister kernel: Tainted: [W]=WARN, [C]=CRAP Feb 12 17:27:20 schallmeister kernel: Hardware name: BCM2835 Feb 12 17:27:20 schallmeister kernel: Workqueue: events_power_efficient phy_state_machine Feb 12 17:27:20 schallmeister kernel: Call trace: Feb 12 17:27:20 schallmeister kernel: unwind_backtrace from show_stack+0x18/0x1c Feb 12 17:27:20 schallmeister kernel: show_stack from dump_stack_lvl+0x5c/0x80 Feb 12 17:27:20 schallmeister kernel: dump_stack_lvl from __warn+0x88/0x124 Feb 12 17:27:20 schallmeister kernel: __warn from warn_slowpath_fmt+0x11c/0x190 Feb 12 17:27:20 schallmeister kernel: warn_slowpath_fmt from _phy_state_machine+0xa0/0x284 Feb 12 17:27:20 schallmeister kernel: _phy_state_machine from phy_state_machine+0x24/0x44 Feb 12 17:27:20 schallmeister kernel: phy_state_machine from process_one_work+0x170/0x3c4 Feb 12 17:27:20 schallmeister kernel: process_one_work from worker_thread+0x278/0x434 Feb 12 17:27:20 schallmeister kernel: worker_thread from kthread+0xe8/0x104 Feb 12 17:27:20 schallmeister kernel: kthread from ret_from_fork+0x14/0x28 Feb 12 17:27:20 schallmeister kernel: Exception stack(0xba89dfb0 to 0xba89dff8) Feb 12 17:27:20 schallmeister kernel: dfa0: 00000000 00000000 00000000 00000000 Feb 12 17:27:20 schallmeister kernel: dfc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Feb 12 17:27:20 schallmeister kernel: dfe0: 00000000 00000000 00000000 00000000 00000013 00000000 Feb 12 17:27:20 schallmeister kernel: ---[ end trace 0000000000000000 ]--- Feb 12 17:27:21 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2561. Feb 12 17:27:21 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:21 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:21 schallmeister go-librespot[12096]: go-librespot daemon starting... Feb 12 17:27:21 schallmeister go-librespot[12097]: time="2026-02-12T17:27:21+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:21 schallmeister go-librespot[12097]: time="2026-02-12T17:27:21+01:00" level=debug msg="app state loaded" Feb 12 17:27:21 schallmeister go-librespot[12097]: time="2026-02-12T17:27:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:21 schallmeister go-librespot[12097]: time="2026-02-12T17:27:21+01: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" Feb 12 17:27:21 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:21 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:21 schallmeister volumio[11854]: info: Applying required configuration parameters for plugin volusonic Feb 12 17:27:21 schallmeister volumio[11854]: info: Loading plugin "Systeminfo"... Feb 12 17:27:22 schallmeister systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2010. Feb 12 17:27:22 schallmeister systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:27:22 schallmeister systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:27:22 schallmeister upmpdcli[12105]: Could not open config: /tmp/upmpdcli.conf Feb 12 17:27:22 schallmeister systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:22 schallmeister systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 12 17:27:22 schallmeister volumio[11854]: info: Loading i18n strings for locale de Feb 12 17:27:22 schallmeister volumio[11854]: Updating browse sources language Feb 12 17:27:22 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::initPlayerControls Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 17:27:23 schallmeister volumio[11854]: Express server listening on port 3000 Feb 12 17:27:23 schallmeister volumio[11854]: [Metrics] WebUI: 16s 14.75ms Feb 12 17:27:23 schallmeister volumio[12066]: Starting albumart workers Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreStateMachine::resetVolumioState Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreStateMachine::getcurrentVolume Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 17:27:23 schallmeister volumio[12068]: Starting albumart workers Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 17:27:23 schallmeister volumio[12067]: Starting albumart workers Feb 12 17:27:23 schallmeister volumio[11854]: info: Volumio Network Manager: Network status updated: 0 Feb 12 17:27:23 schallmeister volumio[11854]: info: Reloading queue from file Feb 12 17:27:23 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreStateMachine::setRepeat null single undefined Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreStateMachine::pushState Feb 12 17:27:23 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::volumioPushState Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreStateMachine::setRandom true Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreStateMachine::pushState Feb 12 17:27:23 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:27:23 schallmeister volumio[11854]: info: CoreCommandRouter::volumioPushState Feb 12 17:27:23 schallmeister volumio[11854]: info: Setting Device type: Raspberry PI Feb 12 17:27:24 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000030. ret = -110 Feb 12 17:27:24 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:24 schallmeister volumio[11854]: info: Discovery: adding 240da420-839e-49ce-9bf0-e9983e82cf3e Feb 12 17:27:24 schallmeister volumio[11854]: info: Discovery: Found device schallmeister Feb 12 17:27:24 schallmeister volumio[11854]: info: CoreCommandRouter::volumioGetState Feb 12 17:27:24 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:27:24 schallmeister volumio[11854]: info: Discovery: this is already registered, 240da420-839e-49ce-9bf0-e9983e82cf3e Feb 12 17:27:24 schallmeister volumio[11854]: info: Discovery: Found device schallmeister Feb 12 17:27:24 schallmeister volumio[11854]: info: CoreCommandRouter::volumioGetState Feb 12 17:27:24 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:27:24 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:24 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:24 schallmeister volumio[11854]: info: Completed loading Core Plugins Feb 12 17:27:24 schallmeister volumio[11854]: info: Preparing to generate the ALSA configuration file Feb 12 17:27:24 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:24 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:24 schallmeister volumio[11854]: info: Asound.conf file unchanged, so no further update is needed Feb 12 17:27:24 schallmeister volumio[11854]: info: Output device has changed, restarting MPD Feb 12 17:27:24 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2562. Feb 12 17:27:24 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:24 schallmeister volumio[11854]: info: ___________ START PLUGINS ___________ Feb 12 17:27:24 schallmeister volumio[11854]: info: ControllerMpd::onStart: Initializing MPD Feb 12 17:27:24 schallmeister volumio[11854]: info: Creating MPD Configuration file Feb 12 17:27:24 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:24 schallmeister go-librespot[12126]: go-librespot daemon starting... Feb 12 17:27:24 schallmeister go-librespot[12130]: time="2026-02-12T17:27:24+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:24 schallmeister go-librespot[12130]: time="2026-02-12T17:27:24+01:00" level=debug msg="app state loaded" Feb 12 17:27:24 schallmeister volumio[11854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 17:27:24 schallmeister volumio[11854]: info: [1770913644938] CoreMusicLibrary::Adding element Last_100 Feb 12 17:27:24 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 17:27:24 schallmeister volumio[11854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 17:27:24 schallmeister volumio[11854]: info: [1770913644946] CoreMusicLibrary::Adding element Webradio Feb 12 17:27:24 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 17:27:24 schallmeister go-librespot[12130]: time="2026-02-12T17:27:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:24 schallmeister go-librespot[12130]: time="2026-02-12T17:27:24+01: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" Feb 12 17:27:24 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:24 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:24 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 17:27:25 schallmeister volumio[11854]: info: Initializing BBC Radios Feb 12 17:27:25 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 17:27:25 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 17:27:25 schallmeister volumio[11854]: info: Creating Spotify config file Feb 12 17:27:25 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:25 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000478. ret = -110 Feb 12 17:27:25 schallmeister volumio[11854]: info: AutoStart - onStart - waiting for system ready state Feb 12 17:27:25 schallmeister volumio[11854]: info: AutoStart - Polling config: interval=5000ms, maxAttempts=60 Feb 12 17:27:25 schallmeister volumio[11854]: info: AutoStart - Maximum wait time: 300 seconds Feb 12 17:27:25 schallmeister volumio[11854]: info: AutoStart - Startup volume enabled, level=20 Feb 12 17:27:25 schallmeister volumio[11854]: info: AutoStart - Check #1/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 17:27:25 schallmeister volumio[11854]: info: Loading i18n strings for locale de Feb 12 17:27:25 schallmeister volumio[11854]: Updating browse sources language Feb 12 17:27:25 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 17:27:25 schallmeister volumio[11854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 17:27:25 schallmeister volumio[11854]: info: [1770913645845] CoreMusicLibrary::Adding element Volusonic Feb 12 17:27:25 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 17:27:25 schallmeister volumio[11854]: Cannot find translation for source Volusonic Feb 12 17:27:25 schallmeister volumio[11854]: info: Volumio Calling Home Feb 12 17:27:26 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000100. ret = -110 Feb 12 17:27:26 schallmeister kernel: ieee80211 phy0: brcmf_escan_timeout: timer expired Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: info: Spotify config file written Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 17:27:26 schallmeister volumio[11854]: info: No need to fix Spotify hosts Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:26 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 17:27:27 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:27] [info] Error getting remote endpoint: asio.system:9 (Bad file descriptor) Feb 12 17:27:27 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:27] [info] asio async_shutdown error: asio.system:9 (Bad file descriptor) Feb 12 17:27:27 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:27] [error] handle_connect error: Timer Expired Feb 12 17:27:27 schallmeister volumio[11854]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Feb 12 17:27:28 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2563. Feb 12 17:27:28 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:28 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:28 schallmeister go-librespot[12186]: go-librespot daemon starting... Feb 12 17:27:28 schallmeister go-librespot[12187]: time="2026-02-12T17:27:28+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:28 schallmeister go-librespot[12187]: time="2026-02-12T17:27:28+01:00" level=debug msg="app state loaded" Feb 12 17:27:28 schallmeister go-librespot[12187]: time="2026-02-12T17:27:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:28 schallmeister go-librespot[12187]: time="2026-02-12T17:27:28+01: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" Feb 12 17:27:28 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:28 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:29 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000028. ret = -110 Feb 12 17:27:30 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000480. ret = -110 Feb 12 17:27:30 schallmeister volumio[11854]: info: AutoStart - Check #2/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 17:27:31 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -110 Feb 12 17:27:31 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2564. Feb 12 17:27:31 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:31 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:31 schallmeister go-librespot[12197]: go-librespot daemon starting... Feb 12 17:27:32 schallmeister go-librespot[12199]: time="2026-02-12T17:27:32+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:32 schallmeister go-librespot[12199]: time="2026-02-12T17:27:32+01:00" level=debug msg="app state loaded" Feb 12 17:27:32 schallmeister go-librespot[12199]: time="2026-02-12T17:27:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:32 schallmeister go-librespot[12199]: time="2026-02-12T17:27:32+01: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" Feb 12 17:27:32 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:32 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:32 schallmeister volumio[11854]: info: VolumeController:: Volume=undefined Mute =false Feb 12 17:27:32 schallmeister volumio[11854]: info: CoreStateMachine::pushState Feb 12 17:27:32 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:27:32 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 17:27:32 schallmeister volumio[11854]: info: CoreCommandRouter::volumioPushState Feb 12 17:27:32 schallmeister volumio[11854]: info: CoreStateMachine::updateTrackBlock Feb 12 17:27:32 schallmeister volumio[11854]: info: CorePlayQueue::getTrackBlock Feb 12 17:27:32 schallmeister volumio[11854]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 17:27:32 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read stat ret = -110 Feb 12 17:27:34 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000024. ret = -110 Feb 12 17:27:35 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2565. Feb 12 17:27:35 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:35 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000484. ret = -110 Feb 12 17:27:35 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:35 schallmeister go-librespot[12209]: go-librespot daemon starting... Feb 12 17:27:35 schallmeister go-librespot[12210]: time="2026-02-12T17:27:35+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:35 schallmeister go-librespot[12210]: time="2026-02-12T17:27:35+01:00" level=debug msg="app state loaded" Feb 12 17:27:35 schallmeister go-librespot[12210]: time="2026-02-12T17:27:35+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:35 schallmeister go-librespot[12210]: time="2026-02-12T17:27:35+01: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" Feb 12 17:27:35 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:35 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:35 schallmeister volumio[11854]: info: AutoStart - Check #3/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 17:27:36 schallmeister volumio[11854]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Feb 12 17:27:36 schallmeister volumio[11854]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=2438757519a972f24a0b1cd789bab20e" http://updates.volumio.org/downloader-v1/track-device Feb 12 17:27:36 schallmeister volumio[11854]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 12 17:27:36 schallmeister volumio[11854]: Dload Upload Total Spent Left Speed Feb 12 17:27:36 schallmeister volumio[11854]: [132B blob data] Feb 12 17:27:36 schallmeister volumio[11854]: retrying in 5 seconds, trial 0 Feb 12 17:27:36 schallmeister volumio[11854]: info: Volumio Calling Home Feb 12 17:27:37 schallmeister systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2011. Feb 12 17:27:37 schallmeister systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:27:37 schallmeister systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:27:37 schallmeister upmpdcli[12222]: Could not open config: /tmp/upmpdcli.conf Feb 12 17:27:37 schallmeister systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:37 schallmeister systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 12 17:27:38 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2566. Feb 12 17:27:38 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:38 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:38 schallmeister go-librespot[12223]: go-librespot daemon starting... Feb 12 17:27:39 schallmeister go-librespot[12224]: time="2026-02-12T17:27:39+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:39 schallmeister go-librespot[12224]: time="2026-02-12T17:27:39+01:00" level=debug msg="app state loaded" Feb 12 17:27:39 schallmeister go-librespot[12224]: time="2026-02-12T17:27:39+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:39 schallmeister go-librespot[12224]: time="2026-02-12T17:27:39+01: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" Feb 12 17:27:39 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:39 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:39 schallmeister volumio[11854]: info: VolumeController:: Volume=undefined Mute =false Feb 12 17:27:39 schallmeister volumio[11854]: info: CoreStateMachine::pushState Feb 12 17:27:39 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:27:39 schallmeister volumio[11854]: info: CoreCommandRouter::volumioPushState Feb 12 17:27:40 schallmeister volumio[11854]: info: AutoStart - Check #4/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 17:27:41 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000120. ret = -110 Feb 12 17:27:42 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2567. Feb 12 17:27:42 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:42 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:42] [info] Error getting remote endpoint: asio.system:9 (Bad file descriptor) Feb 12 17:27:42 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:42] [info] asio async_shutdown error: asio.system:9 (Bad file descriptor) Feb 12 17:27:42 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:42] [error] handle_connect error: Timer Expired Feb 12 17:27:42 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:42 schallmeister go-librespot[12232]: go-librespot daemon starting... Feb 12 17:27:42 schallmeister go-librespot[12233]: time="2026-02-12T17:27:42+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:42 schallmeister go-librespot[12233]: time="2026-02-12T17:27:42+01:00" level=debug msg="app state loaded" Feb 12 17:27:42 schallmeister go-librespot[12233]: time="2026-02-12T17:27:42+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:42 schallmeister go-librespot[12233]: time="2026-02-12T17:27:42+01: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" Feb 12 17:27:42 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:42 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:44 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000488. ret = -110 Feb 12 17:27:45 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2568. Feb 12 17:27:45 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:45 schallmeister volumio[11854]: info: AutoStart - Check #5/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 17:27:45 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:45 schallmeister go-librespot[12242]: go-librespot daemon starting... Feb 12 17:27:45 schallmeister go-librespot[12243]: time="2026-02-12T17:27:45+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:45 schallmeister go-librespot[12243]: time="2026-02-12T17:27:45+01:00" level=debug msg="app state loaded" Feb 12 17:27:45 schallmeister go-librespot[12243]: time="2026-02-12T17:27:45+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:45 schallmeister go-librespot[12243]: time="2026-02-12T17:27:45+01: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" Feb 12 17:27:45 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:46 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:46 schallmeister ifplugd(eth0)[1026]: Link beat lost. Feb 12 17:27:46 schallmeister wireless.js[636]: WIRELESS.JS - INFO: === SNM TRANSITION === Feb 12 17:27:46 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Previous ethernet state: connected Feb 12 17:27:46 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -110 Feb 12 17:27:46 schallmeister wireless.js[636]: WIRELESS.JS - INFO: New ethernet state: disconnected Feb 12 17:27:46 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Single Network Mode: enabled Feb 12 17:27:46 schallmeister wireless.js[636]: WIRELESS.JS - INFO: First start: no Feb 12 17:27:46 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Action: Reconnect WiFi Feb 12 17:27:46 schallmeister wireless.js[636]: WIRELESS.JS - INFO: === END TRANSITION === Feb 12 17:27:46 schallmeister avahi-daemon[616]: Joining mDNS multicast group on interface eth0.IPv4 with address 131.234.232.90. Feb 12 17:27:46 schallmeister avahi-daemon[616]: New relevant interface eth0.IPv4 for mDNS. Feb 12 17:27:46 schallmeister avahi-daemon[616]: Registering new address record for 131.234.232.90 on eth0.IPv4. Feb 12 17:27:46 schallmeister dhcpcd[723]: eth0: adding route to 131.234.232.0/25 Feb 12 17:27:46 schallmeister dhcpcd[723]: eth0: adding default route via 131.234.232.1 Feb 12 17:27:46 schallmeister systemd[1]: hostapd.service: Deactivated successfully. Feb 12 17:27:46 schallmeister systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Feb 12 17:27:46 schallmeister sudo[12128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 12 17:27:46 schallmeister sudo[12128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:46 schallmeister sudo[12123]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 17:27:46 schallmeister sudo[12125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 17:27:46 schallmeister sudo[12125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:46 schallmeister sudo[12123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:46 schallmeister sudo[12123]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:46 schallmeister sudo[12254]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 12 17:27:46 schallmeister sudo[12174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 17:27:46 schallmeister sudo[12254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:46 schallmeister sudo[12174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:46 schallmeister volumio[11854]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Feb 12 17:27:46 schallmeister sudo[12136]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 17:27:46 schallmeister sudo[12139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 17:27:46 schallmeister sudo[12139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:46 schallmeister sudo[12136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:27:46 schallmeister sudo[12136]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:46 schallmeister volumio[11854]: info: MPD Permissions set Feb 12 17:27:46 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 12 17:27:46 schallmeister volumio[11854]: info: MPD Permissions set Feb 12 17:27:46 schallmeister volumio[11854]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=2438757519a972f24a0b1cd789bab20e" http://updates.volumio.org/downloader-v1/track-device Feb 12 17:27:46 schallmeister volumio[11854]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 12 17:27:46 schallmeister volumio[11854]: Dload Upload Total Spent Left Speed Feb 12 17:27:46 schallmeister volumio[11854]: [132B blob data] Feb 12 17:27:46 schallmeister volumio[11854]: retrying in 5 seconds, trial 1 Feb 12 17:27:46 schallmeister volumio[11854]: info: Volumio Calling Home Feb 12 17:27:46 schallmeister systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 17:27:46 schallmeister systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 12 17:27:46 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:46 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:46 schallmeister go-librespot[12265]: go-librespot daemon starting... Feb 12 17:27:46 schallmeister sudo[12174]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:46 schallmeister systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 12 17:27:46 schallmeister systemd[1]: welcome.service: Deactivated successfully. Feb 12 17:27:46 schallmeister systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 12 17:27:46 schallmeister systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 12 17:27:46 schallmeister sudo[12128]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:46 schallmeister systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 12 17:27:46 schallmeister go-librespot[12267]: time="2026-02-12T17:27:46+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:46 schallmeister go-librespot[12267]: time="2026-02-12T17:27:46+01:00" level=debug msg="app state loaded" Feb 12 17:27:46 schallmeister systemd[1]: mpd.service: Deactivated successfully. Feb 12 17:27:46 schallmeister systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 17:27:46 schallmeister systemd[1]: mpd.service: Consumed 5.766s CPU time. Feb 12 17:27:46 schallmeister go-librespot[12267]: time="2026-02-12T17:27:46+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:46 schallmeister go-librespot[12267]: time="2026-02-12T17:27:46+01: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" Feb 12 17:27:46 schallmeister systemd[1]: mpd.socket: Deactivated successfully. Feb 12 17:27:46 schallmeister systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 17:27:46 schallmeister systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 17:27:46 schallmeister systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 17:27:46 schallmeister systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 17:27:46 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:46 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:49 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x0000048c. ret = -110 Feb 12 17:27:49 schallmeister volumio[11854]: info: go-librespot daemon successfully initialized Feb 12 17:27:49 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2569. Feb 12 17:27:49 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:50 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:50 schallmeister go-librespot[12279]: go-librespot daemon starting... Feb 12 17:27:50 schallmeister go-librespot[12280]: time="2026-02-12T17:27:50+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:50 schallmeister go-librespot[12280]: time="2026-02-12T17:27:50+01:00" level=debug msg="app state loaded" Feb 12 17:27:50 schallmeister go-librespot[12280]: time="2026-02-12T17:27:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:50 schallmeister go-librespot[12280]: time="2026-02-12T17:27:50+01: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" Feb 12 17:27:50 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:50 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:27:50 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read stat ret = -110 Feb 12 17:27:50 schallmeister volumio[11854]: info: AutoStart - Check #6/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 17:27:51 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -110 Feb 12 17:27:51 schallmeister sudo[12254]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:51 schallmeister wireless.js[636]: WIRELESS.JS - INFO: SNM: Ethernet disconnected, reconnecting WiFi Feb 12 17:27:51 schallmeister wireless.js[636]: WIRELESS.JS - INFO: SNM: Ethernet disconnected, reconnecting WiFi Feb 12 17:27:51 schallmeister sudo[12001]: pam_unix(sudo:session): session closed for user root Feb 12 17:27:51 schallmeister dhcpcd[723]: eth0: carrier lost Feb 12 17:27:51 schallmeister wireless.js[636]: WIRELESS.JS - INFO: reconnectWiFi: Reconnect command failed: Error: Command failed: /sbin/wpa_cli -i wlan0 reconnect Feb 12 17:27:51 schallmeister wireless.js[636]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Feb 12 17:27:51 schallmeister wireless.js[636]: WIRELESS.JS - INFO: reconnectWiFi: Falling back to full wireless flow restart Feb 12 17:27:51 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Feb 12 17:27:52 schallmeister volumio[11854]: info: Initializing connection to go-librespot Websocket Feb 12 17:27:52 schallmeister systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2012. Feb 12 17:27:52 schallmeister systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:27:53 schallmeister systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:27:53 schallmeister upmpdcli[12347]: Could not open config: /tmp/upmpdcli.conf Feb 12 17:27:53 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2570. Feb 12 17:27:53 schallmeister systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:27:53 schallmeister systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 12 17:27:53 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:53 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:27:53 schallmeister go-librespot[12348]: go-librespot daemon starting... Feb 12 17:27:53 schallmeister go-librespot[12349]: time="2026-02-12T17:27:53+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:27:53 schallmeister go-librespot[12349]: time="2026-02-12T17:27:53+01:00" level=debug msg="app state loaded" Feb 12 17:27:53 schallmeister go-librespot[12349]: time="2026-02-12T17:27:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:27:55 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 17:27:55 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 17:27:55 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 12 17:27:55 schallmeister volumio[11854]: info: AutoStart - Check #7/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 17:27:55 schallmeister volumio[11854]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Feb 12 17:27:55 schallmeister volumio[11854]: info: ------------------------------------------- Feb 12 17:27:55 schallmeister volumio[11854]: info: ----- MyVolumio plugins startup ---- Feb 12 17:27:55 schallmeister volumio[11854]: info: ------------------------------------------- Feb 12 17:27:55 schallmeister volumio[11854]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 12 17:27:56 schallmeister volumio[11854]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=2438757519a972f24a0b1cd789bab20e" http://updates.volumio.org/downloader-v1/track-device Feb 12 17:27:56 schallmeister volumio[11854]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 12 17:27:56 schallmeister volumio[11854]: Dload Upload Total Spent Left Speed Feb 12 17:27:56 schallmeister volumio[11854]: [132B blob data] Feb 12 17:27:56 schallmeister volumio[11854]: retrying in 5 seconds, trial 2 Feb 12 17:27:56 schallmeister volumio[11854]: info: Volumio Calling Home Feb 12 17:27:57 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:57] [info] Error getting remote endpoint: asio.system:9 (Bad file descriptor) Feb 12 17:27:57 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:57] [info] asio async_shutdown error: asio.system:9 (Bad file descriptor) Feb 12 17:27:57 schallmeister volumio-remote-updater[631]: [2026-02-12 17:27:57] [error] handle_connect error: Timer Expired Feb 12 17:28:00 schallmeister volumio[11854]: info: AutoStart - Check #8/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 17:28:01 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000120. ret = -110 Feb 12 17:28:03 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000494. ret = -110 Feb 12 17:28:05 schallmeister volumio[11854]: info: AutoStart - Check #9/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 17:28:05 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read stat ret = -110 Feb 12 17:28:06 schallmeister ntpd[993]: IO: Deleting interface #14 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=36 secs Feb 12 17:28:06 schallmeister ifplugd(eth0)[1026]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Feb 12 17:28:06 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -110 Feb 12 17:28:06 schallmeister sudo[12278]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 17:28:06 schallmeister sudo[12278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 17:28:06 schallmeister sudo[12278]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:08 schallmeister systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2013. Feb 12 17:28:08 schallmeister systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:28:08 schallmeister systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:28:08 schallmeister upmpdcli[12369]: Could not open config: /tmp/upmpdcli.conf Feb 12 17:28:08 schallmeister systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:28:08 schallmeister systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 12 17:28:08 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000490. ret = -110 Feb 12 17:28:10 schallmeister volumio[11854]: info: AutoStart - Check #10/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 17:28:11 schallmeister welcome[12273]: Resolved ip:[2] 131.234.232.90 192.168.211.1 Feb 12 17:28:11 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -110 Feb 12 17:28:11 schallmeister avahi-daemon[616]: Withdrawing address record for 131.234.232.90 on eth0. Feb 12 17:28:11 schallmeister avahi-daemon[616]: Leaving mDNS multicast group on interface eth0.IPv4 with address 131.234.232.90. Feb 12 17:28:11 schallmeister avahi-daemon[616]: Interface eth0.IPv4 no longer relevant for mDNS. Feb 12 17:28:11 schallmeister dhcpcd[723]: eth0: deleting route to 131.234.232.0/25 Feb 12 17:28:11 schallmeister dhcpcd[723]: eth0: deleting default route via 131.234.232.1 Feb 12 17:28:11 schallmeister volumio[11854]: info: Received Get System Info Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 17:28:11 schallmeister volumio[11854]: info: Discovery: Getting this device information Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::volumioGetState Feb 12 17:28:11 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 17:28:11 schallmeister volumio-remote-updater[631]: [2026-02-12 17:28:11] [connect] Successful connection Feb 12 17:28:11 schallmeister systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 12 17:28:11 schallmeister systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 17:28:11 schallmeister sudo[12330]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 12 17:28:11 schallmeister sudo[12330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 17:28:11 schallmeister sudo[12346]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 12 17:28:11 schallmeister sudo[12346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 17:28:11 schallmeister avahi-daemon[616]: Withdrawing address record for 192.168.211.1 on wlan0. Feb 12 17:28:11 schallmeister avahi-daemon[616]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Feb 12 17:28:11 schallmeister sudo[12330]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:11 schallmeister avahi-daemon[616]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 12 17:28:11 schallmeister volumio[11854]: info: Received Get System Info Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 17:28:11 schallmeister volumio[11854]: info: Discovery: Getting this device information Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::volumioGetState Feb 12 17:28:11 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 17:28:11 schallmeister sudo[12346]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 17:28:11 schallmeister sudo[12398]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 12 17:28:11 schallmeister sudo[12398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 17:28:11 schallmeister sudo[12399]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 12 17:28:11 schallmeister sudo[12399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 17:28:11 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 12 17:28:11 schallmeister mpd[12368]: 2026-02-12T17:28:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 17:28:11 schallmeister volumio[11854]: info: Discovery: A device disappeared from network Feb 12 17:28:11 schallmeister volumio[11854]: info: Discovery: Device schallmeister disappeared from network Feb 12 17:28:11 schallmeister volumio[11854]: info: Volumio Network Manager: Network status updated: 1 Feb 12 17:28:11 schallmeister systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 17:28:11 schallmeister volumio-remote-updater[631]: [2026-02-12 17:28:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770913687 101 Feb 12 17:28:11 schallmeister sudo[12139]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:11 schallmeister sudo[12125]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:11 schallmeister volumio[11854]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Feb 12 17:28:11 schallmeister volumio[11854]: info: Discovery: A device disappeared from network Feb 12 17:28:11 schallmeister go-librespot[12349]: time="2026-02-12T17:28:11+01:00" level=debug msg="new websocket client" Feb 12 17:28:11 schallmeister dhcpcd[12407]: dhcpcd not running Feb 12 17:28:11 schallmeister ifplugd(eth0)[1026]: client: dhcpcd not running Feb 12 17:28:11 schallmeister dhcpcd[723]: wlan0: carrier lost Feb 12 17:28:11 schallmeister volumio[11854]: info: Connection to go-librespot Websocket established Feb 12 17:28:11 schallmeister volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 12 17:28:11 schallmeister systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 17:28:11 schallmeister systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 12 17:28:11 schallmeister systemd[1]: welcome.service: Deactivated successfully. Feb 12 17:28:11 schallmeister systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 12 17:28:11 schallmeister systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 12 17:28:11 schallmeister systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 12 17:28:11 schallmeister volumio[11854]: error: MPD error: The expression evaluated to a falsy value: Feb 12 17:28:11 schallmeister volumio[11854]: assert.ok(self.idling) Feb 12 17:28:11 schallmeister volumio[11854]: error: The expression evaluated to a falsy value: Feb 12 17:28:11 schallmeister volumio[11854]: assert.ok(self.idling) Feb 12 17:28:11 schallmeister volumio[11854]: info: MPD running with PID12368 Feb 12 17:28:11 schallmeister volumio[11854]: ,establishing connection Feb 12 17:28:11 schallmeister volumio[11854]: error: updateQueue error: null Feb 12 17:28:11 schallmeister volumio[11854]: error: updateQueue error: null Feb 12 17:28:11 schallmeister sudo[12398]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:11 schallmeister sudo[12399]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:11 schallmeister welcome[12418]: Resolved ip:[0] Feb 12 17:28:11 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Cleaning previous... Feb 12 17:28:11 schallmeister volumio[11854]: info: Received Get System Info Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 17:28:11 schallmeister volumio[11854]: info: Discovery: Getting this device information Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::volumioGetState Feb 12 17:28:11 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:28:11 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 17:28:11 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Cleaning previous... Feb 12 17:28:11 schallmeister systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 12 17:28:12 schallmeister systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 17:28:12 schallmeister ifplugd(eth0)[1026]: Program executed successfully. Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 17:28:12 schallmeister kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Feb 12 17:28:12 schallmeister sudo[12438]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 12 17:28:12 schallmeister sudo[12438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 17:28:12 schallmeister ifplugd(eth0)[1026]: Link beat detected. Feb 12 17:28:12 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control off Feb 12 17:28:12 schallmeister dhcpcd[723]: eth0: carrier acquired Feb 12 17:28:12 schallmeister volumio[11854]: info: Received Get System Info Feb 12 17:28:12 schallmeister kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 12 17:28:12 schallmeister nmbd[1067]: [2026/02/12 17:28:12.116744, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:12 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(138) ERRNO=Network is unreachable Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 17:28:12 schallmeister sudo[12438]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 17:28:12 schallmeister volumio[11854]: info: Discovery: Getting this device information Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::volumioGetState Feb 12 17:28:12 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 17:28:12 schallmeister systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 12 17:28:12 schallmeister systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Feb 12 17:28:12 schallmeister systemd[1]: welcome.service: Deactivated successfully. Feb 12 17:28:12 schallmeister systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 12 17:28:12 schallmeister systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 12 17:28:12 schallmeister dhcpcd[723]: eth0: IAID eb:4c:e6:f6 Feb 12 17:28:12 schallmeister sudo[12445]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 12 17:28:12 schallmeister ifplugd(eth0)[1026]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 12 17:28:12 schallmeister sudo[12445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 17:28:12 schallmeister sudo[12445]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:12 schallmeister wireless.js[636]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Feb 12 17:28:12 schallmeister wireless.js[636]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 12ms Feb 12 17:28:12 schallmeister wireless.js[636]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:19:b3:a3) Feb 12 17:28:12 schallmeister systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 12 17:28:12 schallmeister go-librespot[12349]: time="2026-02-12T17:28:12+01: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" Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 17:28:12 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:28:12 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 17:28:12 schallmeister welcome[12453]: Resolved ip:[0] Feb 12 17:28:12 schallmeister systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 12 17:28:12 schallmeister systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Feb 12 17:28:12 schallmeister sudo[12462]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 12 17:28:12 schallmeister sudo[12462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:28:12 schallmeister sudo[12462]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:12 schallmeister ifplugd(eth0)[1026]: client: sending commands to dhcpcd process Feb 12 17:28:12 schallmeister dhcpcd[723]: ps_ctl_dispatch: cannot handle another client Feb 12 17:28:12 schallmeister dhcpcd[723]: control_free: No such file or directory Feb 12 17:28:12 schallmeister sudo[12492]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 12 17:28:12 schallmeister sudo[12492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:28:12 schallmeister volumio[11854]: info: Connection to go-librespot Websocket closed Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::volumioGetState Feb 12 17:28:12 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:28:12 schallmeister volumio[11854]: info: Received Get System Info Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 17:28:12 schallmeister volumio[11854]: info: Discovery: Getting this device information Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::volumioGetState Feb 12 17:28:12 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 17:28:12 schallmeister volumio[11854]: info: Received Get System Info Feb 12 17:28:12 schallmeister dhcpcd[723]: eth0: soliciting an IPv6 router Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 17:28:12 schallmeister volumio[11854]: info: Discovery: Getting this device information Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::volumioGetState Feb 12 17:28:12 schallmeister volumio[11854]: info: CorePlayQueue::getTrack 0 Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 17:28:12 schallmeister volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 17:28:12 schallmeister ifplugd(eth0)[1026]: Program executed successfully. Feb 12 17:28:13 schallmeister dhcpcd[723]: eth0: rebinding lease of 131.234.232.90 Feb 12 17:28:13 schallmeister dhcpcd[723]: eth0: probing address 131.234.232.90/25 Feb 12 17:28:13 schallmeister nmbd[1067]: [2026/02/12 17:28:13.132419, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:13 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:13 schallmeister nmbd[1067]: [2026/02/12 17:28:13.132802, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:13 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23622 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:13 schallmeister nmbd[1067]: [2026/02/12 17:28:13.132977, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:13 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:13 schallmeister nmbd[1067]: [2026/02/12 17:28:13.133138, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:13 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23623 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:13 schallmeister nmbd[1067]: [2026/02/12 17:28:13.133272, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:13 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:13 schallmeister nmbd[1067]: [2026/02/12 17:28:13.133400, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:13 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23624 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:13 schallmeister nmbd[1067]: [2026/02/12 17:28:13.133541, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:13 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:13 schallmeister nmbd[1067]: [2026/02/12 17:28:13.133668, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:13 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23625 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:13 schallmeister nmbd[1067]: [2026/02/12 17:28:13.133799, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:13 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:13 schallmeister nmbd[1067]: [2026/02/12 17:28:13.133929, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:13 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23626 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:14 schallmeister nmbd[1067]: [2026/02/12 17:28:14.135487, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:14 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:14 schallmeister nmbd[1067]: [2026/02/12 17:28:14.135804, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:14 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23622 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:14 schallmeister nmbd[1067]: [2026/02/12 17:28:14.135974, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:14 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:14 schallmeister nmbd[1067]: [2026/02/12 17:28:14.136142, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:14 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23623 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:14 schallmeister nmbd[1067]: [2026/02/12 17:28:14.136277, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:14 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:14 schallmeister nmbd[1067]: [2026/02/12 17:28:14.136415, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:14 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23624 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:14 schallmeister nmbd[1067]: [2026/02/12 17:28:14.136546, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:14 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:14 schallmeister nmbd[1067]: [2026/02/12 17:28:14.136673, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:14 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23625 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:14 schallmeister nmbd[1067]: [2026/02/12 17:28:14.136803, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:14 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:14 schallmeister nmbd[1067]: [2026/02/12 17:28:14.136946, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:14 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23626 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:14 schallmeister volumio[11854]: info: Getting Spotify volume Feb 12 17:28:14 schallmeister volumio[11854]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 17:28:14 schallmeister volumio[11854]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 17:28:14 schallmeister volumio[11854]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 12 17:28:14 schallmeister volumio[11854]: errno: -111, Feb 12 17:28:14 schallmeister volumio[11854]: code: 'ECONNREFUSED', Feb 12 17:28:14 schallmeister volumio[11854]: syscall: 'connect', Feb 12 17:28:14 schallmeister volumio[11854]: address: '127.0.0.1', Feb 12 17:28:14 schallmeister volumio[11854]: port: 9879, Feb 12 17:28:14 schallmeister volumio[11854]: response: undefined Feb 12 17:28:14 schallmeister volumio[11854]: } Feb 12 17:28:14 schallmeister volumio[11854]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 17:28:15 schallmeister nmbd[1067]: [2026/02/12 17:28:15.138495, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:15 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:15 schallmeister nmbd[1067]: [2026/02/12 17:28:15.138840, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:15 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23622 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:15 schallmeister nmbd[1067]: [2026/02/12 17:28:15.138999, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:15 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:15 schallmeister nmbd[1067]: [2026/02/12 17:28:15.139130, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:15 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23623 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:15 schallmeister nmbd[1067]: [2026/02/12 17:28:15.139276, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:15 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:15 schallmeister nmbd[1067]: [2026/02/12 17:28:15.139403, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:15 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23624 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:15 schallmeister nmbd[1067]: [2026/02/12 17:28:15.139537, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:15 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:15 schallmeister nmbd[1067]: [2026/02/12 17:28:15.139673, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:15 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23625 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:15 schallmeister nmbd[1067]: [2026/02/12 17:28:15.139815, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 12 17:28:15 schallmeister nmbd[1067]: Packet send failed to 131.234.232.127(137) ERRNO=Network is unreachable Feb 12 17:28:15 schallmeister nmbd[1067]: [2026/02/12 17:28:15.139943, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Feb 12 17:28:15 schallmeister nmbd[1067]: retransmit_or_expire_response_records: Failed to resend packet id 23626 to IP 131.234.232.127 on subnet 131.234.232.90 Feb 12 17:28:15 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2571. Feb 12 17:28:15 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:28:15 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:28:15 schallmeister go-librespot[12537]: go-librespot daemon starting... Feb 12 17:28:15 schallmeister go-librespot[12538]: time="2026-02-12T17:28:15+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:28:15 schallmeister go-librespot[12538]: time="2026-02-12T17:28:15+01:00" level=debug msg="app state loaded" Feb 12 17:28:15 schallmeister go-librespot[12538]: time="2026-02-12T17:28:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:28:15 schallmeister go-librespot[12538]: time="2026-02-12T17:28:15+01: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" Feb 12 17:28:15 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:28:15 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:28:18 schallmeister dhcpcd[723]: eth0: leased 131.234.232.90 for 3600 seconds Feb 12 17:28:18 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000024. ret = -110 Feb 12 17:28:18 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2572. Feb 12 17:28:18 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:28:18 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:28:18 schallmeister go-librespot[12549]: go-librespot daemon starting... Feb 12 17:28:18 schallmeister go-librespot[12550]: time="2026-02-12T17:28:18+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:28:18 schallmeister go-librespot[12550]: time="2026-02-12T17:28:18+01:00" level=debug msg="app state loaded" Feb 12 17:28:18 schallmeister go-librespot[12550]: time="2026-02-12T17:28:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:28:18 schallmeister go-librespot[12550]: time="2026-02-12T17:28:18+01: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" Feb 12 17:28:18 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:28:18 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:28:18 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000440. ret = -110 Feb 12 17:28:21 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2573. Feb 12 17:28:21 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:28:21 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:28:22 schallmeister go-librespot[12562]: go-librespot daemon starting... Feb 12 17:28:22 schallmeister go-librespot[12563]: time="2026-02-12T17:28:22+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:28:22 schallmeister go-librespot[12563]: time="2026-02-12T17:28:22+01:00" level=debug msg="app state loaded" Feb 12 17:28:22 schallmeister go-librespot[12563]: time="2026-02-12T17:28:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:28:22 schallmeister go-librespot[12563]: time="2026-02-12T17:28:22+01: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" Feb 12 17:28:22 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:28:22 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:28:22 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Failed to determine most appropriate reg domain: Error: spawnSync /bin/sh ETIMEDOUT Feb 12 17:28:23 schallmeister systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2014. Feb 12 17:28:23 schallmeister systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:28:23 schallmeister systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 12 17:28:23 schallmeister upmpdcli[12571]: Could not open config: /tmp/upmpdcli.conf Feb 12 17:28:23 schallmeister systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:28:23 schallmeister systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 12 17:28:24 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000444. ret = -110 Feb 12 17:28:25 schallmeister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2574. Feb 12 17:28:25 schallmeister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:28:25 schallmeister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 17:28:25 schallmeister go-librespot[12572]: go-librespot daemon starting... Feb 12 17:28:25 schallmeister go-librespot[12573]: time="2026-02-12T17:28:25+01:00" level=info msg="running go-librespot 0.6.2" Feb 12 17:28:25 schallmeister go-librespot[12573]: time="2026-02-12T17:28:25+01:00" level=debug msg="app state loaded" Feb 12 17:28:25 schallmeister go-librespot[12573]: time="2026-02-12T17:28:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 17:28:25 schallmeister go-librespot[12573]: time="2026-02-12T17:28:25+01: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" Feb 12 17:28:25 schallmeister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 17:28:25 schallmeister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: refreshEthernetState: Corrected ethernet state: connected Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Feb 12 17:28:26 schallmeister kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -110 Feb 12 17:28:26 schallmeister avahi-daemon[616]: Joining mDNS multicast group on interface eth0.IPv4 with address 131.234.232.90. Feb 12 17:28:26 schallmeister avahi-daemon[616]: New relevant interface eth0.IPv4 for mDNS. Feb 12 17:28:26 schallmeister avahi-daemon[616]: Registering new address record for 131.234.232.90 on eth0.IPv4. Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: === SNM TRANSITION === Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: New ethernet state: connected Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Single Network Mode: enabled Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: First start: no Feb 12 17:28:26 schallmeister dhcpcd[723]: eth0: adding route to 131.234.232.0/25 Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: === END TRANSITION === Feb 12 17:28:26 schallmeister wireless.js[636]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Feb 12 17:28:26 schallmeister dhcpcd[723]: eth0: adding default route via 131.234.232.1 Feb 12 17:28:26 schallmeister sudo[12548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 17:27' Feb 12 17:28:26 schallmeister sudo[12492]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:26 schallmeister sudo[12548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:28:27 schallmeister sudo[12597]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Feb 12 17:28:27 schallmeister sudo[12597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 17:28:27 schallmeister systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 17:28:27 schallmeister systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 12 17:28:27 schallmeister systemd[1]: welcome.service: Deactivated successfully. Feb 12 17:28:27 schallmeister systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 12 17:28:27 schallmeister systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 12 17:28:27 schallmeister dhcpcd[12609]: dhcpcd not running Feb 12 17:28:27 schallmeister sudo[12597]: pam_unix(sudo:session): session closed for user root Feb 12 17:28:27 schallmeister wireless.js[636]: dhcpcd not running Feb 12 17:28:27 schallmeister wireless.js[636]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Feb 12 17:28:27 schallmeister systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 12 17:28:27 schallmeister welcome[12614]: Resolved ip:[1] 131.234.232.90 Feb 12 17:28:27 schallmeister systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 12 17:28:27 schallmeister wireless.js[636]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Feb 12 17:28:27 schallmeister wireless.js[636]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 29ms Feb 12 17:28:27 schallmeister wireless.js[636]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:19:b3:a3) Feb 12 17:28:27 schallmeister systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 12 17:28:27 schallmeister sudo[12637]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 12 17:28:27 schallmeister sudo[12637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 17:28:27 schallmeister sudo[12637]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"