Jun 12 19:54:00 volumio4 sudo[1712]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Jun 12 19:54:00 volumio4 sudo[1712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:00 volumio4 sudo[1712]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:00 volumio4 sudo[1714]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Jun 12 19:54:00 volumio4 sudo[1714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:00 volumio4 kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Jun 12 19:54:00 volumio4 kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Jun 12 19:54:00 volumio4 sudo[1714]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:00 volumio4 volumio[1040]: info: Discovery: A device disappeared from network Jun 12 19:54:00 volumio4 volumio[1040]: info: Discovery: Device volumio4 disappeared from network Jun 12 19:54:00 volumio4 volumio[1040]: info: Discovery: A device disappeared from network Jun 12 19:54:04 volumio4 volumio[1040]: info: Discovery: Started advertising with name: Volumio4 Jun 12 19:54:05 volumio4 volumio[1040]: info: Discovery: adding 17c4f718-31bc-4387-95fc-bb550f1dd248 Jun 12 19:54:05 volumio4 volumio[1040]: info: Discovery: Found device Volumio4 Jun 12 19:54:05 volumio4 volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 12 19:54:05 volumio4 volumio[1040]: info: CorePlayQueue::getTrack 0 Jun 12 19:54:05 volumio4 volumio[1040]: info: Discovery: this is already registered, 17c4f718-31bc-4387-95fc-bb550f1dd248 Jun 12 19:54:05 volumio4 volumio[1040]: info: Discovery: Found device Volumio4 Jun 12 19:54:05 volumio4 volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 12 19:54:05 volumio4 volumio[1040]: info: CorePlayQueue::getTrack 0 Jun 12 19:54:10 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 12 19:54:10 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 12 19:54:10 volumio4 volumio[1040]: info: Refreshing Cached IP Addresses Jun 12 19:54:10 volumio4 sudo[1734]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 12 19:54:10 volumio4 sudo[1734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:10 volumio4 sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 12 19:54:10 volumio4 sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:10 volumio4 sudo[1736]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:10 volumio4 systemd[1]: upmpdcli.service: Deactivated successfully. Jun 12 19:54:10 volumio4 systemd[1]: upmpdcli.service: Consumed 13.594s CPU time. Jun 12 19:54:10 volumio4 sudo[1738]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 12 19:54:10 volumio4 sudo[1734]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:10 volumio4 sudo[1738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:10 volumio4 sudo[1738]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:10 volumio4 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 12 19:54:10 volumio4 dbus-daemon[568]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.27' (uid=0 pid=1743 comm="timedatectl show --property=NTPSynchronized --valu") Jun 12 19:54:10 volumio4 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 12 19:54:10 volumio4 dbus-daemon[568]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 12 19:54:10 volumio4 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 12 19:54:10 volumio4 sudo[1747]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 12 19:54:10 volumio4 sudo[1747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:10 volumio4 setdatetime-helper.sh[1741]: Time is not synchronized. Attempting to sync... Jun 12 19:54:10 volumio4 sudo[1747]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:10 volumio4 sudo[1749]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 12 19:54:10 volumio4 sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:10 volumio4 sudo[1749]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:11 volumio4 sudo[1757]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 12 Jun 2025 10:54:11 GMT#015' Jun 12 19:54:11 volumio4 sudo[1757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 19:54:11 volumio4 setdatetime-helper.sh[1758]: Thu Jun 12 19:54:11 JST 2025 Jun 12 19:54:11 volumio4 systemd-journald[330]: Time jumped backwards, rotating. Jun 12 19:54:11 volumio4 sudo[1757]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:11 volumio4 setdatetime-helper.sh[1741]: Time synchronized successfully. Jun 12 19:54:11 volumio4 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 12 19:54:11 volumio4 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 12 19:54:11 volumio4 volumio[1040]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.19 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::volumioGetVisibleSources Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 12 19:54:11 volumio4 volumio[1040]: info: CorePlayQueue::getTrack 0 Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 12 19:54:11 volumio4 volumio[1040]: info: Received Get System Info Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 19:54:11 volumio4 volumio[1040]: info: Discovery: Getting this device information Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 12 19:54:11 volumio4 volumio[1040]: info: CorePlayQueue::getTrack 0 Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 12 19:54:11 volumio4 volumio[1040]: info: CorePlayQueue::getTrack 0 Jun 12 19:54:11 volumio4 volumio[1040]: info: Listing playlists Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 12 19:54:11 volumio4 sudo[1765]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 12 19:54:11 volumio4 sudo[1765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:11 volumio4 sudo[1765]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:11 volumio4 sudo[1770]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 12 19:54:11 volumio4 sudo[1770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:11 volumio4 sudo[1777]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 12 19:54:11 volumio4 sudo[1777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:11 volumio4 sudo[1783]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 12 19:54:11 volumio4 sudo[1783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 12 19:54:11 volumio4 sudo[1783]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:11 volumio4 sudo[1770]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:11 volumio4 sudo[1777]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:11 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 12 19:54:11 volumio4 sudo[1785]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 12 19:54:11 volumio4 sudo[1785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:11 volumio4 sudo[1792]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 12 19:54:11 volumio4 sudo[1792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:11 volumio4 sudo[1785]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:11 volumio4 sudo[1792]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:11 volumio4 sudo[1797]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 12 19:54:11 volumio4 sudo[1797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:12 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 12 19:54:12 volumio4 volumio[1040]: info: Received Get System Info Jun 12 19:54:12 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 19:54:12 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 19:54:12 volumio4 volumio[1040]: info: Discovery: Getting this device information Jun 12 19:54:12 volumio4 volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 12 19:54:12 volumio4 volumio[1040]: info: CorePlayQueue::getTrack 0 Jun 12 19:54:12 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 19:54:12 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 12 19:54:15 volumio4 sudo[1797]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:20 volumio4 sudo[1815]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 12 19:54:20 volumio4 sudo[1815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:20 volumio4 sudo[1815]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:20 volumio4 sudo[1817]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 12 19:54:20 volumio4 sudo[1817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:20 volumio4 sudo[1817]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:20 volumio4 sudo[1820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 12 19:54:20 volumio4 sudo[1820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:54:20 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 12 19:54:20 volumio4 sudo[1820]: pam_unix(sudo:session): session closed for user root Jun 12 19:54:20 volumio4 volumio[1040]: info: Upmpdcli Daemon Started Jun 12 19:54:20 volumio4 upmpdcli[1837]: writing RSA key Jun 12 19:54:20 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 12 19:54:26 volumio4 volumio[1040]: info: CoreCommandRouter::Close All Modals sent Jun 12 19:54:26 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Jun 12 19:54:29 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 12 19:54:29 volumio4 volumio[1040]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 12 19:54:29 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 12 19:54:29 volumio4 volumio[1040]: info: Received Get System Version Jun 12 19:54:29 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 19:54:29 volumio4 volumio[1040]: info: Received Get System Info Jun 12 19:54:29 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 19:54:29 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 19:54:29 volumio4 volumio[1040]: info: Discovery: Getting this device information Jun 12 19:54:29 volumio4 volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 12 19:54:29 volumio4 volumio[1040]: info: CorePlayQueue::getTrack 0 Jun 12 19:54:29 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 19:54:40 volumio4 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 12 19:55:13 volumio4 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 12 19:55:13 volumio4 dbus-daemon[568]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.29' (uid=0 pid=1921 comm="timedatectl show --property=NTPSynchronized --valu") Jun 12 19:55:13 volumio4 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 12 19:55:13 volumio4 dbus-daemon[568]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 12 19:55:13 volumio4 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 12 19:55:13 volumio4 setdatetime-helper.sh[1920]: Time is not synchronized. Attempting to sync... Jun 12 19:55:13 volumio4 sudo[1929]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 12 Jun 2025 10:55:14 GMT#015' Jun 12 19:55:13 volumio4 sudo[1929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 19:55:14 volumio4 setdatetime-helper.sh[1930]: Thu Jun 12 19:55:14 JST 2025 Jun 12 19:55:14 volumio4 sudo[1929]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:14 volumio4 setdatetime-helper.sh[1920]: Time synchronized successfully. Jun 12 19:55:14 volumio4 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 12 19:55:14 volumio4 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 12 19:55:32 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jun 12 19:55:32 volumio4 volumio[1040]: info: Saving new wireless network Jun 12 19:55:32 volumio4 sudo[1961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jun 12 19:55:32 volumio4 sudo[1961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:32 volumio4 sudo[1961]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:32 volumio4 volumio[1040]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 12 19:55:32 volumio4 volumio[1040]: info: Discovery: Restarting Advertising due to device name change Jun 12 19:55:32 volumio4 sudo[1964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 12 19:55:32 volumio4 sudo[1964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:32 volumio4 systemd[1]: Stopping wireless.service - Wireless Services... Jun 12 19:55:32 volumio4 wpa_supplicant[960]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 12 19:55:32 volumio4 wpa_supplicant[960]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 12 19:55:32 volumio4 wpa_supplicant[960]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Jun 12 19:55:32 volumio4 wpa_supplicant[960]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Jun 12 19:55:32 volumio4 wpa_supplicant[960]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:25:dc:d2:59:bf reason=3 locally_generated=1 Jun 12 19:55:32 volumio4 dhcpcd[675]: wlan0: carrier lost - roaming Jun 12 19:55:32 volumio4 dhcpcd[675]: wlan0: changing route to 192.168.0.0/24 Jun 12 19:55:32 volumio4 dhcpcd[675]: wlan0: changing default route via 192.168.0.1 Jun 12 19:55:32 volumio4 wpa_supplicant[960]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 12 19:55:32 volumio4 wpa_supplicant[960]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jun 12 19:55:32 volumio4 wpa_supplicant[960]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jun 12 19:55:32 volumio4 dhcpcd[675]: wlan0: carrier acquired Jun 12 19:55:33 volumio4 avahi-daemon[1034]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 12 19:55:33 volumio4 avahi-daemon[1034]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.10. Jun 12 19:55:33 volumio4 avahi-daemon[1034]: Withdrawing address record for 192.168.0.10 on wlan0. Jun 12 19:55:33 volumio4 wpa_supplicant[960]: wlan0: CTRL-EVENT-TERMINATING Jun 12 19:55:33 volumio4 dhcpcd[675]: wlan0: config file changed, expiring leases Jun 12 19:55:33 volumio4 systemd[1]: wireless.service: Deactivated successfully. Jun 12 19:55:33 volumio4 systemd[1]: Stopped wireless.service - Wireless Services. Jun 12 19:55:33 volumio4 systemd[1]: wireless.service: Consumed 52.964s CPU time. Jun 12 19:55:33 volumio4 dhcpcd[675]: wlan0: deleting route to 192.168.0.0/24 Jun 12 19:55:33 volumio4 dhcpcd[675]: wlan0: deleting default route via 192.168.0.1 Jun 12 19:55:33 volumio4 volumio[1040]: info: Discovery: A device disappeared from network Jun 12 19:55:33 volumio4 volumio[1040]: info: Discovery: Device volumio4 disappeared from network Jun 12 19:55:33 volumio4 dhcpcd[675]: wlan0: IAID eb:12:cc:8e Jun 12 19:55:33 volumio4 volumio[1040]: info: Discovery: A device disappeared from network Jun 12 19:55:33 volumio4 systemd[1]: Starting wireless.service - Wireless Services... Jun 12 19:55:33 volumio4 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 12 19:55:33 volumio4 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 12 19:55:33 volumio4 systemd[1]: welcome.service: Deactivated successfully. Jun 12 19:55:33 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 12 19:55:33 volumio4 dhcpcd[675]: wlan0: carrier lost Jun 12 19:55:33 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 12 19:55:33 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 12 19:55:33 volumio4 welcome[2001]: Resolved ip:[0] Jun 12 19:55:33 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 12 19:55:33 volumio4 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 12 19:55:34 volumio4 ntpd[819]: IO: Deleting interface #3 wlan0, 192.168.0.10#123, interface stats: received=140, sent=140, dropped=0, active_time=232 secs Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 47.79.37.210 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 122.215.240.51 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 45.77.20.103 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 162.159.200.123 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 108.160.132.224 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 129.250.35.250 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 103.131.151.30 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 160.16.113.133 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 172.237.12.44 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 129.250.35.251 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 122.215.240.52 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 23.81.40.154 unlink local addr 192.168.0.10 -> Jun 12 19:55:34 volumio4 ntpd[819]: PROTO: 133.130.121.141 unlink local addr 192.168.0.10 -> Jun 12 19:55:35 volumio4 sudo[2023]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 12 19:55:35 volumio4 sudo[2023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 19:55:35 volumio4 sudo[2023]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:35 volumio4 sudo[2025]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 12 19:55:35 volumio4 sudo[2025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 19:55:35 volumio4 sudo[2025]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:35 volumio4 sudo[2033]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 12 19:55:35 volumio4 sudo[2033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:35 volumio4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 12 19:55:35 volumio4 sudo[2033]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:35 volumio4 sudo[2035]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 12 19:55:35 volumio4 sudo[2035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:35 volumio4 sudo[2035]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:35 volumio4 sudo[2043]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 12 19:55:36 volumio4 sudo[2043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:36 volumio4 sudo[2043]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:36 volumio4 sudo[2047]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 12 19:55:36 volumio4 sudo[2047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:36 volumio4 volumio[1040]: info: Volumio Network Manager: Network status updated: 0 Jun 12 19:55:37 volumio4 volumio[1040]: info: Discovery: Started advertising with name: Volumio4 Jun 12 19:55:38 volumio4 volumio[1040]: info: Discovery: adding 17c4f718-31bc-4387-95fc-bb550f1dd248 Jun 12 19:55:38 volumio4 volumio[1040]: info: Discovery: Found device Volumio4 Jun 12 19:55:38 volumio4 volumio[1040]: info: CoreCommandRouter::volumioGetState Jun 12 19:55:38 volumio4 volumio[1040]: info: CorePlayQueue::getTrack 0 Jun 12 19:55:38 volumio4 sudo[2047]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:38 volumio4 sudo[2067]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 12 19:55:38 volumio4 sudo[2067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:38 volumio4 sudo[2067]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:38 volumio4 sudo[2069]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Jun 12 19:55:38 volumio4 sudo[2069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:38 volumio4 sudo[2069]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:39 volumio4 sudo[2077]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 12 19:55:39 volumio4 sudo[2077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 19:55:39 volumio4 sudo[2077]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:39 volumio4 sudo[2079]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 12 19:55:39 volumio4 sudo[2079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 19:55:39 volumio4 sudo[2079]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:39 volumio4 wpa_supplicant[2084]: Successfully initialized wpa_supplicant Jun 12 19:55:39 volumio4 sudo[2085]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 12 19:55:39 volumio4 sudo[2085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 19:55:39 volumio4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 12 19:55:39 volumio4 dhcpcd[675]: ps_ctl_dispatch: cannot handle another client Jun 12 19:55:39 volumio4 dhcpcd[675]: control_free: No such file or directory Jun 12 19:55:39 volumio4 sudo[2085]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:40 volumio4 wpa_supplicant[2084]: nl80211: kernel reports: Registration to specific type not supported Jun 12 19:55:40 volumio4 sudo[2092]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 19:55:40 volumio4 sudo[2092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:40 volumio4 sudo[2092]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:41 volumio4 sudo[2096]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 19:55:41 volumio4 sudo[2096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:41 volumio4 sudo[2096]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:42 volumio4 wpa_supplicant[2089]: wlan0: Trying to associate with 00:25:dc:d2:59:bf (SSID='eoRT-2d259bf-a' freq=5180 MHz) Jun 12 19:55:42 volumio4 wpa_supplicant[2089]: wlan0: Associated with 00:25:dc:d2:59:bf Jun 12 19:55:42 volumio4 wpa_supplicant[2089]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 12 19:55:42 volumio4 wpa_supplicant[2089]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Jun 12 19:55:42 volumio4 sudo[2101]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 19:55:42 volumio4 sudo[2101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:42 volumio4 sudo[2101]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:43 volumio4 wpa_supplicant[2089]: wlan0: WPA: Key negotiation completed with 00:25:dc:d2:59:bf [PTK=CCMP GTK=CCMP] Jun 12 19:55:43 volumio4 wpa_supplicant[2089]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:25:dc:d2:59:bf completed [id=0 id_str=] Jun 12 19:55:43 volumio4 dhcpcd[675]: wlan0: carrier acquired Jun 12 19:55:43 volumio4 dhcpcd[675]: wlan0: IAID eb:12:cc:8e Jun 12 19:55:43 volumio4 sudo[2106]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 19:55:43 volumio4 sudo[2106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:43 volumio4 sudo[2106]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:43 volumio4 dhcpcd[675]: wlan0: soliciting an IPv6 router Jun 12 19:55:43 volumio4 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 12 19:55:44 volumio4 dhcpcd[675]: wlan0: soliciting a DHCP lease Jun 12 19:55:44 volumio4 sudo[2113]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 19:55:44 volumio4 sudo[2113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:44 volumio4 sudo[2113]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:45 volumio4 dhcpcd[675]: wlan0: offered 192.168.0.10 from 192.168.0.1 Jun 12 19:55:45 volumio4 dhcpcd[675]: wlan0: probing address 192.168.0.10/24 Jun 12 19:55:45 volumio4 sudo[2117]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 19:55:45 volumio4 sudo[2117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:45 volumio4 sudo[2117]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:46 volumio4 sudo[2136]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 19:55:46 volumio4 sudo[2136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:46 volumio4 sudo[2136]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:47 volumio4 sudo[2140]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 19:55:47 volumio4 sudo[2140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:47 volumio4 sudo[2140]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:48 volumio4 sudo[2144]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 19:55:48 volumio4 sudo[2144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:48 volumio4 sudo[2144]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:49 volumio4 sudo[2148]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 19:55:49 volumio4 sudo[2148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:49 volumio4 sudo[2148]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:50 volumio4 dhcpcd[675]: wlan0: leased 192.168.0.10 for 86400 seconds Jun 12 19:55:50 volumio4 avahi-daemon[1034]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.10. Jun 12 19:55:50 volumio4 avahi-daemon[1034]: New relevant interface wlan0.IPv4 for mDNS. Jun 12 19:55:50 volumio4 avahi-daemon[1034]: Registering new address record for 192.168.0.10 on wlan0.IPv4. Jun 12 19:55:50 volumio4 dhcpcd[675]: wlan0: adding route to 192.168.0.0/24 Jun 12 19:55:50 volumio4 dhcpcd[675]: wlan0: adding default route via 192.168.0.1 Jun 12 19:55:50 volumio4 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 12 19:55:50 volumio4 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 12 19:55:50 volumio4 systemd[1]: welcome.service: Deactivated successfully. Jun 12 19:55:50 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 12 19:55:50 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 12 19:55:50 volumio4 volumio[1040]: compat.c: read() failed: Resource temporarily unavailable Jun 12 19:55:50 volumio4 volumio[1040]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 19:55:50 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 12 19:55:50 volumio4 welcome[2158]: Resolved ip:[1] 192.168.0.10 Jun 12 19:55:50 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 12 19:55:50 volumio4 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 12 19:55:50 volumio4 volumio[1040]: Error: dns service error: unknown Jun 12 19:55:50 volumio4 volumio[1040]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 12 19:55:50 volumio4 volumio[1040]: errorCode: -65537 Jun 12 19:55:50 volumio4 volumio[1040]: } Jun 12 19:55:50 volumio4 volumio[1040]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 19:55:50 volumio4 sudo[2178]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 12 19:55:50 volumio4 sudo[2178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 12 19:55:50 volumio4 sudo[2178]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:50 volumio4 sudo[2183]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 12 19:55:50 volumio4 sudo[2183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 12 19:55:50 volumio4 sudo[2183]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:50 volumio4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 12 19:55:50 volumio4 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 12 19:55:50 volumio4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 12 19:55:50 volumio4 systemd[1]: shairport-sync.service: Consumed 2.602s CPU time. Jun 12 19:55:50 volumio4 avahi-daemon[1034]: Got SIGTERM, quitting. Jun 12 19:55:50 volumio4 systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 12 19:55:50 volumio4 avahi-daemon[1034]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.10. Jun 12 19:55:50 volumio4 avahi-daemon[1034]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 12 19:55:50 volumio4 avahi-daemon[1034]: avahi-daemon 0.8 exiting. Jun 12 19:55:50 volumio4 systemd[1]: avahi-daemon.service: Deactivated successfully. Jun 12 19:55:50 volumio4 systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 12 19:55:50 volumio4 dbus-daemon[568]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.34' (uid=0 pid=1084 comm="/usr/sbin/smbd --foreground --no-process-group") Jun 12 19:55:51 volumio4 systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jun 12 19:55:51 volumio4 avahi-daemon[2195]: Process 1034 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Jun 12 19:55:51 volumio4 avahi-daemon[2195]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jun 12 19:55:51 volumio4 avahi-daemon[2195]: Successfully dropped root privileges. Jun 12 19:55:51 volumio4 avahi-daemon[2195]: avahi-daemon 0.8 starting up. Jun 12 19:55:51 volumio4 dbus-daemon[568]: [system] Successfully activated service 'org.freedesktop.Avahi' Jun 12 19:55:51 volumio4 systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jun 12 19:55:51 volumio4 avahi-daemon[2195]: Successfully called chroot(). Jun 12 19:55:51 volumio4 avahi-daemon[2195]: Successfully dropped remaining capabilities. Jun 12 19:55:51 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 12 19:55:51 volumio4 avahi-daemon[2195]: Loading service file /services/volumio.service. Jun 12 19:55:51 volumio4 avahi-daemon[2195]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.10. Jun 12 19:55:51 volumio4 avahi-daemon[2195]: New relevant interface wlan0.IPv4 for mDNS. Jun 12 19:55:51 volumio4 avahi-daemon[2195]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jun 12 19:55:51 volumio4 avahi-daemon[2195]: New relevant interface lo.IPv4 for mDNS. Jun 12 19:55:51 volumio4 avahi-daemon[2195]: Network interface enumeration completed. Jun 12 19:55:51 volumio4 avahi-daemon[2195]: Registering new address record for 192.168.0.10 on wlan0.IPv4. Jun 12 19:55:51 volumio4 avahi-daemon[2195]: Registering new address record for 127.0.0.1 on lo.IPv4. Jun 12 19:55:51 volumio4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 12 19:55:51 volumio4 sudo[1964]: pam_unix(sudo:session): session closed for user root Jun 12 19:55:51 volumio4 systemd[1]: Started wireless.service - Wireless Services. Jun 12 19:55:51 volumio4 ntpd[819]: IO: Listen normally on 4 wlan0 192.168.0.10:123 Jun 12 19:55:51 volumio4 ntpd[819]: IO: new interface(s) found: waking up resolver Jun 12 19:55:52 volumio4 avahi-daemon[2195]: Server startup complete. Host name is volumio4.local. Local service cookie is 4031834508. Jun 12 19:55:52 volumio4 avahi-daemon[2195]: Service "Volumio4" (/services/volumio.service) successfully established. Jun 12 19:55:54 volumio4 sudo[2219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-12 19:54' Jun 12 19:55:54 volumio4 sudo[2219]: 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"