Jun 01 14:28:14 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 01 14:28:14 volumio dbus-daemon[598]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.238' (uid=0 pid=5242 comm="timedatectl show --property=NTPSynchronized --valu") Jun 01 14:28:14 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 01 14:28:14 volumio dbus-daemon[598]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 01 14:28:14 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 01 14:28:14 volumio setdatetime-helper.sh[5241]: Time is not synchronized. Attempting to sync... Jun 01 14:28:14 volumio sudo[5250]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 01 Jun 2025 05:28:14 GMT#015' Jun 01 14:28:14 volumio sudo[5250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 01 14:28:14 volumio setdatetime-helper.sh[5251]: Sun Jun 1 14:28:14 JST 2025 Jun 01 14:28:14 volumio systemd-journald[337]: Time jumped backwards, rotating. Jun 01 14:28:14 volumio sudo[5250]: pam_unix(sudo:session): session closed for user root Jun 01 14:28:14 volumio setdatetime-helper.sh[5241]: Time synchronized successfully. Jun 01 14:28:14 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 01 14:28:14 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 01 14:28:43 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 01 14:29:00 volumio volumio[4712]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Jun 01 14:29:00 volumio volumio[4712]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Jun 01 14:29:10 volumio nmbd[925]: [2025/06/01 14:29:10.078403, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 01 14:29:10 volumio nmbd[925]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Jun 01 14:29:23 volumio volumio[4712]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Jun 01 14:29:23 volumio volumio[4712]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Jun 01 14:29:23 volumio sudo[5358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Jun 01 14:29:23 volumio sudo[5358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:29:23 volumio sudo[5358]: pam_unix(sudo:session): session closed for user root Jun 01 14:29:23 volumio sudo[5360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Jun 01 14:29:23 volumio sudo[5360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:29:23 volumio sudo[5360]: pam_unix(sudo:session): session closed for user root Jun 01 14:29:23 volumio volumio[4712]: info: Permissions for /etc/network/interfaces set Jun 01 14:29:23 volumio volumio[4712]: info: Restarting Wired Network Jun 01 14:29:23 volumio volumio[4712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 01 14:29:23 volumio volumio[4712]: info: Discovery: Restarting Advertising due to device name change Jun 01 14:29:23 volumio sudo[5363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Jun 01 14:29:23 volumio sudo[5363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:29:23 volumio avahi-daemon[4196]: Withdrawing address record for 192.168.0.97 on eth0. Jun 01 14:29:23 volumio dhcpcd[670]: eth0: pid 5365 deleted IP address 192.168.0.97/24 Jun 01 14:29:23 volumio dhcpcd[670]: eth0: deleting route to 192.168.0.0/24 Jun 01 14:29:23 volumio dhcpcd[670]: eth0: deleting default route via 192.168.0.1 Jun 01 14:29:23 volumio avahi-daemon[4196]: Withdrawing address record for 192.168.0.98 on eth0. Jun 01 14:29:23 volumio dhcpcd[580]: eth0: pid 0 deleted route to 192.168.0.0/24 Jun 01 14:29:23 volumio dhcpcd[580]: eth0: pid 0 deleted default route via 192.168.0.1 Jun 01 14:29:23 volumio dhcpcd[580]: eth0: pid 5365 deleted IP address 192.168.0.98/24 Jun 01 14:29:23 volumio avahi-daemon[4196]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.98. Jun 01 14:29:23 volumio avahi-daemon[4196]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 01 14:29:23 volumio sudo[5363]: pam_unix(sudo:session): session closed for user root Jun 01 14:29:23 volumio volumio[4712]: info: Discovery: A device disappeared from network Jun 01 14:29:23 volumio volumio[4712]: info: Discovery: Device volumio disappeared from network Jun 01 14:29:23 volumio sudo[5369]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Jun 01 14:29:23 volumio sudo[5369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:29:23 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jun 01 14:29:23 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jun 01 14:29:23 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jun 01 14:29:23 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jun 01 14:29:23 volumio sudo[5369]: pam_unix(sudo:session): session closed for user root Jun 01 14:29:23 volumio dhcpcd[580]: eth0: rebinding lease of 192.168.0.98 Jun 01 14:29:23 volumio dhcpcd[580]: eth0: carrier lost Jun 01 14:29:23 volumio dhcpcd[670]: eth0: rebinding lease of 192.168.0.98 Jun 01 14:29:23 volumio dhcpcd[670]: eth0: pid 5365 deleted IP address 192.168.0.98/24 Jun 01 14:29:23 volumio sudo[5403]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Jun 01 14:29:23 volumio sudo[5403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 01 14:29:23 volumio dhcpcd[5411]: ps_bpf_recvmsg: Network is down Jun 01 14:29:23 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 01 14:29:23 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 01 14:29:23 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 01 14:29:23 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 01 14:29:23 volumio dhcpcd[5404]: ps_bpf_recvmsg: Network is down Jun 01 14:29:23 volumio dhcpcd[5410]: ps_bpf_recvmsg: Network is down Jun 01 14:29:23 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jun 01 14:29:23 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jun 01 14:29:23 volumio sudo[5403]: pam_unix(sudo:session): session closed for user root Jun 01 14:29:23 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 01 14:29:23 volumio dhcpcd[670]: eth0: rebinding lease of 192.168.0.98 Jun 01 14:29:23 volumio dhcpcd[670]: eth0: carrier lost Jun 01 14:29:23 volumio welcome[5436]: Resolved ip:[1] 192.168.0.86 Jun 01 14:29:23 volumio dbus-daemon[598]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.240' (uid=0 pid=5439 comm="timedatectl show --property=NTPSynchronized --valu") Jun 01 14:29:23 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 01 14:29:23 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 01 14:29:23 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jun 01 14:29:23 volumio ifplugd(eth0)[856]: Link beat lost. Jun 01 14:29:23 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jun 01 14:29:23 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jun 01 14:29:23 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 01 14:29:23 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 01 14:29:23 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 01 14:29:23 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 01 14:29:23 volumio welcome[5491]: Resolved ip:[1] 192.168.0.86 Jun 01 14:29:23 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 01 14:29:23 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jun 01 14:29:23 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jun 01 14:29:23 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jun 01 14:29:23 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 01 14:29:23 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 01 14:29:23 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 01 14:29:23 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 01 14:29:23 volumio welcome[5497]: Resolved ip:[1] 192.168.0.86 Jun 01 14:29:23 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 01 14:29:23 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jun 01 14:29:24 volumio dbus-daemon[598]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 01 14:29:24 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 01 14:29:24 volumio setdatetime-helper.sh[5398]: Time is already synchronized. Jun 01 14:29:24 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 01 14:29:24 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 01 14:29:24 volumio volumio[4712]: info: Discovery: A device disappeared from network Jun 01 14:29:24 volumio volumio[4712]: info: Discovery: A device disappeared from network Jun 01 14:29:25 volumio ntpd[862]: IO: Deleting interface #3 eth0, 192.168.0.97#123, interface stats: received=457, sent=457, dropped=0, active_time=1405 secs Jun 01 14:29:25 volumio ntpd[862]: PROTO: 47.79.37.210 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 45.76.221.157 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 172.237.12.44 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 45.76.211.39 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 23.81.40.154 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 162.159.200.123 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 122.215.240.52 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 139.162.81.45 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 162.159.200.1 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 160.16.113.133 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 202.181.103.212 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 64.176.59.83 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 103.131.151.30 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 108.160.132.224 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 45.77.20.103 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 103.131.151.20 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: PROTO: 129.250.35.250 unlink local addr 192.168.0.97 -> Jun 01 14:29:25 volumio ntpd[862]: IO: Deleting interface #4 eth0, 192.168.0.98#123, interface stats: received=0, sent=0, dropped=0, active_time=1405 secs Jun 01 14:29:25 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jun 01 14:29:25 volumio dhcpcd[580]: eth0: carrier acquired Jun 01 14:29:25 volumio dhcpcd[670]: eth0: carrier acquired Jun 01 14:29:25 volumio dhcpcd[670]: eth0: config file changed, expiring leases Jun 01 14:29:25 volumio dhcpcd[580]: eth0: config file changed, expiring leases Jun 01 14:29:25 volumio dhcpcd[670]: eth0: IAID eb:e1:03:99 Jun 01 14:29:25 volumio dhcpcd[580]: eth0: IAID eb:e1:03:99 Jun 01 14:29:25 volumio dhcpcd[670]: eth0: probing address 192.168.0.110/24 Jun 01 14:29:25 volumio dhcpcd[580]: eth0: probing address 192.168.0.110/24 Jun 01 14:29:25 volumio dhcpcd[670]: eth0: soliciting an IPv6 router Jun 01 14:29:25 volumio dhcpcd[580]: eth0: soliciting an IPv6 router Jun 01 14:29:25 volumio ifplugd(eth0)[856]: Link beat detected. Jun 01 14:29:28 volumio volumio[4712]: info: Discovery: Started advertising with name: Volumio Jun 01 14:29:29 volumio volumio[4712]: compat.c: read() failed: Resource temporarily unavailable Jun 01 14:29:29 volumio volumio[4712]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 14:29:29 volumio volumio[4712]: Error: dns service error: unknown Jun 01 14:29:29 volumio volumio[4712]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 01 14:29:29 volumio volumio[4712]: errorCode: -65537 Jun 01 14:29:29 volumio volumio[4712]: } Jun 01 14:29:29 volumio volumio[4712]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 14:29:29 volumio dhcpcd[580]: eth0: using static address 192.168.0.110/24 Jun 01 14:29:30 volumio avahi-daemon[4196]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.110. Jun 01 14:29:30 volumio avahi-daemon[4196]: New relevant interface eth0.IPv4 for mDNS. Jun 01 14:29:30 volumio avahi-daemon[4196]: Registering new address record for 192.168.0.110 on eth0.IPv4. Jun 01 14:29:30 volumio dhcpcd[580]: eth0: adding route to 192.168.0.0/24 Jun 01 14:29:30 volumio dhcpcd[580]: eth0: adding default route via 192.168.0.1 Jun 01 14:29:30 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jun 01 14:29:30 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jun 01 14:29:30 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 01 14:29:30 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 01 14:29:30 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 01 14:29:30 volumio sudo[5556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-01 14:28' Jun 01 14:29:30 volumio sudo[5556]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" 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 May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"