Sep 08 15:23:03 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 08 15:23:03 volumio dbus-daemon[514]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.20' (uid=0 pid=1496 comm="timedatectl show --property=NTPSynchronized --valu") Sep 08 15:23:03 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 08 15:23:03 volumio dbus-daemon[514]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 08 15:23:03 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 08 15:23:03 volumio setdatetime-helper.sh[1495]: Time is not synchronized. Attempting to sync... Sep 08 15:23:03 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 15:23:03 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 15:23:03 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 08 15:23:03 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 08 15:23:03 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 08 15:23:03 volumio volumio[1003]: info: CoreCommandRouter::volumioGetBrowseSources Sep 08 15:23:03 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 15:23:03 volumio sudo[1504]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 08 Sep 2025 06:23:03 GMT#015' Sep 08 15:23:03 volumio sudo[1504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 08 15:23:03 volumio setdatetime-helper.sh[1505]: Mon Sep 8 06:23:03 UTC 2025 Sep 08 15:23:03 volumio systemd-journald[299]: Time jumped backwards, rotating. Sep 08 15:23:03 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 08 15:23:03 volumio setdatetime-helper.sh[1495]: Time synchronized successfully. Sep 08 15:23:03 volumio sudo[1504]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:03 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 08 15:23:05 volumio volumio[1003]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 08 15:23:05 volumio volumio[1003]: info: Completed starting MyVolumio Plugin Sep 08 15:23:05 volumio volumio[1003]: [Metrics] CommandRouter: 59s 907.46ms Sep 08 15:23:05 volumio volumio[1003]: info: CoreCommandRouter::volumiosetStartupVolume Sep 08 15:23:05 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 15:23:05 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 15:23:05 volumio volumio[1003]: info: CoreCommandRouter::Close All Modals sent Sep 08 15:23:05 volumio volumio[1003]: info: CoreCommandRouter::Close All Modals sent Sep 08 15:23:06 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 08 15:23:06 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 08 15:23:07 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 08 15:23:12 volumio volumio[1003]: info: BOOT COMPLETED Sep 08 15:23:14 volumio sudo[1527]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 08 15:23:14 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:14 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:14 volumio sudo[1529]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 08 15:23:14 volumio sudo[1529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:14 volumio sudo[1529]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:15 volumio volumio[1003]: verbose: New Socket.io Connection to 192.168.1.120 from 192.168.1.100 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Sep 08 15:23:15 volumio volumio[1003]: info: Received Get System Info Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 15:23:15 volumio volumio[1003]: info: Discovery: Getting this device information Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Sep 08 15:23:15 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 15:23:15 volumio volumio-remote-updater[527]: No test mode Sep 08 15:23:15 volumio volumio-remote-updater[527]: No alpha test mode Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 15:23:15 volumio volumio[1003]: info: Retrieving Cloud Streaming UI Sep 08 15:23:15 volumio volumio[1003]: info: Getting Tidal Cloud Configuration Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 15:23:15 volumio volumio[1003]: info: Getting Qobuz Cloud Configuration Sep 08 15:23:15 volumio volumio[1003]: info: Asking plugin for UI Config Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 15:23:15 volumio volumio[1003]: info: Getting Spotify Cloud Configuration Sep 08 15:23:15 volumio volumio[1003]: info: Asking plugin for UI Config Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 15:23:15 volumio volumio[1003]: info: Saving Spotify Acccount Sep 08 15:23:15 volumio volumio[1003]: info: Got Tidal Cloud Configuration Sep 08 15:23:15 volumio volumio[1003]: info: Got it Sep 08 15:23:15 volumio volumio[1003]: info: Got it Sep 08 15:23:15 volumio volumio[1003]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 08 15:23:15 volumio volumio[1003]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 15:23:15 volumio volumio[1003]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Sep 08 15:23:16 volumio volumio[1003]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 08 15:23:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 08 15:23:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 08 15:23:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 08 15:23:16 volumio volumio[1003]: info: Loading i18n strings for locale ja Sep 08 15:23:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 08 15:23:16 volumio volumio[1003]: info: Setting timezone to Asia/Tokyo Sep 08 15:23:16 volumio sudo[1540]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 08 15:23:16 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:16 volumio sudo[1540]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:17 volumio sudo[1544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Sep 08 15:23:17 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:17 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:17 volumio sudo[1548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 08 15:23:17 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:17 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:17 volumio sudo[1552]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Sep 08 15:23:17 volumio sudo[1552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:17 volumio systemd-timedated[1498]: Changed time zone to 'Asia/Tokyo' (JST). Sep 08 15:23:17 volumio sudo[1552]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:17 volumio volumio[1003]: info: Fetching Streaming Services browse cache Sep 08 15:23:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 08 15:23:17 volumio volumio[1003]: Updating browse sources language Sep 08 15:23:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 15:23:19 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 08 15:23:19 volumio volumio[1003]: info: Loading i18n strings for locale ca Sep 08 15:23:19 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 08 15:23:19 volumio volumio[1003]: info: Setting timezone to Asia/Tokyo Sep 08 15:23:19 volumio sudo[1557]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 08 15:23:19 volumio sudo[1557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:19 volumio sudo[1557]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:20 volumio sudo[1561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Sep 08 15:23:20 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:20 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:20 volumio sudo[1565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 08 15:23:20 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:20 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:20 volumio sudo[1569]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Sep 08 15:23:20 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:20 volumio sudo[1569]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:20 volumio volumio[1003]: info: Fetching Streaming Services browse cache Sep 08 15:23:20 volumio volumio[1003]: Updating browse sources language Sep 08 15:23:20 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 15:23:26 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 08 15:23:26 volumio volumio[1003]: info: Loading i18n strings for locale ja Sep 08 15:23:26 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 08 15:23:26 volumio volumio[1003]: info: Setting timezone to Asia/Tokyo Sep 08 15:23:27 volumio sudo[1587]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 08 15:23:27 volumio sudo[1587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:27 volumio sudo[1587]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:27 volumio sudo[1591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Sep 08 15:23:27 volumio sudo[1591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:27 volumio sudo[1591]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:27 volumio sudo[1595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 08 15:23:27 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:27 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:27 volumio sudo[1599]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Sep 08 15:23:27 volumio sudo[1599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:27 volumio sudo[1599]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:27 volumio volumio[1003]: info: Fetching Streaming Services browse cache Sep 08 15:23:27 volumio volumio[1003]: Updating browse sources language Sep 08 15:23:27 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 15:23:30 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 08 15:23:30 volumio volumio[1003]: info: Loading i18n strings for locale ja Sep 08 15:23:30 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 08 15:23:30 volumio volumio[1003]: info: Setting timezone to Asia/Tokyo Sep 08 15:23:30 volumio sudo[1603]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 08 15:23:30 volumio sudo[1603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:30 volumio sudo[1603]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:30 volumio sudo[1607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Sep 08 15:23:30 volumio sudo[1607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:30 volumio sudo[1607]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:30 volumio sudo[1611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 08 15:23:30 volumio sudo[1611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:30 volumio sudo[1611]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:31 volumio sudo[1615]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Sep 08 15:23:31 volumio sudo[1615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:31 volumio sudo[1615]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:31 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 08 15:23:31 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 08 15:23:31 volumio volumio[1003]: info: Fetching Streaming Services browse cache Sep 08 15:23:31 volumio sudo[1618]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 08 15:23:31 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:23:31 volumio volumio[1003]: Updating browse sources language Sep 08 15:23:31 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 15:23:32 volumio sudo[1618]: pam_unix(sudo:session): session closed for user root Sep 08 15:23:32 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 08 15:23:33 volumio volumio[1003]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 08 15:23:33 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 08 15:23:33 volumio volumio[1003]: info: Received Get System Version Sep 08 15:23:33 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 08 15:23:33 volumio volumio[1003]: info: Received Get System Info Sep 08 15:23:33 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 15:23:33 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 15:23:33 volumio volumio[1003]: info: Discovery: Getting this device information Sep 08 15:23:33 volumio volumio[1003]: info: CoreCommandRouter::volumioGetState Sep 08 15:23:33 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Sep 08 15:23:33 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 15:23:47 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 08 15:23:47 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 15:23:47 volumio volumio-remote-updater[527]: No test mode Sep 08 15:23:47 volumio volumio-remote-updater[527]: No alpha test mode Sep 08 15:23:47 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 08 15:23:48 volumio volumio[1003]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 08 15:23:48 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 08 15:23:50 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 08 15:23:50 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 15:23:50 volumio volumio-remote-updater[527]: No test mode Sep 08 15:23:50 volumio volumio-remote-updater[527]: No alpha test mode Sep 08 15:23:50 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 08 15:23:51 volumio volumio[1003]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 08 15:23:51 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 08 15:23:57 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 08 15:23:57 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 15:23:57 volumio volumio-remote-updater[527]: No test mode Sep 08 15:23:57 volumio volumio-remote-updater[527]: No alpha test mode Sep 08 15:23:57 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 08 15:23:58 volumio volumio[1003]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 08 15:23:58 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 08 15:24:01 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 08 15:24:01 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 08 15:24:01 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 08 15:24:01 volumio volumio-remote-updater[527]: No test mode Sep 08 15:24:01 volumio volumio-remote-updater[527]: No alpha test mode Sep 08 15:24:01 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 08 15:24:01 volumio volumio[1003]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 08 15:24:01 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 08 15:24:10 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Sep 08 15:24:10 volumio volumio[1003]: info: System name has changed, restarting Shairport Sync Sep 08 15:24:10 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 15:24:10 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 15:24:10 volumio volumio[1003]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 15:24:10 volumio volumio[1003]: info: Updating MyVolumio device info Sep 08 15:24:10 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 15:24:10 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 15:24:10 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 15:24:10 volumio volumio[1003]: info: Discovery: Restarting Advertising due to device name change Sep 08 15:24:10 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Sep 08 15:24:10 volumio sudo[1688]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Sep 08 15:24:10 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:24:10 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 08 15:24:10 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 15:24:10 volumio sudo[1688]: pam_unix(sudo:session): session closed for user root Sep 08 15:24:10 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 08 15:24:10 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Sep 08 15:24:10 volumio systemd[1]: upmpdcli.service: Consumed 11.256s CPU time. Sep 08 15:24:10 volumio volumio[1003]: info: Starting Shairport Sync Sep 08 15:24:10 volumio sudo[1694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Sep 08 15:24:10 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:24:10 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 08 15:24:10 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root Sep 08 15:24:10 volumio systemd[1]: Starting fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels... Sep 08 15:24:10 volumio dbus-daemon[514]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.26' (uid=0 pid=1698 comm="timedatectl show --property=NTPSynchronized --valu") Sep 08 15:24:11 volumio sudo[1700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 15:24:11 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 08 15:24:11 volumio sudo[1700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:24:11 volumio systemd[1]: fix-alpha-hosts.service: Deactivated successfully. Sep 08 15:24:11 volumio systemd[1]: Finished fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels. Sep 08 15:24:11 volumio volumio[1003]: info: Permissions for /etc/hosts set Sep 08 15:24:11 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 08 15:24:11 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Sep 08 15:24:11 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 08 15:24:11 volumio systemd[1]: shairport-sync.service: Consumed 2.046s CPU time. Sep 08 15:24:11 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 08 15:24:11 volumio systemd[1]: Starting fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels... Sep 08 15:24:11 volumio sudo[1700]: pam_unix(sudo:session): session closed for user root Sep 08 15:24:11 volumio systemd[1]: fix-alpha-hosts.service: Deactivated successfully. Sep 08 15:24:11 volumio systemd[1]: Finished fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels. Sep 08 15:24:11 volumio volumio[1003]: info: Hostname now is volumio_1f Sep 08 15:24:11 volumio sudo[1709]: volumio : unable to resolve host volumio: System error Sep 08 15:24:11 volumio sudo[1709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio_1f Sep 08 15:24:11 volumio sudo[1709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:24:11 volumio volumio[1003]: info: Shairport-Sync Started Sep 08 15:24:11 volumio sudo[1709]: pam_unix(sudo:session): session closed for user root Sep 08 15:24:11 volumio volumio[1003]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 08 15:24:11 volumio volumio[1003]: error: Cannot set new hostname: Error: Command failed: /usr/bin/sudo /bin/hostname volumio_1f Sep 08 15:24:11 volumio volumio[1003]: sudo: unable to resolve host volumio: System error Sep 08 15:24:11 volumio volumio[1003]: hostname: the specified hostname is invalid Sep 08 15:24:11 volumio dbus-daemon[514]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 08 15:24:11 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 08 15:24:11 volumio setdatetime-helper.sh[1695]: Time is not synchronized. Attempting to sync... Sep 08 15:24:11 volumio sudo[1729]: volumio : unable to resolve host volumio: System error Sep 08 15:24:11 volumio sudo[1729]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Sep 08 15:24:11 volumio sudo[1729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 08 15:24:11 volumio sudo[1729]: pam_unix(sudo:session): session closed for user root Sep 08 15:24:11 volumio volumio[1003]: info: Permissions for /etc/avahi/services/volumio.service Sep 08 15:24:11 volumio volumio[1003]: info: Discovery: A device disappeared from network Sep 08 15:24:11 volumio volumio[1003]: info: Discovery: Device volumio disappeared from network Sep 08 15:24:11 volumio sudo[1737]: root : unable to resolve host volumio: System error Sep 08 15:24:11 volumio setdatetime-helper.sh[1737]: sudo: unable to resolve host volumio: System error Sep 08 15:24:11 volumio sudo[1737]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Mon, 08 Sep 2025 06:24:12 GMT#015' Sep 08 15:24:11 volumio sudo[1737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 08 15:24:12 volumio setdatetime-helper.sh[1738]: Mon Sep 8 15:24:12 JST 2025 Sep 08 15:24:12 volumio sudo[1737]: pam_unix(sudo:session): session closed for user root Sep 08 15:24:12 volumio setdatetime-helper.sh[1695]: Time synchronized successfully. Sep 08 15:24:12 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 08 15:24:12 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 08 15:24:12 volumio volumio[1003]: info: Discovery: A device disappeared from network Sep 08 15:24:12 volumio volumio[1003]: info: Discovery: A device disappeared from network Sep 08 15:24:12 volumio avahi-daemon[510]: Files changed, reloading. Sep 08 15:24:12 volumio avahi-daemon[510]: Loading service file /services/volumio.service. Sep 08 15:24:12 volumio volumio[1003]: info: Avahi name changed to volumio_1f Sep 08 15:24:13 volumio avahi-daemon[510]: Service "Volumio_1F" (/services/volumio.service) successfully established. Sep 08 15:24:15 volumio volumio[1003]: info: Discovery: Started advertising with name: Volumio_1F Sep 08 15:24:16 volumio volumio[1003]: compat.c: read() failed: Resource temporarily unavailable Sep 08 15:24:16 volumio volumio[1003]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 15:24:16 volumio volumio[1003]: Error: dns service error: unknown Sep 08 15:24:16 volumio volumio[1003]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Sep 08 15:24:16 volumio volumio[1003]: errorCode: -65537 Sep 08 15:24:16 volumio volumio[1003]: } Sep 08 15:24:16 volumio volumio[1003]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 15:24:20 volumio sudo[1771]: volumio : unable to resolve host volumio: System error Sep 08 15:24:20 volumio sudo[1771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-08 15:23' Sep 08 15:24:20 volumio sudo[1771]: 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"