Dec 28 19:33:01 volumio kernel: r8169 0000:03:00.0 eth0: NETDEV WATCHDOG: CPU: 3: transmit queue 0 timed out 5604 ms Dec 28 19:33:01 volumio kernel: r8169 0000:03:00.0 eth0: rtl_txcfg_empty_cond == 0 (loop: 666, delay: 100). Dec 28 19:33:08 volumio kernel: r8169 0000:03:00.0 eth0: NETDEV WATCHDOG: CPU: 0: transmit queue 0 timed out 5552 ms Dec 28 19:33:08 volumio kernel: r8169 0000:03:00.0 eth0: rtl_txcfg_empty_cond == 0 (loop: 666, delay: 100). Dec 28 19:33:16 volumio kernel: r8169 0000:03:00.0 eth0: NETDEV WATCHDOG: CPU: 0: transmit queue 0 timed out 5508 ms Dec 28 19:33:16 volumio kernel: r8169 0000:03:00.0 eth0: rtl_txcfg_empty_cond == 0 (loop: 666, delay: 100). Dec 28 19:33:23 volumio kernel: r8169 0000:03:00.0 eth0: NETDEV WATCHDOG: CPU: 0: transmit queue 0 timed out 5324 ms Dec 28 19:33:23 volumio kernel: r8169 0000:03:00.0 eth0: rtl_txcfg_empty_cond == 0 (loop: 666, delay: 100). Dec 28 19:33:26 volumio go-librespot[887108]: time="2025-12-28T19:33:26+01:00" level=error msg="did not receive last pong from dealer, 120s passed" Dec 28 19:33:31 volumio kernel: r8169 0000:03:00.0 eth0: NETDEV WATCHDOG: CPU: 0: transmit queue 0 timed out 5156 ms Dec 28 19:33:31 volumio kernel: r8169 0000:03:00.0 eth0: rtl_txcfg_empty_cond == 0 (loop: 666, delay: 100). Dec 28 19:33:38 volumio kernel: r8169 0000:03:00.0 eth0: NETDEV WATCHDOG: CPU: 0: transmit queue 0 timed out 5132 ms Dec 28 19:33:38 volumio kernel: r8169 0000:03:00.0 eth0: rtl_txcfg_empty_cond == 0 (loop: 666, delay: 100). Dec 28 19:33:46 volumio kernel: r8169 0000:03:00.0 eth0: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 5056 ms Dec 28 19:33:46 volumio kernel: r8169 0000:03:00.0 eth0: rtl_txcfg_empty_cond == 0 (loop: 666, delay: 100). Dec 28 19:33:56 volumio go-librespot[887108]: time="2025-12-28T19:33:56+01:00" level=error msg="did not receive last pong from dealer, 150s passed" Dec 28 19:33:56 volumio go-librespot[887108]: time="2025-12-28T19:33:56+01:00" level=error msg="did not receive last pong ack from accesspoint, 193s passed" Dec 28 19:33:56 volumio go-librespot[887108]: time="2025-12-28T19:33:56+01:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.200.32:37144->104.199.65.9:4070: use of closed network connection" Dec 28 19:34:01 volumio dhcpcd[894]: eth0: carrier lost Dec 28 19:34:01 volumio kernel: r8169 0000:03:00.0 eth0: Link is Down Dec 28 19:34:01 volumio avahi-daemon[853]: Withdrawing address record for 192.168.200.32 on eth0. Dec 28 19:34:01 volumio avahi-daemon[853]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.200.32. Dec 28 19:34:01 volumio avahi-daemon[853]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 28 19:34:01 volumio dhcpcd[894]: eth0: deleting route to 192.168.200.0/24 Dec 28 19:34:01 volumio dhcpcd[894]: eth0: deleting default route via 192.168.200.1 Dec 28 19:34:01 volumio volumio[886902]: info: Discovery: A device disappeared from network Dec 28 19:34:01 volumio volumio[886902]: info: Discovery: Device volumio disappeared from network Dec 28 19:34:01 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 19:34:01 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 19:34:01 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 28 19:34:01 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 28 19:34:01 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 28 19:34:01 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 28 19:34:01 volumio welcome[990451]: Resolved ip:[0] Dec 28 19:34:01 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 28 19:34:01 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 19:34:01 volumio ifplugd(eth0)[1336]: Link beat lost. Dec 28 19:34:02 volumio ntpd[1310]: IO: Deleting interface #8 eth0, 192.168.200.32#123, interface stats: received=0, sent=0, dropped=0, active_time=113 secs Dec 28 19:34:02 volumio ntpd[1310]: PROTO: 193.77.189.222 unlink local addr 192.168.200.32 -> Dec 28 19:34:02 volumio ntpd[1310]: PROTO: 84.255.228.71 unlink local addr 192.168.200.32 -> Dec 28 19:34:02 volumio ntpd[1310]: PROTO: 84.255.251.205 unlink local addr 192.168.200.32 -> Dec 28 19:34:02 volumio ntpd[1310]: PROTO: 193.2.4.2 unlink local addr 192.168.200.32 -> Dec 28 19:34:02 volumio ntpd[1310]: PROTO: 194.28.110.28 unlink local addr 192.168.200.32 -> Dec 28 19:34:02 volumio ntpd[1310]: PROTO: 84.255.251.204 unlink local addr 192.168.200.32 -> Dec 28 19:34:02 volumio ntpd[1310]: PROTO: 84.255.251.188 unlink local addr 192.168.200.32 -> Dec 28 19:34:02 volumio ntpd[1310]: PROTO: 84.255.251.189 unlink local addr 192.168.200.32 -> Dec 28 19:34:04 volumio dhcpcd[894]: eth0: carrier acquired Dec 28 19:34:04 volumio kernel: r8169 0000:03:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 28 19:34:04 volumio dhcpcd[894]: eth0: IAID 2e:65:4b:e6 Dec 28 19:34:04 volumio dhcpcd[894]: eth0: probing address 192.168.200.32/24 Dec 28 19:34:04 volumio ifplugd(eth0)[1336]: Link beat detected. Dec 28 19:34:04 volumio dhcpcd[894]: eth0: soliciting an IPv6 router Dec 28 19:34:05 volumio go-librespot[887108]: time="2025-12-28T19:34:05+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:05 volumio go-librespot[887108]: time="2025-12-28T19:34:05+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:05 volumio go-librespot[887108]: time="2025-12-28T19:34:05+01:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Dec 28 19:34:05 volumio go-librespot[887108]: time="2025-12-28T19:34:05+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Dec 28 19:34:05 volumio go-librespot[887108]: time="2025-12-28T19:34:05+01:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Dec 28 19:34:06 volumio go-librespot[887108]: time="2025-12-28T19:34:06+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:06 volumio go-librespot[887108]: time="2025-12-28T19:34:06+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:06 volumio go-librespot[887108]: time="2025-12-28T19:34:06+01:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Dec 28 19:34:06 volumio go-librespot[887108]: time="2025-12-28T19:34:06+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Dec 28 19:34:06 volumio go-librespot[887108]: time="2025-12-28T19:34:06+01:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Dec 28 19:34:06 volumio go-librespot[887108]: time="2025-12-28T19:34:06+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:06 volumio go-librespot[887108]: time="2025-12-28T19:34:06+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:06 volumio go-librespot[887108]: time="2025-12-28T19:34:06+01:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Dec 28 19:34:06 volumio go-librespot[887108]: time="2025-12-28T19:34:06+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Dec 28 19:34:06 volumio go-librespot[887108]: time="2025-12-28T19:34:06+01:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Dec 28 19:34:07 volumio go-librespot[887108]: time="2025-12-28T19:34:07+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:07 volumio go-librespot[887108]: time="2025-12-28T19:34:07+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:07 volumio go-librespot[887108]: time="2025-12-28T19:34:07+01:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Dec 28 19:34:07 volumio go-librespot[887108]: time="2025-12-28T19:34:07+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Dec 28 19:34:07 volumio go-librespot[887108]: time="2025-12-28T19:34:07+01:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Dec 28 19:34:08 volumio go-librespot[887108]: time="2025-12-28T19:34:08+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:08 volumio go-librespot[887108]: time="2025-12-28T19:34:08+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:08 volumio go-librespot[887108]: time="2025-12-28T19:34:08+01:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Dec 28 19:34:08 volumio go-librespot[887108]: time="2025-12-28T19:34:08+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Dec 28 19:34:08 volumio go-librespot[887108]: time="2025-12-28T19:34:08+01:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Dec 28 19:34:09 volumio volumio[886902]: info: Volumio Network Manager: Network status updated: 0 Dec 28 19:34:09 volumio avahi-daemon[853]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.200.32. Dec 28 19:34:09 volumio avahi-daemon[853]: New relevant interface eth0.IPv4 for mDNS. Dec 28 19:34:09 volumio dhcpcd[894]: eth0: using static address 192.168.200.32/24 Dec 28 19:34:09 volumio avahi-daemon[853]: Registering new address record for 192.168.200.32 on eth0.IPv4. Dec 28 19:34:09 volumio dhcpcd[894]: eth0: adding route to 192.168.200.0/24 Dec 28 19:34:09 volumio dhcpcd[894]: eth0: adding default route via 192.168.200.1 Dec 28 19:34:09 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 19:34:09 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 19:34:09 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 28 19:34:09 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 28 19:34:09 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 28 19:34:09 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 28 19:34:09 volumio welcome[990497]: Resolved ip:[1] 192.168.200.32 Dec 28 19:34:09 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 28 19:34:09 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 19:34:10 volumio dhcpcd[894]: eth0: carrier lost Dec 28 19:34:10 volumio kernel: r8169 0000:03:00.0 eth0: Link is Down Dec 28 19:34:10 volumio avahi-daemon[853]: Withdrawing address record for 192.168.200.32 on eth0. Dec 28 19:34:10 volumio avahi-daemon[853]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.200.32. Dec 28 19:34:10 volumio dhcpcd[894]: eth0: deleting route to 192.168.200.0/24 Dec 28 19:34:10 volumio avahi-daemon[853]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 28 19:34:10 volumio dhcpcd[894]: eth0: deleting default route via 192.168.200.1 Dec 28 19:34:10 volumio volumio[886902]: info: Discovery: A device disappeared from network Dec 28 19:34:10 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 19:34:10 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 19:34:10 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 28 19:34:10 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 28 19:34:10 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 28 19:34:10 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 28 19:34:10 volumio welcome[990531]: Resolved ip:[0] Dec 28 19:34:10 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 28 19:34:10 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 19:34:10 volumio ifplugd(eth0)[1336]: Link beat lost. Dec 28 19:34:12 volumio go-librespot[887108]: time="2025-12-28T19:34:12+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:12 volumio go-librespot[887108]: time="2025-12-28T19:34:12+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:12 volumio go-librespot[887108]: time="2025-12-28T19:34:12+01:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Dec 28 19:34:12 volumio go-librespot[887108]: time="2025-12-28T19:34:12+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Dec 28 19:34:12 volumio go-librespot[887108]: time="2025-12-28T19:34:12+01:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Dec 28 19:34:13 volumio dhcpcd[894]: eth0: carrier acquired Dec 28 19:34:13 volumio kernel: r8169 0000:03:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 28 19:34:13 volumio dhcpcd[894]: eth0: IAID 2e:65:4b:e6 Dec 28 19:34:13 volumio dhcpcd[894]: eth0: probing address 192.168.200.32/24 Dec 28 19:34:13 volumio dhcpcd[894]: eth0: soliciting an IPv6 router Dec 28 19:34:13 volumio ifplugd(eth0)[1336]: Link beat detected. Dec 28 19:34:14 volumio go-librespot[887108]: time="2025-12-28T19:34:14+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:14 volumio go-librespot[887108]: time="2025-12-28T19:34:14+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:14 volumio go-librespot[887108]: time="2025-12-28T19:34:14+01:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Dec 28 19:34:14 volumio go-librespot[887108]: time="2025-12-28T19:34:14+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Dec 28 19:34:14 volumio go-librespot[887108]: time="2025-12-28T19:34:14+01:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Dec 28 19:34:15 volumio volumio[886902]: info: Discovery: Browse raised the following error Error: dns service error: unknown Dec 28 19:34:16 volumio dhcpcd[894]: eth0: carrier lost Dec 28 19:34:16 volumio kernel: r8169 0000:03:00.0 eth0: Link is Down Dec 28 19:34:16 volumio ifplugd(eth0)[1336]: Link beat lost. Dec 28 19:34:19 volumio go-librespot[887108]: time="2025-12-28T19:34:19+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:19 volumio go-librespot[887108]: time="2025-12-28T19:34:19+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:19 volumio go-librespot[887108]: time="2025-12-28T19:34:19+01:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Dec 28 19:34:19 volumio go-librespot[887108]: time="2025-12-28T19:34:19+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Dec 28 19:34:19 volumio go-librespot[887108]: time="2025-12-28T19:34:19+01:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Dec 28 19:34:23 volumio volumio[886902]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:34:23 volumio volumio[886902]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 28 19:34:23 volumio volumio[886902]: at doSend (node:dgram:717:16) Dec 28 19:34:23 volumio volumio[886902]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Dec 28 19:34:23 volumio volumio[886902]: at afterDns (node:dgram:663:5) Dec 28 19:34:23 volumio volumio[886902]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Dec 28 19:34:23 volumio volumio[886902]: errno: -101, Dec 28 19:34:23 volumio volumio[886902]: code: 'ENETUNREACH', Dec 28 19:34:23 volumio volumio[886902]: syscall: 'send', Dec 28 19:34:23 volumio volumio[886902]: address: '255.255.255.255', Dec 28 19:34:23 volumio volumio[886902]: port: 3483 Dec 28 19:34:23 volumio volumio[886902]: } Dec 28 19:34:23 volumio volumio[886902]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:34:24 volumio sudo[990593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 19:33' Dec 28 19:34:24 volumio sudo[990593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:24 volumio sudo[990593]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:24 volumio go-librespot[887108]: time="2025-12-28T19:34:24+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Dec 28 19:34:24 volumio volumio-remote-updater[861]: [2025-12-28 19:34:24] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 28 19:34:24 volumio volumio-remote-updater[861]: [2025-12-28 19:34:24] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 28 19:34:24 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 28 19:34:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 28 19:34:24 volumio systemd[1]: volumio.service: Consumed 2min 15.325s CPU time. Dec 28 19:34:24 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 28 19:34:24 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 28 19:34:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Dec 28 19:34:24 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 28 19:34:24 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 28 19:34:24 volumio systemd[1]: volumio.service: Consumed 2min 15.325s CPU time. Dec 28 19:34:24 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 28 19:34:24 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 28 19:34:24 volumio volumio[990634]: info: ------------------------------------------- Dec 28 19:34:24 volumio volumio[990634]: info: ----- Volumio3 ---- Dec 28 19:34:24 volumio volumio[990634]: info: ------------------------------------------- Dec 28 19:34:24 volumio volumio[990634]: info: ----- System startup ---- Dec 28 19:34:24 volumio volumio[990634]: info: ------------------------------------------- Dec 28 19:34:25 volumio volumio[990634]: info: MYVOLUMIO Environment detected Dec 28 19:34:25 volumio volumio[990634]: info: Plugin folders cleanup Dec 28 19:34:25 volumio volumio[990634]: info: Scanning into folder /volumio/app/plugins/ Dec 28 19:34:25 volumio volumio[990634]: info: Scanning category audio_interface Dec 28 19:34:25 volumio volumio[990634]: info: Scanning category miscellanea Dec 28 19:34:25 volumio volumio[990634]: info: Scanning category music_service Dec 28 19:34:25 volumio volumio[990634]: info: Scanning category plugins.json Dec 28 19:34:25 volumio volumio[990634]: info: Scanning category system_controller Dec 28 19:34:25 volumio volumio[990634]: info: Scanning category user_interface Dec 28 19:34:25 volumio volumio[990634]: info: Scanning into folder /data/plugins/ Dec 28 19:34:25 volumio volumio[990634]: info: Scanning category music_service Dec 28 19:34:25 volumio volumio[990634]: info: Scanning category system_controller Dec 28 19:34:25 volumio volumio[990634]: info: Scanning category user_interface Dec 28 19:34:25 volumio volumio[990634]: info: Plugin folders cleanup completed Dec 28 19:34:25 volumio volumio[990634]: info: ------------------------------------------- Dec 28 19:34:25 volumio volumio[990634]: info: ----- Core plugins startup ---- Dec 28 19:34:25 volumio volumio[990634]: info: ------------------------------------------- Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugins from folder /volumio/app/plugins/ Dec 28 19:34:25 volumio volumio[990634]: info: Adding plugin upnp to MyMusic Plugins Dec 28 19:34:25 volumio volumio[990634]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 28 19:34:25 volumio volumio[990634]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugins from folder /data/plugins/ Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "system"... Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "appearance"... Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "network"... Dec 28 19:34:25 volumio volumio[990634]: info: Refreshing Cached IP Addresses Dec 28 19:34:25 volumio sudo[990663]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 19:34:25 volumio sudo[990663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "services"... Dec 28 19:34:25 volumio sudo[990665]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "alsa_controller"... Dec 28 19:34:25 volumio sudo[990663]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:25 volumio sudo[990665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:25 volumio sudo[990677]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 28 19:34:25 volumio sudo[990677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:25 volumio sudo[990665]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:25 volumio sudo[990677]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:25 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "wizard"... Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "networkfs"... Dec 28 19:34:25 volumio volumio[990634]: info: Starting Udev Watcher for removable devices Dec 28 19:34:25 volumio volumio[990634]: info: Ignoring mount for partition: boot Dec 28 19:34:25 volumio volumio[990634]: info: Ignoring mount for partition: volumio Dec 28 19:34:25 volumio volumio[990634]: info: Ignoring mount for partition: volumio_data Dec 28 19:34:25 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "volumio_command_line_client"... Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "upnp"... Dec 28 19:34:25 volumio volumio[990634]: info: [1766946865422] Starting Upmpd Daemon Dec 28 19:34:25 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "my_music"... Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "mpd"... Dec 28 19:34:25 volumio volumio[990634]: info: Loading plugin "upnp_browser"... Dec 28 19:34:26 volumio volumio[990634]: info: Starting UPNP Browser Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "alarm-clock"... Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "airplay_emulation"... Dec 28 19:34:26 volumio volumio[990634]: info: Starting Shairport Sync Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "last_100"... Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "webradio"... Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "i2s_dacs"... Dec 28 19:34:26 volumio volumio[990634]: info: I2S DAC not set, start Auto-detection Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "volumiodiscovery"... Dec 28 19:34:26 volumio volumio[990634]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:26 volumio volumio[990634]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:26 volumio volumio[990634]: *** WARNING *** For more information see Dec 28 19:34:26 volumio volumio[990634]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:26 volumio volumio[990634]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:26 volumio volumio[990634]: *** WARNING *** For more information see Dec 28 19:34:26 volumio node[990634]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:26 volumio node[990634]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:26 volumio node[990634]: *** WARNING *** For more information see Dec 28 19:34:26 volumio node[990634]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:26 volumio node[990634]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:26 volumio node[990634]: *** WARNING *** For more information see Dec 28 19:34:26 volumio volumio[990634]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 28 19:34:26 volumio volumio[990634]: info: Discovery: Started advertising with name: volumio Dec 28 19:34:26 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "spop"... Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "squeezelite_mc"... Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "autostart"... Dec 28 19:34:26 volumio volumio[990634]: info: Applying required configuration parameters for plugin autostart Dec 28 19:34:26 volumio volumio[990634]: info: AutoStart - onVolumioStart - read config.json Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "outputs"... Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "albumart"... Dec 28 19:34:26 volumio volumio[990634]: info: Plugin example_plugin is not enabled Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "inputs"... Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "updater_comm"... Dec 28 19:34:26 volumio go-librespot[887108]: time="2025-12-28T19:34:26+01:00" level=error msg="did not receive last pong from dealer, 180s passed" Dec 28 19:34:26 volumio volumio[990634]: info: Plugin mpdemulation is not enabled Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "rest_api"... Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "websocket"... Dec 28 19:34:26 volumio volumio[990634]: info: Starting Socket.io Server version 1.7.4 Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "backup_restore"... Dec 28 19:34:26 volumio volumio[990706]: Forking 3 albumart workers Dec 28 19:34:26 volumio volumio[990634]: info: Applying required configuration parameters for plugin backup_restore Dec 28 19:34:26 volumio volumio[990634]: info: Loading plugin "Systeminfo"... Dec 28 19:34:26 volumio ifplugd(eth0)[1336]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Dec 28 19:34:26 volumio volumio[990634]: info: Loading i18n strings for locale en Dec 28 19:34:26 volumio volumio[990634]: Updating browse sources language Dec 28 19:34:26 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:27 volumio ifplugd(eth0)[1336]: Program executed successfully. Dec 28 19:34:27 volumio kernel: RTL8211E Gigabit Ethernet r8169-0-300:00: attached PHY driver (mii_bus:phy_addr=r8169-0-300:00, irq=MAC) Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:27 volumio volumio[990716]: Starting albumart workers Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::initPlayerControls Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: Express server listening on port 3000 Dec 28 19:34:27 volumio volumio[990634]: [Metrics] WebUI: 2s 464.73ms Dec 28 19:34:27 volumio volumio[990717]: Starting albumart workers Dec 28 19:34:27 volumio volumio[990634]: info: Setting Device type: x86 Dec 28 19:34:27 volumio volumio[990634]: info: CoreStateMachine::resetVolumioState Dec 28 19:34:27 volumio volumio[990634]: info: CoreStateMachine::getcurrentVolume Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:27 volumio volumio[990718]: Starting albumart workers Dec 28 19:34:27 volumio volumio[990634]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 28 19:34:27 volumio volumio[990634]: info: Completed loading Core Plugins Dec 28 19:34:27 volumio volumio[990634]: info: Preparing to generate the ALSA configuration file Dec 28 19:34:27 volumio volumio[990634]: info: Cannot read play queue from file Dec 28 19:34:27 volumio volumio[990634]: info: Volumio Network Manager: Network status updated: 0 Dec 28 19:34:27 volumio volumio[990634]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 28 19:34:27 volumio volumio[990634]: wlan0 Interface doesn't support scanning. Dec 28 19:34:27 volumio volumio[990634]: info: Cannot use regular scanning, forcing with ap-force Dec 28 19:34:27 volumio kernel: r8169 0000:03:00.0 eth0: Link is Down Dec 28 19:34:27 volumio sudo[990773]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 28 19:34:27 volumio sudo[990773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:27 volumio sudo[990773]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:27 volumio volumio[990634]: command failed: No such device (-19) Dec 28 19:34:27 volumio volumio[990634]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 28 19:34:27 volumio volumio[990634]: command failed: No such device (-19) Dec 28 19:34:27 volumio volumio[990634]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 28 19:34:27 volumio volumio[990634]: info: VolumeController:: Volume=100 Mute =false Dec 28 19:34:27 volumio volumio[990634]: info: CoreStateMachine::pushState Dec 28 19:34:27 volumio volumio[990634]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:27 volumio volumio[990634]: info: CoreStateMachine::updateTrackBlock Dec 28 19:34:27 volumio volumio[990634]: info: CorePlayQueue::getTrackBlock Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 19:34:27 volumio volumio[990634]: info: Asound.conf file unchanged, so no further update is needed Dec 28 19:34:27 volumio volumio[990634]: info: Output device has changed, restarting MPD Dec 28 19:34:27 volumio sudo[990803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 19:34:27 volumio sudo[990803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:27 volumio volumio[990634]: info: Output device has changed, restarting Shairport Sync Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:27 volumio sudo[990803]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:27 volumio sudo[990806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 19:34:27 volumio sudo[990806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:27 volumio volumio[990634]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:34:27 volumio volumio[990634]: info: ___________ START PLUGINS ___________ Dec 28 19:34:27 volumio volumio[990634]: info: ControllerMpd::onStart: Initializing MPD Dec 28 19:34:27 volumio volumio[990634]: info: Creating MPD Configuration file Dec 28 19:34:27 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:34:27 volumio sudo[990813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 19:34:27 volumio sudo[990813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:27 volumio volumio[990634]: info: [1766946867479] CoreMusicLibrary::Adding element Media Servers Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:27 volumio volumio[990634]: info: UPNP Browser: Client initialized successfully Dec 28 19:34:27 volumio sudo[990815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 19:34:27 volumio sudo[990815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:27 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 28 19:34:27 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 19:34:27 volumio sudo[990813]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:27 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 28 19:34:27 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 19:34:27 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 19:34:27 volumio volumio[990634]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 19:34:27 volumio volumio[990634]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:34:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:34:27 volumio volumio[990634]: info: [1766946867516] CoreMusicLibrary::Adding element Last_100 Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:34:27 volumio volumio[990634]: info: [1766946867517] CoreMusicLibrary::Adding element Webradio Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:34:27 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 28 19:34:27 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 19:34:27 volumio volumio[990634]: info: Initializing BBC Radios Dec 28 19:34:27 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 28 19:34:27 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 19:34:27 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 19:34:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 19:34:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: Creating Spotify config file Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio sudo[990832]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 19:34:27 volumio sudo[990832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 19:34:27 volumio sudo[990832]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:27 volumio volumio[990634]: info: [squeezelite_mc] Starting proxy server... Dec 28 19:34:27 volumio volumio[990634]: info: AutoStart - onStart Dec 28 19:34:27 volumio volumio[990634]: info: Volumio Calling Home Dec 28 19:34:27 volumio volumio[990634]: info: [squeezelite_mc] Proxy server started on port 46881 Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 19:34:27 volumio volumio[990634]: info: CoreStateMachine::setRepeat null single undefined Dec 28 19:34:27 volumio volumio[990634]: info: CoreStateMachine::pushState Dec 28 19:34:27 volumio volumio[990634]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:27 volumio volumio[990634]: info: CoreStateMachine::setRandom null Dec 28 19:34:27 volumio volumio[990634]: info: CoreStateMachine::pushState Dec 28 19:34:27 volumio volumio[990634]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:27 volumio volumio[990634]: Unhandled rejection Error: No sockets available, cannot start. Dec 28 19:34:27 volumio volumio[990634]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 28 19:34:27 volumio volumio[990634]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 28 19:34:27 volumio volumio[990634]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 28 19:34:27 volumio volumio[990634]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 28 19:34:27 volumio volumio[990634]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 28 19:34:27 volumio volumio[990634]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 28 19:34:27 volumio volumio[990634]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 28 19:34:27 volumio volumio[990634]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 28 19:34:27 volumio volumio[990634]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Dec 28 19:34:27 volumio volumio[990634]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 28 19:34:27 volumio volumio[990634]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 28 19:34:27 volumio volumio[990634]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 28 19:34:27 volumio volumio[990634]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 28 19:34:27 volumio volumio[990634]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 28 19:34:27 volumio volumio[990634]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 28 19:34:27 volumio volumio[990634]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 28 19:34:27 volumio volumio[990634]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Dec 28 19:34:27 volumio volumio[990634]: info: MPD Permissions set Dec 28 19:34:27 volumio volumio[990634]: info: MPD Permissions set Dec 28 19:34:27 volumio volumio[990634]: error: Upnp client error: Error: This socket has been ended by the other party Dec 28 19:34:27 volumio volumio[990634]: info: VolumeController:: Volume=100 Mute =false Dec 28 19:34:27 volumio volumio[990634]: info: CoreStateMachine::pushState Dec 28 19:34:27 volumio volumio[990634]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:27 volumio volumio[990634]: info: [squeezelite_mc] Server discovery started Dec 28 19:34:27 volumio volumio[990634]: info: [squeezelite_mc] Player finder started Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:27 volumio volumio[990634]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 28 19:34:27 volumio volumio[990634]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:34:27 volumio volumio[990634]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 28 19:34:27 volumio volumio[990634]: at doSend (node:dgram:717:16) Dec 28 19:34:27 volumio volumio[990634]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Dec 28 19:34:27 volumio volumio[990634]: at afterDns (node:dgram:663:5) Dec 28 19:34:27 volumio volumio[990634]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Dec 28 19:34:27 volumio volumio[990634]: errno: -101, Dec 28 19:34:27 volumio volumio[990634]: code: 'ENETUNREACH', Dec 28 19:34:27 volumio volumio[990634]: syscall: 'send', Dec 28 19:34:27 volumio volumio[990634]: address: '255.255.255.255', Dec 28 19:34:27 volumio volumio[990634]: port: 3483 Dec 28 19:34:27 volumio volumio[990634]: } Dec 28 19:34:27 volumio volumio[990634]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:34:27 volumio sudo[990868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 19:33' Dec 28 19:34:27 volumio sudo[990868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:27 volumio sudo[990868]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:28 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 28 19:34:28 volumio kernel: upmpdcli[1980]: segfault at 18 ip 00007fb93c32a640 sp 00007fb935e92958 error 4 in libmpdclient.so.2.20[b640,7fb93c327000+e000] likely on CPU 1 (core 1, socket 0) Dec 28 19:34:28 volumio kernel: Code: 8b 47 24 c3 50 48 8d 0d 9e b5 00 00 ba 41 00 00 00 48 8d 35 05 b5 00 00 48 8d 3d 43 b5 00 00 e8 f6 cd ff ff 66 0f 1f 44 00 00 <8b> 57 18 53 31 c0 48 89 fb 83 fa 09 77 22 48 c7 c1 f2 fd ff ff 48 Dec 28 19:34:28 volumio systemd[1]: upmpdcli.service: Main process exited, code=killed, status=11/SEGV Dec 28 19:34:28 volumio systemd[1]: upmpdcli.service: Failed with result 'signal'. Dec 28 19:34:28 volumio systemd[1]: upmpdcli.service: Consumed 1min 59.500s CPU time. Dec 28 19:34:28 volumio sudo[990815]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:28 volumio sudo[990806]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:28 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 28 19:34:28 volumio systemd[1]: volumio.service: Consumed 6.973s CPU time. Dec 28 19:34:28 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 28 19:34:28 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 28 19:34:28 volumio mpd[990844]: 2025-12-28T19:34:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 19:34:28 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Dec 28 19:34:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 28 19:34:28 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 28 19:34:28 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 28 19:34:28 volumio systemd[1]: volumio.service: Consumed 6.973s CPU time. Dec 28 19:34:28 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 28 19:34:28 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 28 19:34:28 volumio volumio[990890]: info: ------------------------------------------- Dec 28 19:34:28 volumio volumio[990890]: info: ----- Volumio3 ---- Dec 28 19:34:28 volumio volumio[990890]: info: ------------------------------------------- Dec 28 19:34:28 volumio volumio[990890]: info: ----- System startup ---- Dec 28 19:34:28 volumio volumio[990890]: info: ------------------------------------------- Dec 28 19:34:28 volumio volumio[990890]: info: MYVOLUMIO Environment detected Dec 28 19:34:28 volumio volumio[990890]: info: Plugin folders cleanup Dec 28 19:34:28 volumio volumio[990890]: info: Scanning into folder /volumio/app/plugins/ Dec 28 19:34:28 volumio volumio[990890]: info: Scanning category audio_interface Dec 28 19:34:28 volumio volumio[990890]: info: Scanning category miscellanea Dec 28 19:34:28 volumio volumio[990890]: info: Scanning category music_service Dec 28 19:34:28 volumio volumio[990890]: info: Scanning category plugins.json Dec 28 19:34:28 volumio volumio[990890]: info: Scanning category system_controller Dec 28 19:34:28 volumio volumio[990890]: info: Scanning category user_interface Dec 28 19:34:28 volumio volumio[990890]: info: Scanning into folder /data/plugins/ Dec 28 19:34:28 volumio volumio[990890]: info: Scanning category music_service Dec 28 19:34:28 volumio volumio[990890]: info: Scanning category system_controller Dec 28 19:34:28 volumio volumio[990890]: info: Scanning category user_interface Dec 28 19:34:28 volumio volumio[990890]: info: Plugin folders cleanup completed Dec 28 19:34:28 volumio volumio[990890]: info: ------------------------------------------- Dec 28 19:34:28 volumio volumio[990890]: info: ----- Core plugins startup ---- Dec 28 19:34:28 volumio volumio[990890]: info: ------------------------------------------- Dec 28 19:34:28 volumio volumio[990890]: info: Loading plugins from folder /volumio/app/plugins/ Dec 28 19:34:28 volumio volumio[990890]: info: Adding plugin upnp to MyMusic Plugins Dec 28 19:34:28 volumio volumio[990890]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 28 19:34:28 volumio volumio[990890]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 28 19:34:28 volumio volumio[990890]: info: Loading plugins from folder /data/plugins/ Dec 28 19:34:28 volumio volumio[990890]: info: Loading plugin "system"... Dec 28 19:34:28 volumio volumio[990890]: info: Loading plugin "appearance"... Dec 28 19:34:29 volumio volumio-remote-updater[861]: [2025-12-28 19:34:29] [connect] Successful connection Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "network"... Dec 28 19:34:29 volumio volumio[990890]: info: Refreshing Cached IP Addresses Dec 28 19:34:29 volumio sudo[990919]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 19:34:29 volumio sudo[990919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:29 volumio sudo[990921]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 19:34:29 volumio sudo[990921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "services"... Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "alsa_controller"... Dec 28 19:34:29 volumio sudo[990919]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:29 volumio sudo[990929]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 28 19:34:29 volumio sudo[990929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:29 volumio sudo[990921]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:29 volumio sudo[990929]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:29 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "wizard"... Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "networkfs"... Dec 28 19:34:29 volumio volumio[990890]: info: Starting Udev Watcher for removable devices Dec 28 19:34:29 volumio volumio[990890]: info: Ignoring mount for partition: boot Dec 28 19:34:29 volumio volumio[990890]: info: Ignoring mount for partition: volumio Dec 28 19:34:29 volumio volumio[990890]: info: Ignoring mount for partition: volumio_data Dec 28 19:34:29 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "volumio_command_line_client"... Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "upnp"... Dec 28 19:34:29 volumio volumio[990890]: info: [1766946869295] Starting Upmpd Daemon Dec 28 19:34:29 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "my_music"... Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "mpd"... Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "upnp_browser"... Dec 28 19:34:29 volumio volumio[990890]: info: Starting UPNP Browser Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "alarm-clock"... Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "airplay_emulation"... Dec 28 19:34:29 volumio volumio[990890]: info: Starting Shairport Sync Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "last_100"... Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "webradio"... Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "i2s_dacs"... Dec 28 19:34:29 volumio volumio[990890]: info: I2S DAC not set, start Auto-detection Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "volumiodiscovery"... Dec 28 19:34:29 volumio volumio[990890]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:29 volumio volumio[990890]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:29 volumio volumio[990890]: *** WARNING *** For more information see Dec 28 19:34:29 volumio volumio[990890]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:29 volumio volumio[990890]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:29 volumio volumio[990890]: *** WARNING *** For more information see Dec 28 19:34:29 volumio node[990890]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:29 volumio node[990890]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:29 volumio node[990890]: *** WARNING *** For more information see Dec 28 19:34:29 volumio node[990890]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:29 volumio node[990890]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:29 volumio node[990890]: *** WARNING *** For more information see Dec 28 19:34:29 volumio volumio[990890]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 28 19:34:29 volumio volumio[990890]: info: Discovery: Started advertising with name: volumio Dec 28 19:34:29 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:34:29 volumio volumio[990890]: info: Loading plugin "spop"... Dec 28 19:34:30 volumio dhcpcd[894]: eth0: carrier acquired Dec 28 19:34:30 volumio kernel: r8169 0000:03:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 28 19:34:30 volumio dhcpcd[894]: eth0: IAID 2e:65:4b:e6 Dec 28 19:34:30 volumio dhcpcd[894]: eth0: probing address 192.168.200.32/24 Dec 28 19:34:30 volumio volumio[990890]: info: Loading plugin "squeezelite_mc"... Dec 28 19:34:30 volumio ifplugd(eth0)[1336]: Link beat detected. Dec 28 19:34:30 volumio ifplugd(eth0)[1336]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 28 19:34:30 volumio ifplugd(eth0)[1336]: client: command failed: No such device (-19) Dec 28 19:34:30 volumio volumio[990890]: info: Loading plugin "autostart"... Dec 28 19:34:30 volumio volumio[990890]: info: Applying required configuration parameters for plugin autostart Dec 28 19:34:30 volumio volumio[990890]: info: AutoStart - onVolumioStart - read config.json Dec 28 19:34:30 volumio volumio[990890]: info: Loading plugin "outputs"... Dec 28 19:34:30 volumio volumio[990890]: info: Loading plugin "albumart"... Dec 28 19:34:30 volumio volumio[990890]: info: Plugin example_plugin is not enabled Dec 28 19:34:30 volumio volumio[990890]: info: Loading plugin "inputs"... Dec 28 19:34:30 volumio volumio[990890]: info: Loading plugin "updater_comm"... Dec 28 19:34:30 volumio volumio[990890]: info: Plugin mpdemulation is not enabled Dec 28 19:34:30 volumio volumio[990890]: info: Loading plugin "rest_api"... Dec 28 19:34:30 volumio ifplugd(eth0)[1336]: Program executed successfully. Dec 28 19:34:30 volumio volumio[990890]: info: Loading plugin "websocket"... Dec 28 19:34:30 volumio volumio[990890]: info: Starting Socket.io Server version 1.7.4 Dec 28 19:34:30 volumio volumio[990890]: info: Loading plugin "backup_restore"... Dec 28 19:34:30 volumio volumio[991012]: Forking 3 albumart workers Dec 28 19:34:30 volumio volumio[990890]: info: Applying required configuration parameters for plugin backup_restore Dec 28 19:34:30 volumio volumio[990890]: info: Loading plugin "Systeminfo"... Dec 28 19:34:30 volumio volumio[990890]: info: Loading i18n strings for locale en Dec 28 19:34:30 volumio volumio[990890]: Updating browse sources language Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::initPlayerControls Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:30 volumio volumio[990890]: Express server listening on port 3000 Dec 28 19:34:30 volumio volumio[990890]: [Metrics] WebUI: 2s 453.38ms Dec 28 19:34:30 volumio volumio[990890]: info: Setting Device type: x86 Dec 28 19:34:30 volumio volumio[991023]: Starting albumart workers Dec 28 19:34:30 volumio volumio[990890]: info: CoreStateMachine::resetVolumioState Dec 28 19:34:30 volumio volumio[990890]: info: CoreStateMachine::getcurrentVolume Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 19:34:30 volumio volumio[991024]: Starting albumart workers Dec 28 19:34:30 volumio volumio[991022]: Starting albumart workers Dec 28 19:34:30 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:31 volumio volumio[990890]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 28 19:34:31 volumio volumio[990890]: info: Completed loading Core Plugins Dec 28 19:34:31 volumio volumio[990890]: info: Preparing to generate the ALSA configuration file Dec 28 19:34:31 volumio volumio[990890]: info: Cannot read play queue from file Dec 28 19:34:31 volumio volumio[990890]: info: Volumio Network Manager: Network status updated: 0 Dec 28 19:34:31 volumio volumio[990890]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 28 19:34:31 volumio volumio[990890]: wlan0 Interface doesn't support scanning. Dec 28 19:34:31 volumio volumio[990890]: info: Cannot use regular scanning, forcing with ap-force Dec 28 19:34:31 volumio sudo[991056]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 28 19:34:31 volumio sudo[991056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:31 volumio sudo[991056]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:31 volumio volumio[990890]: command failed: No such device (-19) Dec 28 19:34:31 volumio volumio[990890]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 28 19:34:31 volumio volumio[990890]: command failed: No such device (-19) Dec 28 19:34:31 volumio volumio[990890]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 28 19:34:31 volumio volumio-remote-updater[861]: [2025-12-28 19:34:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766946869 101 Dec 28 19:34:31 volumio volumio[990890]: 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: 1 Dec 28 19:34:31 volumio dhcpcd[894]: eth0: soliciting an IPv6 router Dec 28 19:34:31 volumio volumio[990890]: info: VolumeController:: Volume=100 Mute =false Dec 28 19:34:31 volumio volumio[990890]: info: CoreStateMachine::pushState Dec 28 19:34:31 volumio volumio[990890]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:31 volumio volumio[990890]: info: CoreStateMachine::updateTrackBlock Dec 28 19:34:31 volumio volumio[990890]: info: CorePlayQueue::getTrackBlock Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 19:34:31 volumio volumio[990890]: info: Asound.conf file unchanged, so no further update is needed Dec 28 19:34:31 volumio volumio[990890]: info: Output device has changed, restarting MPD Dec 28 19:34:31 volumio volumio[990890]: info: Output device has changed, restarting Shairport Sync Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:31 volumio sudo[991068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 19:34:31 volumio sudo[991068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:31 volumio sudo[991068]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:31 volumio sudo[991070]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 19:34:31 volumio sudo[991070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:31 volumio volumio[990890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:34:31 volumio volumio[990890]: info: ___________ START PLUGINS ___________ Dec 28 19:34:31 volumio volumio[990890]: info: ControllerMpd::onStart: Initializing MPD Dec 28 19:34:31 volumio volumio[990890]: info: Creating MPD Configuration file Dec 28 19:34:31 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 28 19:34:31 volumio sudo[991086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 19:34:31 volumio sudo[991086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:31 volumio sudo[991086]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:31 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 28 19:34:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 19:34:31 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 28 19:34:31 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 19:34:31 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:34:31 volumio volumio[990890]: info: [1766946871291] CoreMusicLibrary::Adding element Media Servers Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:31 volumio volumio[990890]: info: UPNP Browser: Client initialized successfully Dec 28 19:34:31 volumio sudo[991093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 19:34:31 volumio sudo[991093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:31 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 19:34:31 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 19:34:31 volumio volumio[990890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:31 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 28 19:34:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 19:34:31 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 28 19:34:31 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 19:34:31 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 19:34:31 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 19:34:31 volumio volumio[990890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:34:31 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:34:31 volumio volumio[990890]: info: [1766946871347] CoreMusicLibrary::Adding element Last_100 Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:34:31 volumio volumio[990890]: info: [1766946871351] CoreMusicLibrary::Adding element Webradio Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:34:31 volumio volumio[990890]: info: Initializing BBC Radios Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:31 volumio volumio[990890]: info: Creating Spotify config file Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:31 volumio sudo[991106]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 19:34:31 volumio sudo[991106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 19:34:31 volumio sudo[991106]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:31 volumio volumio[990890]: info: [squeezelite_mc] Starting proxy server... Dec 28 19:34:31 volumio volumio[990890]: info: AutoStart - onStart Dec 28 19:34:31 volumio volumio[990890]: info: Volumio Calling Home Dec 28 19:34:31 volumio volumio[990890]: info: [squeezelite_mc] Proxy server started on port 37699 Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 19:34:31 volumio volumio[990890]: info: CoreStateMachine::setRepeat null single undefined Dec 28 19:34:31 volumio volumio[990890]: info: CoreStateMachine::pushState Dec 28 19:34:31 volumio volumio[990890]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:31 volumio volumio[990890]: info: CoreStateMachine::setRandom null Dec 28 19:34:31 volumio volumio[990890]: info: CoreStateMachine::pushState Dec 28 19:34:31 volumio volumio[990890]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:31 volumio volumio[990890]: Unhandled rejection Error: No sockets available, cannot start. Dec 28 19:34:31 volumio volumio[990890]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 28 19:34:31 volumio volumio[990890]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 28 19:34:31 volumio volumio[990890]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 28 19:34:31 volumio volumio[990890]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 28 19:34:31 volumio volumio[990890]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 28 19:34:31 volumio volumio[990890]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 28 19:34:31 volumio volumio[990890]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 28 19:34:31 volumio volumio[990890]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 28 19:34:31 volumio volumio[990890]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Dec 28 19:34:31 volumio volumio[990890]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 28 19:34:31 volumio volumio[990890]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 28 19:34:31 volumio volumio[990890]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 28 19:34:31 volumio volumio[990890]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 28 19:34:31 volumio volumio[990890]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 28 19:34:31 volumio volumio[990890]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 28 19:34:31 volumio volumio[990890]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 28 19:34:31 volumio volumio[990890]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Dec 28 19:34:31 volumio volumio[990890]: info: MPD Permissions set Dec 28 19:34:31 volumio volumio[990890]: info: MPD Permissions set Dec 28 19:34:31 volumio volumio[990890]: info: VolumeController:: Volume=100 Mute =false Dec 28 19:34:31 volumio volumio[990890]: info: CoreStateMachine::pushState Dec 28 19:34:31 volumio volumio[990890]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:31 volumio volumio[990890]: info: [squeezelite_mc] Server discovery started Dec 28 19:34:31 volumio volumio[990890]: info: [squeezelite_mc] Player finder started Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:31 volumio volumio[990890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:31 volumio volumio[990890]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 28 19:34:31 volumio volumio[990890]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:34:31 volumio volumio[990890]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 28 19:34:31 volumio volumio[990890]: at doSend (node:dgram:717:16) Dec 28 19:34:31 volumio volumio[990890]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Dec 28 19:34:31 volumio volumio[990890]: at afterDns (node:dgram:663:5) Dec 28 19:34:31 volumio volumio[990890]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Dec 28 19:34:31 volumio volumio[990890]: errno: -101, Dec 28 19:34:31 volumio volumio[990890]: code: 'ENETUNREACH', Dec 28 19:34:31 volumio volumio[990890]: syscall: 'send', Dec 28 19:34:31 volumio volumio[990890]: address: '255.255.255.255', Dec 28 19:34:31 volumio volumio[990890]: port: 3483 Dec 28 19:34:31 volumio volumio[990890]: } Dec 28 19:34:31 volumio volumio[990890]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:34:31 volumio sudo[991150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 19:33' Dec 28 19:34:31 volumio sudo[991150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:31 volumio sudo[991150]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:31 volumio volumio-remote-updater[861]: [2025-12-28 19:34:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 28 19:34:31 volumio volumio-remote-updater[861]: [2025-12-28 19:34:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 28 19:34:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 28 19:34:31 volumio sudo[991093]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:31 volumio sudo[991070]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:31 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 28 19:34:31 volumio systemd[1]: volumio.service: Consumed 7.017s CPU time. Dec 28 19:34:31 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 28 19:34:31 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 28 19:34:31 volumio mpd[991127]: 2025-12-28T19:34:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 19:34:31 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 28 19:34:32 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Dec 28 19:34:32 volumio systemd[1]: dynamicswap.service: Start request repeated too quickly. Dec 28 19:34:32 volumio systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Dec 28 19:34:32 volumio systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Dec 28 19:34:32 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 28 19:34:32 volumio systemd[1]: volumio.service: Consumed 7.017s CPU time. Dec 28 19:34:32 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 28 19:34:32 volumio go-librespot[887108]: time="2025-12-28T19:34:32+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:32 volumio go-librespot[887108]: time="2025-12-28T19:34:32+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Dec 28 19:34:32 volumio go-librespot[887108]: time="2025-12-28T19:34:32+01:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Dec 28 19:34:32 volumio go-librespot[887108]: time="2025-12-28T19:34:32+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Dec 28 19:34:32 volumio go-librespot[887108]: time="2025-12-28T19:34:32+01:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Dec 28 19:34:32 volumio volumio[991167]: info: ------------------------------------------- Dec 28 19:34:32 volumio volumio[991167]: info: ----- Volumio3 ---- Dec 28 19:34:32 volumio volumio[991167]: info: ------------------------------------------- Dec 28 19:34:32 volumio volumio[991167]: info: ----- System startup ---- Dec 28 19:34:32 volumio volumio[991167]: info: ------------------------------------------- Dec 28 19:34:32 volumio volumio[991167]: info: MYVOLUMIO Environment detected Dec 28 19:34:32 volumio volumio[991167]: info: Plugin folders cleanup Dec 28 19:34:32 volumio volumio[991167]: info: Scanning into folder /volumio/app/plugins/ Dec 28 19:34:32 volumio volumio[991167]: info: Scanning category audio_interface Dec 28 19:34:32 volumio volumio[991167]: info: Scanning category miscellanea Dec 28 19:34:32 volumio volumio[991167]: info: Scanning category music_service Dec 28 19:34:32 volumio volumio[991167]: info: Scanning category plugins.json Dec 28 19:34:32 volumio volumio[991167]: info: Scanning category system_controller Dec 28 19:34:32 volumio volumio[991167]: info: Scanning category user_interface Dec 28 19:34:32 volumio volumio[991167]: info: Scanning into folder /data/plugins/ Dec 28 19:34:32 volumio volumio[991167]: info: Scanning category music_service Dec 28 19:34:32 volumio volumio[991167]: info: Scanning category system_controller Dec 28 19:34:32 volumio volumio[991167]: info: Scanning category user_interface Dec 28 19:34:32 volumio volumio[991167]: info: Plugin folders cleanup completed Dec 28 19:34:32 volumio volumio[991167]: info: ------------------------------------------- Dec 28 19:34:32 volumio volumio[991167]: info: ----- Core plugins startup ---- Dec 28 19:34:32 volumio volumio[991167]: info: ------------------------------------------- Dec 28 19:34:32 volumio volumio[991167]: info: Loading plugins from folder /volumio/app/plugins/ Dec 28 19:34:32 volumio volumio[991167]: info: Adding plugin upnp to MyMusic Plugins Dec 28 19:34:32 volumio volumio[991167]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 28 19:34:32 volumio volumio[991167]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 28 19:34:32 volumio volumio[991167]: info: Loading plugins from folder /data/plugins/ Dec 28 19:34:32 volumio volumio[991167]: info: Loading plugin "system"... Dec 28 19:34:32 volumio volumio[991167]: info: Loading plugin "appearance"... Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "network"... Dec 28 19:34:33 volumio volumio[991167]: info: Refreshing Cached IP Addresses Dec 28 19:34:33 volumio sudo[991191]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 19:34:33 volumio sudo[991191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:33 volumio sudo[991191]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:33 volumio sudo[991193]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 19:34:33 volumio sudo[991193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "services"... Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "alsa_controller"... Dec 28 19:34:33 volumio sudo[991201]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 28 19:34:33 volumio sudo[991201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:33 volumio sudo[991193]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:33 volumio sudo[991201]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:33 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "wizard"... Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "networkfs"... Dec 28 19:34:33 volumio volumio[991167]: info: Starting Udev Watcher for removable devices Dec 28 19:34:33 volumio volumio[991167]: info: Ignoring mount for partition: boot Dec 28 19:34:33 volumio volumio[991167]: info: Ignoring mount for partition: volumio Dec 28 19:34:33 volumio volumio[991167]: info: Ignoring mount for partition: volumio_data Dec 28 19:34:33 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "volumio_command_line_client"... Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "upnp"... Dec 28 19:34:33 volumio volumio[991167]: info: [1766946873169] Starting Upmpd Daemon Dec 28 19:34:33 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "my_music"... Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "mpd"... Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "upnp_browser"... Dec 28 19:34:33 volumio volumio[991167]: info: Starting UPNP Browser Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "alarm-clock"... Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "airplay_emulation"... Dec 28 19:34:33 volumio volumio[991167]: info: Starting Shairport Sync Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "last_100"... Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "webradio"... Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "i2s_dacs"... Dec 28 19:34:33 volumio volumio[991167]: info: I2S DAC not set, start Auto-detection Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "volumiodiscovery"... Dec 28 19:34:33 volumio volumio[991167]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:33 volumio volumio[991167]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:33 volumio volumio[991167]: *** WARNING *** For more information see Dec 28 19:34:33 volumio volumio[991167]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:33 volumio volumio[991167]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:33 volumio volumio[991167]: *** WARNING *** For more information see Dec 28 19:34:33 volumio node[991167]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:33 volumio node[991167]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:33 volumio node[991167]: *** WARNING *** For more information see Dec 28 19:34:33 volumio node[991167]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 19:34:33 volumio node[991167]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 19:34:33 volumio node[991167]: *** WARNING *** For more information see Dec 28 19:34:33 volumio volumio[991167]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 28 19:34:33 volumio volumio[991167]: info: Discovery: Started advertising with name: volumio Dec 28 19:34:33 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 19:34:33 volumio volumio[991167]: info: Loading plugin "spop"... Dec 28 19:34:34 volumio volumio[991167]: info: Loading plugin "squeezelite_mc"... Dec 28 19:34:34 volumio volumio[991167]: info: Loading plugin "autostart"... Dec 28 19:34:34 volumio volumio[991167]: info: Applying required configuration parameters for plugin autostart Dec 28 19:34:34 volumio volumio[991167]: info: AutoStart - onVolumioStart - read config.json Dec 28 19:34:34 volumio volumio[991167]: info: Loading plugin "outputs"... Dec 28 19:34:34 volumio volumio[991167]: info: Loading plugin "albumart"... Dec 28 19:34:34 volumio volumio[991167]: info: Plugin example_plugin is not enabled Dec 28 19:34:34 volumio volumio[991167]: info: Loading plugin "inputs"... Dec 28 19:34:34 volumio volumio[991167]: info: Loading plugin "updater_comm"... Dec 28 19:34:34 volumio volumio[991167]: info: Plugin mpdemulation is not enabled Dec 28 19:34:34 volumio volumio[991167]: info: Loading plugin "rest_api"... Dec 28 19:34:34 volumio volumio[991167]: info: Loading plugin "websocket"... Dec 28 19:34:34 volumio volumio[991167]: info: Starting Socket.io Server version 1.7.4 Dec 28 19:34:34 volumio volumio[991167]: info: Loading plugin "backup_restore"... Dec 28 19:34:34 volumio volumio[991231]: Forking 3 albumart workers Dec 28 19:34:34 volumio volumio[991167]: info: Applying required configuration parameters for plugin backup_restore Dec 28 19:34:34 volumio volumio[991167]: info: Loading plugin "Systeminfo"... Dec 28 19:34:34 volumio volumio[991167]: info: Loading i18n strings for locale en Dec 28 19:34:34 volumio volumio[991167]: Updating browse sources language Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::initPlayerControls Dec 28 19:34:34 volumio volumio[991243]: Starting albumart workers Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:34 volumio volumio[991167]: Express server listening on port 3000 Dec 28 19:34:34 volumio volumio[991167]: [Metrics] WebUI: 2s 422.16ms Dec 28 19:34:34 volumio volumio[991167]: info: Setting Device type: x86 Dec 28 19:34:34 volumio volumio[991242]: Starting albumart workers Dec 28 19:34:34 volumio volumio[991167]: info: CoreStateMachine::resetVolumioState Dec 28 19:34:34 volumio volumio[991167]: info: CoreStateMachine::getcurrentVolume Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:34 volumio volumio[991167]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 28 19:34:34 volumio volumio[991167]: info: Completed loading Core Plugins Dec 28 19:34:34 volumio volumio[991167]: info: Preparing to generate the ALSA configuration file Dec 28 19:34:34 volumio volumio[991241]: Starting albumart workers Dec 28 19:34:34 volumio volumio[991167]: info: Cannot read play queue from file Dec 28 19:34:34 volumio volumio[991167]: info: Volumio Network Manager: Network status updated: 0 Dec 28 19:34:34 volumio volumio[991167]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 28 19:34:34 volumio volumio[991167]: wlan0 Interface doesn't support scanning. Dec 28 19:34:34 volumio volumio[991167]: info: Cannot use regular scanning, forcing with ap-force Dec 28 19:34:34 volumio sudo[991275]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 28 19:34:34 volumio sudo[991275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:34 volumio sudo[991275]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:34 volumio volumio[991167]: command failed: No such device (-19) Dec 28 19:34:34 volumio volumio[991167]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 28 19:34:34 volumio volumio[991167]: command failed: No such device (-19) Dec 28 19:34:34 volumio volumio[991167]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 28 19:34:34 volumio volumio[991167]: info: VolumeController:: Volume=100 Mute =false Dec 28 19:34:34 volumio volumio[991167]: info: CoreStateMachine::pushState Dec 28 19:34:34 volumio volumio[991167]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:34 volumio volumio[991167]: info: CoreStateMachine::updateTrackBlock Dec 28 19:34:34 volumio volumio[991167]: info: CorePlayQueue::getTrackBlock Dec 28 19:34:34 volumio volumio[991167]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 19:34:35 volumio volumio[991167]: info: Asound.conf file unchanged, so no further update is needed Dec 28 19:34:35 volumio volumio[991167]: info: Output device has changed, restarting MPD Dec 28 19:34:35 volumio volumio[991167]: info: Output device has changed, restarting Shairport Sync Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:35 volumio sudo[991287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 19:34:35 volumio sudo[991287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:35 volumio sudo[991287]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:35 volumio sudo[991289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 19:34:35 volumio sudo[991289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:35 volumio volumio[991167]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:34:35 volumio volumio[991167]: info: ___________ START PLUGINS ___________ Dec 28 19:34:35 volumio volumio[991167]: info: ControllerMpd::onStart: Initializing MPD Dec 28 19:34:35 volumio volumio[991167]: info: Creating MPD Configuration file Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:34:35 volumio volumio[991167]: info: [1766946875103] CoreMusicLibrary::Adding element Media Servers Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:35 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 28 19:34:35 volumio sudo[991303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 19:34:35 volumio volumio[991167]: info: UPNP Browser: Client initialized successfully Dec 28 19:34:35 volumio sudo[991303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:35 volumio sudo[991305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 19:34:35 volumio sudo[991303]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:35 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 28 19:34:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 19:34:35 volumio sudo[991305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 28 19:34:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 19:34:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 19:34:35 volumio volumio[991167]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 19:34:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 19:34:35 volumio volumio[991167]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:34:35 volumio volumio[991167]: info: [1766946875176] CoreMusicLibrary::Adding element Last_100 Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 19:34:35 volumio volumio[991167]: info: [1766946875183] CoreMusicLibrary::Adding element Webradio Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 19:34:35 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 28 19:34:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 19:34:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 28 19:34:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 19:34:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:34:35 volumio volumio[991167]: info: Initializing BBC Radios Dec 28 19:34:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 19:34:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 19:34:35 volumio volumio[991167]: info: Creating Spotify config file Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:35 volumio sudo[991332]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 19:34:35 volumio sudo[991332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 19:34:35 volumio sudo[991332]: pam_unix(sudo:session): session closed for user root Dec 28 19:34:35 volumio volumio[991167]: info: [squeezelite_mc] Starting proxy server... Dec 28 19:34:35 volumio volumio[991167]: info: AutoStart - onStart Dec 28 19:34:35 volumio volumio[991167]: info: Volumio Calling Home Dec 28 19:34:35 volumio volumio[991167]: info: [squeezelite_mc] Proxy server started on port 37565 Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 19:34:35 volumio volumio[991167]: info: CoreStateMachine::setRepeat null single undefined Dec 28 19:34:35 volumio volumio[991167]: info: CoreStateMachine::pushState Dec 28 19:34:35 volumio volumio[991167]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:35 volumio volumio[991167]: info: CoreStateMachine::setRandom null Dec 28 19:34:35 volumio volumio[991167]: info: CoreStateMachine::pushState Dec 28 19:34:35 volumio volumio[991167]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:35 volumio volumio[991167]: Unhandled rejection Error: No sockets available, cannot start. Dec 28 19:34:35 volumio volumio[991167]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 28 19:34:35 volumio volumio[991167]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 28 19:34:35 volumio volumio[991167]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 28 19:34:35 volumio volumio[991167]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 28 19:34:35 volumio volumio[991167]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 28 19:34:35 volumio volumio[991167]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 28 19:34:35 volumio volumio[991167]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 28 19:34:35 volumio volumio[991167]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 28 19:34:35 volumio volumio[991167]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Dec 28 19:34:35 volumio volumio[991167]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 28 19:34:35 volumio volumio[991167]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 28 19:34:35 volumio volumio[991167]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 28 19:34:35 volumio volumio[991167]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 28 19:34:35 volumio volumio[991167]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 28 19:34:35 volumio volumio[991167]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 28 19:34:35 volumio volumio[991167]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 28 19:34:35 volumio volumio[991167]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Dec 28 19:34:35 volumio volumio[991167]: info: MPD Permissions set Dec 28 19:34:35 volumio volumio[991167]: info: MPD Permissions set Dec 28 19:34:35 volumio volumio[991167]: info: VolumeController:: Volume=100 Mute =false Dec 28 19:34:35 volumio volumio[991167]: info: CoreStateMachine::pushState Dec 28 19:34:35 volumio volumio[991167]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:35 volumio volumio[991167]: info: VolumeController:: Volume=100 Mute =false Dec 28 19:34:35 volumio volumio[991167]: info: CoreStateMachine::pushState Dec 28 19:34:35 volumio volumio[991167]: info: CorePlayQueue::getTrack 0 Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::volumioPushState Dec 28 19:34:35 volumio volumio[991167]: info: [squeezelite_mc] Server discovery started Dec 28 19:34:35 volumio volumio[991167]: info: [squeezelite_mc] Player finder started Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 19:34:35 volumio volumio[991167]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:34:35 volumio volumio[991167]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Dec 28 19:34:35 volumio volumio[991167]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:34:35 volumio volumio[991167]: Error: send ENETUNREACH 255.255.255.255:3483 Dec 28 19:34:35 volumio volumio[991167]: at doSend (node:dgram:717:16) Dec 28 19:34:35 volumio volumio[991167]: at defaultTriggerAsyncIdScope (node:internal/async_hooks:464:18) Dec 28 19:34:35 volumio volumio[991167]: at afterDns (node:dgram:663:5) Dec 28 19:34:35 volumio volumio[991167]: at process.processTicksAndRejections (node:internal/process/task_queues:83:21) { Dec 28 19:34:35 volumio volumio[991167]: errno: -101, Dec 28 19:34:35 volumio volumio[991167]: code: 'ENETUNREACH', Dec 28 19:34:35 volumio volumio[991167]: syscall: 'send', Dec 28 19:34:35 volumio volumio[991167]: address: '255.255.255.255', Dec 28 19:34:35 volumio volumio[991167]: port: 3483 Dec 28 19:34:35 volumio volumio[991167]: } Dec 28 19:34:35 volumio volumio[991167]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:34:35 volumio avahi-daemon[853]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.200.32. Dec 28 19:34:35 volumio dhcpcd[894]: eth0: using static address 192.168.200.32/24 Dec 28 19:34:35 volumio avahi-daemon[853]: New relevant interface eth0.IPv4 for mDNS. Dec 28 19:34:35 volumio avahi-daemon[853]: Registering new address record for 192.168.200.32 on eth0.IPv4. Dec 28 19:34:35 volumio dhcpcd[894]: eth0: adding route to 192.168.200.0/24 Dec 28 19:34:35 volumio dhcpcd[894]: eth0: adding default route via 192.168.200.1 Dec 28 19:34:35 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 19:34:35 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 19:34:35 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 28 19:34:35 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 28 19:34:35 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 28 19:34:35 volumio sudo[991388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 19:33' Dec 28 19:34:35 volumio sudo[991388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:34:35 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 28 19:34:35 volumio welcome[991382]: Resolved ip:[1] 192.168.200.32 Dec 28 19:34:35 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 28 19:34:35 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"