Jun 20 13:09:03 kche wpa_supplicant[1241]: wlan0: Failed to initiate sched scan Jun 20 13:09:04 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:04 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:09 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:09 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:10 kche wpa_supplicant[1241]: wlan0: Failed to initiate sched scan Jun 20 13:09:14 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:14 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:18 kche wpa_supplicant[1241]: wlan0: Failed to initiate sched scan Jun 20 13:09:19 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:19 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:24 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:24 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:25 kche wpa_supplicant[1241]: wlan0: Failed to initiate sched scan Jun 20 13:09:29 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:29 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:33 kche wpa_supplicant[1241]: wlan0: Failed to initiate sched scan Jun 20 13:09:34 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:34 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:39 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:39 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:40 kche wpa_supplicant[1241]: wlan0: Failed to initiate sched scan Jun 20 13:09:44 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:44 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:48 kche wpa_supplicant[1241]: wlan0: Failed to initiate sched scan Jun 20 13:09:49 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:49 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:54 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:54 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:54 kche volumio[1420]: info: Tunnel connection is inactive, restarting it Jun 20 13:09:54 kche volumio[1420]: info: Starting Tunnel 1 Jun 20 13:09:54 kche volumio[1420]: info: Starting Tunnel Connection Checker Jun 20 13:09:55 kche sudo[18166]: volumio : unable to resolve host kche: System error Jun 20 13:09:55 kche sudo[18166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 20 13:09:55 kche sudo[18166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 13:09:55 kche systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 20 13:09:55 kche systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 13:09:55 kche sudo[18166]: pam_unix(sudo:session): session closed for user root Jun 20 13:09:55 kche dbus-daemon[934]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.293' (uid=0 pid=18169 comm="timedatectl show --property=NTPSynchronized --valu") Jun 20 13:09:55 kche volumio[1420]: info: Remote SSH Started Jun 20 13:09:55 kche autossh[18172]: port set to 0, monitoring disabled Jun 20 13:09:55 kche autossh[18172]: starting ssh (count 1) Jun 20 13:09:55 kche autossh[18172]: ssh child pid is 18175 Jun 20 13:09:55 kche volumio[1420]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 20 13:09:55 kche systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 20 13:09:55 kche volumio[1420]: info: CoreCommandRouter::volumioGetState Jun 20 13:09:55 kche volumiossh-tunnel[18175]: ssh: Could not resolve hostname eu7.myvolumio.org: Device or resource busy Jun 20 13:09:55 kche autossh[18172]: ssh exited prematurely with status 255; autossh exiting Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 20 13:09:55 kche dbus-daemon[934]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 20 13:09:55 kche systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 20 13:09:55 kche setdatetime-helper.sh[18168]: Time is already synchronized. Jun 20 13:09:55 kche systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 20 13:09:55 kche systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Jun 20 13:09:55 kche systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 13:09:55 kche systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 13:09:55 kche autossh[18178]: port set to 0, monitoring disabled Jun 20 13:09:55 kche autossh[18178]: starting ssh (count 1) Jun 20 13:09:55 kche autossh[18178]: ssh child pid is 18181 Jun 20 13:09:55 kche volumiossh-tunnel[18181]: ssh: Could not resolve hostname eu7.myvolumio.org: Device or resource busy Jun 20 13:09:55 kche autossh[18178]: ssh exited prematurely with status 255; autossh exiting Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Jun 20 13:09:55 kche systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 13:09:55 kche systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 13:09:55 kche autossh[18183]: port set to 0, monitoring disabled Jun 20 13:09:55 kche autossh[18183]: starting ssh (count 1) Jun 20 13:09:55 kche autossh[18183]: ssh child pid is 18186 Jun 20 13:09:55 kche volumiossh-tunnel[18186]: ssh: Could not resolve hostname eu7.myvolumio.org: Device or resource busy Jun 20 13:09:55 kche autossh[18183]: ssh exited prematurely with status 255; autossh exiting Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Jun 20 13:09:55 kche systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 13:09:55 kche systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 13:09:55 kche autossh[18188]: port set to 0, monitoring disabled Jun 20 13:09:55 kche autossh[18188]: starting ssh (count 1) Jun 20 13:09:55 kche autossh[18188]: ssh child pid is 18191 Jun 20 13:09:55 kche volumiossh-tunnel[18191]: ssh: Could not resolve hostname eu7.myvolumio.org: Device or resource busy Jun 20 13:09:55 kche autossh[18188]: ssh exited prematurely with status 255; autossh exiting Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 20 13:09:55 kche wpa_supplicant[1241]: wlan0: Failed to initiate sched scan Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Jun 20 13:09:55 kche systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 13:09:55 kche systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 13:09:55 kche autossh[18193]: port set to 0, monitoring disabled Jun 20 13:09:55 kche autossh[18193]: starting ssh (count 1) Jun 20 13:09:55 kche autossh[18193]: ssh child pid is 18196 Jun 20 13:09:55 kche volumiossh-tunnel[18196]: ssh: Could not resolve hostname eu7.myvolumio.org: Device or resource busy Jun 20 13:09:55 kche autossh[18193]: ssh exited prematurely with status 255; autossh exiting Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 20 13:09:55 kche systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 20 13:09:56 kche systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Jun 20 13:09:56 kche systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 13:09:56 kche systemd[1]: sshtunnel.service: Start request repeated too quickly. Jun 20 13:09:56 kche systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 20 13:09:56 kche systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 13:09:59 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:09:59 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:10:03 kche wpa_supplicant[1241]: wlan0: Failed to initiate sched scan Jun 20 13:10:04 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:10:04 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:10:09 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:10:09 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:10:10 kche wpa_supplicant[1241]: wlan0: Failed to initiate sched scan Jun 20 13:10:14 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:10:14 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:10:18 kche wpa_supplicant[1241]: wlan0: Trying to associate with 08:8a:f1:05:5c:c0 (SSID='MultiroomAudio' freq=2422 MHz) Jun 20 13:10:18 kche wpa_supplicant[1241]: wlan0: Associated with 08:8a:f1:05:5c:c0 Jun 20 13:10:18 kche wpa_supplicant[1241]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 20 13:10:18 kche wpa_supplicant[1241]: wlan0: WPA: Key negotiation completed with 08:8a:f1:05:5c:c0 [PTK=CCMP GTK=CCMP] Jun 20 13:10:18 kche wpa_supplicant[1241]: wlan0: CTRL-EVENT-CONNECTED - Connection to 08:8a:f1:05:5c:c0 completed [id=0 id_str=] Jun 20 13:10:18 kche dhcpcd[967]: wlan0: carrier acquired Jun 20 13:10:18 kche dhcpcd[967]: wlan0: IAID 67:2d:da:be Jun 20 13:10:18 kche dhcpcd[967]: wlan0: soliciting an IPv6 router Jun 20 13:10:19 kche dhcpcd[967]: wlan0: rebinding lease of 192.168.1.11 Jun 20 13:10:19 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:10:19 kche volumio[1420]: info: Discovery: Remote connection error: 192.168.1.14 Jun 20 13:10:19 kche dhcpcd[967]: wlan0: probing address 192.168.1.11/24 Jun 20 13:10:23 kche dhcpcd[967]: wlan0: leased 192.168.1.11 for infinity Jun 20 13:10:23 kche avahi-daemon[1411]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.11. Jun 20 13:10:23 kche avahi-daemon[1411]: New relevant interface wlan0.IPv4 for mDNS. Jun 20 13:10:23 kche avahi-daemon[1411]: Registering new address record for 192.168.1.11 on wlan0.IPv4. Jun 20 13:10:23 kche dhcpcd[967]: wlan0: adding route to 192.168.1.0/24 Jun 20 13:10:23 kche dhcpcd[967]: wlan0: adding default route via 192.168.1.1 Jun 20 13:10:23 kche systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 20 13:10:23 kche systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 20 13:10:23 kche systemd[1]: welcome.service: Deactivated successfully. Jun 20 13:10:23 kche systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 20 13:10:23 kche systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 20 13:10:23 kche systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 20 13:10:23 kche welcome[18256]: Resolved ip:[1] 192.168.1.11 Jun 20 13:10:23 kche systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 20 13:10:23 kche systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 20 13:10:25 kche ntpd[1095]: IO: Listen normally on 5 wlan0 192.168.1.11:123 Jun 20 13:10:25 kche ntpd[1095]: IO: new interface(s) found: waking up resolver Jun 20 13:10:25 kche ntpd[1095]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 20 13:10:25 kche systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 20 13:10:26 kche volumio[1420]: compat.c: read() failed: Resource temporarily unavailable Jun 20 13:10:26 kche volumio[1420]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 13:10:26 kche volumio[1420]: Error: dns service error: unknown Jun 20 13:10:26 kche volumio[1420]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 20 13:10:26 kche volumio[1420]: errorCode: -65537 Jun 20 13:10:26 kche volumio[1420]: } Jun 20 13:10:26 kche volumio[1420]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 13:10:39 kche ntpd[1095]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 20 13:10:39 kche ntpd[1095]: DNS: dns_check: DNS error: -11, System error Jun 20 13:10:39 kche ntpd[1095]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Jun 20 13:10:40 kche ntpd[1095]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 20 13:10:43 kche ntpd[1095]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 20 13:10:43 kche ntpd[1095]: DNS: Pool taking: 217.91.44.17 Jun 20 13:10:43 kche ntpd[1095]: DNS: Pool taking: 116.203.96.227 Jun 20 13:10:43 kche ntpd[1095]: DNS: Pool taking: 116.203.187.86 Jun 20 13:10:43 kche ntpd[1095]: DNS: Pool taking: 185.13.148.71 Jun 20 13:10:43 kche ntpd[1095]: DNS: Pool taking: 2a03:4000:21:209::1 Jun 20 13:10:43 kche ntpd[1095]: DNS: Pool taking: 2001:678:ce0:6974:6d71:7474:4e:5450 Jun 20 13:10:43 kche ntpd[1095]: DNS: Pool taking: 2603:c020:8017:3eff::123:123 Jun 20 13:10:43 kche ntpd[1095]: DNS: Pool taking: 2a01:4f8:191:946a::2 Jun 20 13:10:43 kche ntpd[1095]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jun 20 13:10:43 kche sudo[18287]: volumio : unable to resolve host kche: System error Jun 20 13:10:43 kche sudo[18287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-20 13:09' Jun 20 13:10:43 kche sudo[18287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 14 07:24:14 UTC 2025" VOLUMIO_VERSION="4.012" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b365b054264e3ebc2709665da2b5d1ee"