Sep 15 12:53:37 volumio sudo[1994]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 12:53:37 volumio sudo[1994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:53:37 volumio sudo[1997]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 12:53:37 volumio sudo[1997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:53:37 volumio sudo[1994]: pam_unix(sudo:session): session closed for user root Sep 15 12:53:37 volumio sudo[1997]: pam_unix(sudo:session): session closed for user root Sep 15 12:53:37 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 15 12:53:37 volumio dbus-daemon[724]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.25' (uid=0 pid=2001 comm="timedatectl show --property=NTPSynchronized --valu") Sep 15 12:53:37 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 15 12:53:38 volumio volumio[1070]: verbose: New Socket.io Connection to 192.168.50.62 from 192.168.50.196 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 15 12:53:38 volumio dbus-daemon[724]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 15 12:53:38 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 15 12:53:38 volumio setdatetime-helper.sh[1998]: Time is not synchronized. Attempting to sync... Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::volumioGetVisibleSources Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Sep 15 12:53:38 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::volumioGetQueue Sep 15 12:53:38 volumio volumio[1070]: info: CoreStateMachine::getQueue Sep 15 12:53:38 volumio volumio[1070]: info: CorePlayQueue::getQueue Sep 15 12:53:38 volumio volumio[1070]: info: Listing playlists Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 15 12:53:38 volumio volumio[1070]: info: Received Get System Info Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 12:53:38 volumio volumio[1070]: info: Discovery: Getting this device information Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Sep 15 12:53:38 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Sep 15 12:53:38 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 15 12:53:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 15 12:53:38 volumio sudo[2009]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 15 Sep 2025 03:53:38 GMT#015' Sep 15 12:53:38 volumio sudo[2009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 15 12:53:38 volumio setdatetime-helper.sh[2010]: Mon Sep 15 12:53:38 KST 2025 Sep 15 12:53:38 volumio systemd-journald[347]: Time jumped backwards, rotating. Sep 15 12:53:38 volumio sudo[2009]: pam_unix(sudo:session): session closed for user root Sep 15 12:53:38 volumio setdatetime-helper.sh[1998]: Time synchronized successfully. Sep 15 12:53:38 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 15 12:53:38 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 15 12:53:40 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 15 12:53:40 volumio volumio[1070]: info: Received Get System Info Sep 15 12:53:40 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 12:53:40 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 12:53:40 volumio volumio[1070]: info: Discovery: Getting this device information Sep 15 12:53:40 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Sep 15 12:53:40 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Sep 15 12:53:40 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 12:53:40 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 15 12:53:41 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 15 12:53:41 volumio volumio[1070]: info: Received Get System Info Sep 15 12:53:41 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 12:53:41 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 12:53:41 volumio volumio[1070]: info: Discovery: Getting this device information Sep 15 12:53:41 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Sep 15 12:53:41 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Sep 15 12:53:41 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 12:53:48 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 15 12:53:57 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 15 12:53:57 volumio volumio[1070]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 15 12:53:57 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 15 12:53:57 volumio volumio[1070]: info: Received Get System Version Sep 15 12:53:57 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 12:53:57 volumio volumio[1070]: info: Received Get System Info Sep 15 12:53:57 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 12:53:57 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 12:53:57 volumio volumio[1070]: info: Discovery: Getting this device information Sep 15 12:53:57 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Sep 15 12:53:57 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Sep 15 12:53:57 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 12:54:07 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 15 12:54:26 volumio ntpd[922]: CLOCK: time stepped by 1.112044 Sep 15 12:54:26 volumio ntpd[922]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Sep 15 12:54:41 volumio volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 12:54:41 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 15 12:54:41 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Sep 15 12:54:41 volumio sudo[2117]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Sep 15 12:54:41 volumio sudo[2117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:54:41 volumio sudo[2123]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 15 12:54:41 volumio sudo[2123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:54:41 volumio sudo[2123]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:41 volumio sudo[2117]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:41 volumio sudo[2131]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 15 12:54:41 volumio sudo[2131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:54:41 volumio sudo[2135]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 15 12:54:41 volumio sudo[2135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:54:41 volumio sudo[2131]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:41 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 15 12:54:41 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 15 12:54:41 volumio sudo[2141]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 12:54:41 volumio sudo[2141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:54:41 volumio sudo[2135]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:41 volumio sudo[2141]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:41 volumio sudo[2145]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 12:54:41 volumio sudo[2145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:54:41 volumio sudo[2145]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:41 volumio sudo[2149]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 12:54:41 volumio sudo[2149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:54:45 volumio sudo[2149]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:48 volumio volumio[1070]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Sep 15 12:54:48 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Sep 15 12:54:48 volumio sudo[2153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Sep 15 12:54:48 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:54:48 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:48 volumio sudo[2155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Sep 15 12:54:48 volumio sudo[2155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:54:48 volumio sudo[2155]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:48 volumio volumio[1070]: info: Permissions for /etc/network/interfaces set Sep 15 12:54:48 volumio volumio[1070]: info: Restarting Wireless Network Sep 15 12:54:48 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Sep 15 12:54:48 volumio volumio[1070]: info: Discovery: Restarting Advertising due to device name change Sep 15 12:54:48 volumio sudo[2158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Sep 15 12:54:48 volumio sudo[2158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 15 12:54:48 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 15 12:54:48 volumio systemd[1]: Starting wireless.service - Wireless Services... Sep 15 12:54:48 volumio dbus-daemon[724]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.27' (uid=0 pid=2176 comm="timedatectl show --property=NTPSynchronized --valu") Sep 15 12:54:48 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 15 12:54:48 volumio dbus-daemon[724]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 15 12:54:48 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 15 12:54:48 volumio setdatetime-helper.sh[2160]: Time is not synchronized. Attempting to sync... Sep 15 12:54:49 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 15 12:54:49 volumio dnsmasq[1089]: exiting on receipt of SIGTERM Sep 15 12:54:49 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Sep 15 12:54:49 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 15 12:54:49 volumio sudo[2201]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 15 Sep 2025 03:54:49 GMT#015' Sep 15 12:54:49 volumio sudo[2201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 15 12:54:49 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 15 12:54:49 volumio setdatetime-helper.sh[2202]: Mon Sep 15 12:54:49 KST 2025 Sep 15 12:54:49 volumio systemd-journald[347]: Time jumped backwards, rotating. Sep 15 12:54:49 volumio sudo[2201]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:49 volumio setdatetime-helper.sh[2160]: Time synchronized successfully. Sep 15 12:54:49 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 15 12:54:49 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 15 12:54:49 volumio dhcpcd[779]: wlan0: carrier lost Sep 15 12:54:49 volumio systemd[1]: hostapd.service: Deactivated successfully. Sep 15 12:54:49 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 15 12:54:49 volumio sudo[2216]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Sep 15 12:54:49 volumio sudo[2216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 15 12:54:49 volumio avahi-daemon[722]: Withdrawing address record for 169.254.11.59 on wlan0. Sep 15 12:54:49 volumio volumio[1070]: info: Discovery: A device disappeared from network Sep 15 12:54:49 volumio volumio[1070]: info: Discovery: Device volumio disappeared from network Sep 15 12:54:49 volumio volumio[1070]: info: Discovery: A device disappeared from network Sep 15 12:54:49 volumio dhcpcd[779]: wlan0: deleting route to 169.254.0.0/16 Sep 15 12:54:49 volumio avahi-daemon[722]: Withdrawing address record for 192.168.211.1 on wlan0. Sep 15 12:54:49 volumio avahi-daemon[722]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Sep 15 12:54:49 volumio avahi-daemon[722]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 15 12:54:49 volumio volumio[1070]: info: Discovery: A device disappeared from network Sep 15 12:54:49 volumio sudo[2216]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:49 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Sep 15 12:54:49 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Sep 15 12:54:49 volumio systemd[1]: welcome.service: Deactivated successfully. Sep 15 12:54:49 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 15 12:54:49 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 15 12:54:49 volumio sudo[2231]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Sep 15 12:54:49 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 15 12:54:49 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 15 12:54:50 volumio welcome[2238]: Resolved ip:[1] 192.168.50.62 Sep 15 12:54:50 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:50 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 15 12:54:50 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Sep 15 12:54:50 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Sep 15 12:54:50 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Sep 15 12:54:50 volumio systemd[1]: welcome.service: Deactivated successfully. Sep 15 12:54:50 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 15 12:54:50 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 15 12:54:50 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 15 12:54:50 volumio welcome[2265]: Resolved ip:[1] 192.168.50.62 Sep 15 12:54:50 volumio systemd[1]: wireless.service: Deactivated successfully. Sep 15 12:54:50 volumio systemd[1]: Started wireless.service - Wireless Services. Sep 15 12:54:50 volumio sudo[2158]: pam_unix(sudo:session): session closed for user root Sep 15 12:54:50 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 15 12:54:50 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Sep 15 12:54:51 volumio ntpd[922]: IO: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=338 secs Sep 15 12:54:51 volumio ntpd[922]: IO: Deleting interface #5 wlan0, 169.254.11.59#123, interface stats: received=0, sent=0, dropped=0, active_time=328 secs Sep 15 12:54:53 volumio volumio[1070]: info: Discovery: Started advertising with name: Volumio Sep 15 12:54:53 volumio volumio[1070]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Sep 15 12:54:53 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Sep 15 12:54:54 volumio volumio[1070]: compat.c: read() failed: Resource temporarily unavailable Sep 15 12:54:54 volumio volumio[1070]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 12:54:54 volumio volumio[1070]: Error: dns service error: unknown Sep 15 12:54:54 volumio volumio[1070]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Sep 15 12:54:54 volumio volumio[1070]: errorCode: -65537 Sep 15 12:54:54 volumio volumio[1070]: } Sep 15 12:54:54 volumio volumio[1070]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 12:54:54 volumio sudo[2293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-15 12:53' Sep 15 12:54:54 volumio sudo[2293]: 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="dfc7bb04e09bf2260691765a2340814af1fa2971" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 30 06:39:32 UTC 2025" VOLUMIO_VERSION="4.022" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e7b0bd0cb5661ea77402e5bd121ec64a"