Sep 10 19:21:11 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 10 19:21:11 volumio dbus-daemon[894]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.41' (uid=0 pid=3181 comm="timedatectl show --property=NTPSynchronized --valu") Sep 10 19:21:11 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 10 19:21:11 volumio dbus-daemon[894]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 10 19:21:11 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 10 19:21:11 volumio setdatetime-helper.sh[3180]: Time is not synchronized. Attempting to sync... Sep 10 19:21:11 volumio sudo[3189]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 10 Sep 2025 17:21:12 GMT#015' Sep 10 19:21:11 volumio sudo[3189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 10 19:21:12 volumio setdatetime-helper.sh[3190]: Wed Sep 10 17:21:12 UTC 2025 Sep 10 19:21:12 volumio sudo[3189]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:12 volumio setdatetime-helper.sh[3180]: Time synchronized successfully. Sep 10 19:21:12 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 10 19:21:12 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 10 19:21:13 volumio sudo[3205]: root : TTY=pts/1 ; PWD=/root ; USER=root ; COMMAND=/usr/bin/systemctl restart sshd Sep 10 19:21:13 volumio sudo[3205]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=0) Sep 10 19:21:13 volumio sudo[3205]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:17 volumio sudo[3209]: root : TTY=pts/1 ; PWD=/root ; USER=root ; COMMAND=/usr/bin/systemctl restart ssh Sep 10 19:21:17 volumio sudo[3209]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=0) Sep 10 19:21:17 volumio sshd[1815]: Received signal 15; terminating. Sep 10 19:21:17 volumio systemd[1]: Stopping ssh.service - OpenBSD Secure Shell server... Sep 10 19:21:17 volumio systemd[1]: ssh.service: Deactivated successfully. Sep 10 19:21:17 volumio systemd[1]: Stopped ssh.service - OpenBSD Secure Shell server. Sep 10 19:21:17 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Sep 10 19:21:17 volumio sshd[3214]: Server listening on 0.0.0.0 port 22. Sep 10 19:21:17 volumio sshd[3214]: Server listening on :: port 22. Sep 10 19:21:17 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Sep 10 19:21:17 volumio sudo[3209]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:23 volumio sudo[3233]: root : TTY=pts/1 ; PWD=/root ; USER=root ; COMMAND=/usr/bin/systemctl status Ssh Sep 10 19:21:23 volumio sudo[3233]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=0) Sep 10 19:21:23 volumio sudo[3233]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:26 volumio sudo[3240]: root : TTY=pts/1 ; PWD=/root ; USER=root ; COMMAND=/usr/bin/systemctl status ssh Sep 10 19:21:26 volumio sudo[3240]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=0) Sep 10 19:21:26 volumio sudo[3240]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:39 volumio sudo[3264]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 10 19:21:39 volumio sudo[3264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:39 volumio sudo[3266]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 10 19:21:39 volumio sudo[3266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:39 volumio sudo[3264]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:39 volumio sudo[3266]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:39 volumio volumio[1240]: verbose: New Socket.io Connection to 192.168.97.110 from 192.168.97.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::volumioGetVisibleSources Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Sep 10 19:21:39 volumio volumio[1240]: info: Received Get System Info Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 19:21:39 volumio volumio[1240]: info: Discovery: Getting this device information Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:21:39 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 19:21:39 volumio volumio-remote-updater[909]: Test mode disabled Sep 10 19:21:39 volumio volumio-remote-updater[909]: Alpha mode disabled Sep 10 19:21:39 volumio volumio-remote-updater[909]: Alpha legacy test mode disabled Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:21:39 volumio volumio[1240]: info: Retrieving Cloud Streaming UI Sep 10 19:21:39 volumio volumio[1240]: info: Getting Tidal Cloud Configuration Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:21:39 volumio volumio[1240]: info: Getting Qobuz Cloud Configuration Sep 10 19:21:39 volumio volumio[1240]: info: Asking plugin for UI Config Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:21:39 volumio volumio[1240]: info: Getting Spotify Cloud Configuration Sep 10 19:21:39 volumio volumio[1240]: info: Asking plugin for UI Config Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:21:39 volumio volumio[1240]: info: Saving Spotify Acccount Sep 10 19:21:39 volumio volumio[1240]: info: Got Tidal Cloud Configuration Sep 10 19:21:39 volumio volumio[1240]: info: Got it Sep 10 19:21:39 volumio volumio[1240]: info: Got it Sep 10 19:21:39 volumio volumio[1240]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:21:39 volumio volumio[1240]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Sep 10 19:21:39 volumio volumio[1240]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 10 19:21:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 10 19:21:40 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 10 19:21:40 volumio volumio[1240]: info: Loading i18n strings for locale es Sep 10 19:21:40 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 10 19:21:40 volumio volumio[1240]: info: Setting timezone to Europe/Madrid Sep 10 19:21:40 volumio sudo[3277]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 10 19:21:40 volumio sudo[3277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:40 volumio sudo[3277]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:40 volumio sudo[3281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Madrid /etc/localtime Sep 10 19:21:40 volumio sudo[3281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:40 volumio sudo[3281]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:40 volumio sudo[3288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 10 19:21:40 volumio sudo[3288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:40 volumio sudo[3288]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:40 volumio sudo[3295]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Madrid Sep 10 19:21:40 volumio sudo[3295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:40 volumio systemd-timedated[3183]: Changed time zone to 'Europe/Madrid' (CEST). Sep 10 19:21:40 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Sep 10 19:21:40 volumio sudo[3295]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:40 volumio volumio[1240]: Updating browse sources language Sep 10 19:21:40 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 19:21:41 volumio volumio[1240]: info: Fetching Streaming Services browse cache Sep 10 19:21:41 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Sep 10 19:21:41 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Sep 10 19:21:41 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 19:21:42 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 10 19:21:42 volumio volumio[1240]: info: MyVolumio login type: Token Sep 10 19:21:42 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 19:21:42 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 10 19:21:42 volumio volumio[1240]: info: Loading i18n strings for locale es Sep 10 19:21:42 volumio volumio[1240]: Updating browse sources language Sep 10 19:21:42 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 19:21:42 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 10 19:21:42 volumio volumio[1240]: info: Setting timezone to Europe/Madrid Sep 10 19:21:42 volumio sudo[3355]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 10 19:21:42 volumio sudo[3355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:42 volumio sudo[3355]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:42 volumio sudo[3359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Madrid /etc/localtime Sep 10 19:21:42 volumio sudo[3359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:42 volumio sudo[3359]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:42 volumio sudo[3363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 10 19:21:42 volumio sudo[3363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:42 volumio sudo[3363]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:42 volumio sudo[3367]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Madrid Sep 10 19:21:42 volumio sudo[3367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:42 volumio sudo[3367]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:42 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 10 19:21:42 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 10 19:21:42 volumio sudo[3370]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 10 19:21:42 volumio sudo[3370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:42 volumio volumio[1240]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 10 19:21:42 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 10 19:21:42 volumio volumio[1240]: info: MyVolumio login type: Token Sep 10 19:21:42 volumio volumio[1240]: info: Fetching Streaming Services browse cache Sep 10 19:21:43 volumio ntpd[1049]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 10 19:21:44 volumio ntpd[1049]: CLOCK: time stepped by 0.846116 Sep 10 19:21:44 volumio ntpd[1049]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Sep 10 19:21:44 volumio ntpd[1049]: CLOCK: frequency error 2783 PPM exceeds tolerance 500 PPM Sep 10 19:21:44 volumio volumio[1240]: info: MyVolumio token set successfully Sep 10 19:21:44 volumio volumio[1240]: info: MYVOLUMIO: Adding device Sep 10 19:21:44 volumio volumio[1240]: info: MYVOLUMIO: Evaluating Server Sep 10 19:21:44 volumio volumio[1240]: info: MyVolumio status changed Sep 10 19:21:44 volumio volumio[1240]: info: Streaming services startup Sep 10 19:21:44 volumio volumio[1240]: info: Starting Streaming Daemon Sep 10 19:21:44 volumio volumio[1240]: info: Removing browser output: myVolumio user plan is not superstar Sep 10 19:21:44 volumio volumio[1240]: info: Removing audio output: Sep 10 19:21:44 volumio volumio[1240]: info: Stoppping Tunnel 1 Sep 10 19:21:44 volumio sudo[3405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 10 19:21:44 volumio sudo[3405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:45 volumio sudo[3405]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:45 volumio volumio[1240]: error: Cannot start Volumio Streaming Daemon Sep 10 19:21:45 volumio volumio[1240]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 10 19:21:45 volumio volumio[1240]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 10 19:21:45 volumio sudo[3408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 10 19:21:45 volumio sudo[3408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 10 19:21:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 10 19:21:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 10 19:21:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 10 19:21:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 10 19:21:45 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 10 19:21:45 volumio sudo[3408]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:45 volumio volumio[1240]: info: Remote SSH Stopped Sep 10 19:21:45 volumio volumio[1240]: info: Setting Geolocation for MyVolumio to eu3 Sep 10 19:21:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 19:21:45 volumio volumio[1240]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 10 19:21:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 19:21:46 volumio volumio[1240]: info: Updating MyVolumio device info Sep 10 19:21:46 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:46 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:46 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:46 volumio sudo[3370]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:46 volumio volumio[1240]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 10 19:21:49 volumio volumio[1240]: info: MYVOLUMIO: Adding device Sep 10 19:21:49 volumio volumio[1240]: info: MYVOLUMIO: Evaluating Server Sep 10 19:21:50 volumio volumio[1240]: info: Setting Geolocation for MyVolumio to eu10 Sep 10 19:21:50 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:50 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:50 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:50 volumio volumio[1240]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 10 19:21:51 volumio volumio[1240]: info: Updating MyVolumio device info Sep 10 19:21:51 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:51 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:51 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:51 volumio volumio[1240]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 10 19:21:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Sep 10 19:21:54 volumio volumio[1240]: info: System name has changed, restarting Shairport Sync Sep 10 19:21:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:21:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:54 volumio volumio[1240]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 10 19:21:54 volumio volumio[1240]: info: Updating MyVolumio device info Sep 10 19:21:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:54 volumio volumio[1240]: info: Discovery: Restarting Advertising due to device name change Sep 10 19:21:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Sep 10 19:21:54 volumio sudo[3445]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Sep 10 19:21:54 volumio sudo[3445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:54 volumio sudo[3445]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 10 19:21:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:21:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 10 19:21:54 volumio volumio[1240]: info: Starting Shairport Sync Sep 10 19:21:54 volumio sudo[3453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Sep 10 19:21:54 volumio sudo[3453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:54 volumio sudo[3453]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:54 volumio volumio[1240]: info: Permissions for /etc/hosts set Sep 10 19:21:54 volumio sudo[3455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 10 19:21:54 volumio sudo[3455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:54 volumio volumio[1240]: info: Hostname now is volumio.casa Sep 10 19:21:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 10 19:21:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Sep 10 19:21:55 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 10 19:21:55 volumio systemd[1]: shairport-sync.service: Consumed 1.700s CPU time. Sep 10 19:21:55 volumio volumio[1240]: info: Shairport-Sync Started Sep 10 19:21:55 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 10 19:21:55 volumio sudo[3455]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:55 volumio sudo[3459]: volumio : unable to resolve host volumio: System error Sep 10 19:21:55 volumio sudo[3459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio.casa Sep 10 19:21:55 volumio sudo[3459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:55 volumio.casa sudo[3461]: volumio : unable to resolve host volumio: System error Sep 10 19:21:55 volumio.casa sudo[3459]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:55 volumio.casa sudo[3461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Sep 10 19:21:55 volumio.casa sudo[3461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:21:55 volumio.casa volumio[1240]: info: New hostname set Sep 10 19:21:55 volumio.casa sudo[3461]: pam_unix(sudo:session): session closed for user root Sep 10 19:21:55 volumio.casa volumio[1240]: info: Permissions for /etc/avahi/services/volumio.service Sep 10 19:21:55 volumio.casa avahi-daemon[891]: Files changed, reloading. Sep 10 19:21:55 volumio.casa avahi-daemon[891]: Loading service file /services/volumio.service. Sep 10 19:21:55 volumio.casa volumio[1240]: info: Avahi name changed to volumio.casa Sep 10 19:21:55 volumio.casa volumio[1240]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 10 19:21:55 volumio.casa systemd[1]: upmpdcli.service: Deactivated successfully. Sep 10 19:21:55 volumio.casa avahi-daemon[891]: Service "Volumio.casa" (/services/volumio.service) successfully established. Sep 10 19:21:55 volumio.casa volumio[1240]: info: Discovery: A device disappeared from network Sep 10 19:21:55 volumio.casa volumio[1240]: info: Discovery: Device volumio disappeared from network Sep 10 19:21:55 volumio.casa volumio[1240]: info: Discovery: A device disappeared from network Sep 10 19:21:55 volumio.casa volumio[1240]: info: Discovery: A device disappeared from network Sep 10 19:21:58 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 10 19:21:58 volumio.casa volumio[1240]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 10 19:21:58 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 10 19:21:58 volumio.casa volumio[1240]: info: Received Get System Version Sep 10 19:21:58 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 10 19:21:58 volumio.casa volumio[1240]: info: Received Get System Info Sep 10 19:21:58 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 19:21:58 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 19:21:58 volumio.casa volumio[1240]: info: Discovery: Getting this device information Sep 10 19:21:58 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:21:58 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:21:58 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 19:21:59 volumio.casa volumio[1240]: info: Discovery: Started advertising with name: Volumio.casa Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 10 19:22:00 volumio.casa volumio[1240]: info: Preparing to save Alsa Options, stopping services first Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:00 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioPause Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreStateMachine::pause Sep 10 19:22:00 volumio.casa volumio[1240]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"R3PRO"},"disallowPush":true} Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 10 19:22:00 volumio.casa volumio[1240]: info: Device 5 does not have any Mixer Control Available Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 10 19:22:00 volumio.casa volumio[1240]: info: Updating Volume Controller Parameters: Device: 5 Name: R3PRO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 10 19:22:00 volumio.casa volumio[1240]: info: Disabling external Volume Control Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreStateMachine::pushState Sep 10 19:22:00 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioPushState Sep 10 19:22:00 volumio.casa volumio[1240]: info: Preparing to generate the ALSA configuration file Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 10 19:22:00 volumio.casa sudo[3502]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 10 19:22:00 volumio.casa sudo[3502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:00 volumio.casa sudo[3504]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 10 19:22:00 volumio.casa sudo[3504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:00 volumio.casa sudo[3504]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 10 19:22:00 volumio.casa volumio[1240]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 10 19:22:00 volumio.casa volumio[1240]: wlan0 Interface doesn't support scanning : Device or resource busy Sep 10 19:22:00 volumio.casa volumio[1240]: info: Cannot use regular scanning, forcing with ap-force Sep 10 19:22:00 volumio.casa sudo[3515]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 10 19:22:00 volumio.casa sudo[3515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:00 volumio.casa sudo[3515]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:00 volumio.casa volumio[1240]: command failed: Device or resource busy (-16) Sep 10 19:22:00 volumio.casa volumio[1240]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 10 19:22:00 volumio.casa volumio[1240]: command failed: Device or resource busy (-16) Sep 10 19:22:00 volumio.casa volumio[1240]: info: Asound.conf file written Sep 10 19:22:00 volumio.casa sudo[3519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 10 19:22:00 volumio.casa sudo[3519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:00 volumio.casa sudo[3519]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:00 volumio.casa volumio[1240]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Sep 10 19:22:00 volumio.casa volumio[1240]: No state is present for card vc4hdmi0 Sep 10 19:22:00 volumio.casa volumio[1240]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Sep 10 19:22:00 volumio.casa volumio[1240]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 10 19:22:00 volumio.casa volumio[1240]: Hardware is initialized using a generic method Sep 10 19:22:00 volumio.casa volumio[1240]: No state is present for card vc4hdmi0 Sep 10 19:22:00 volumio.casa volumio[1240]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Sep 10 19:22:00 volumio.casa volumio[1240]: No state is present for card vc4hdmi1 Sep 10 19:22:00 volumio.casa volumio[1240]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Sep 10 19:22:00 volumio.casa volumio[1240]: Found hardware: "vc4-hdmi" "" "" "" "" Sep 10 19:22:00 volumio.casa volumio[1240]: Hardware is initialized using a generic method Sep 10 19:22:00 volumio.casa volumio[1240]: No state is present for card vc4hdmi1 Sep 10 19:22:00 volumio.casa volumio[1240]: No state is present for card R3PRO Sep 10 19:22:00 volumio.casa volumio[1240]: Found hardware: "USB-Audio" "USB Mixer" "USB32bb:0004" "" "" Sep 10 19:22:00 volumio.casa volumio[1240]: Hardware is initialized using a generic method Sep 10 19:22:00 volumio.casa volumio[1240]: No state is present for card R3PRO Sep 10 19:22:00 volumio.casa volumio[1240]: info: Output device has changed, restarting MPD Sep 10 19:22:00 volumio.casa volumio[1240]: info: Output device has changed, restarting Shairport Sync Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:22:00 volumio.casa sudo[3526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 10 19:22:00 volumio.casa sudo[3526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:00 volumio.casa sudo[3527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 10 19:22:00 volumio.casa sudo[3527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:00 volumio.casa sudo[3526]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:00 volumio.casa volumio[1240]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 10 19:22:00 volumio.casa volumio[1240]: info: Output device has changed, restarting MPD Sep 10 19:22:00 volumio.casa volumio[1240]: info: Output device has changed, restarting Shairport Sync Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:22:00 volumio.casa sudo[3535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 10 19:22:00 volumio.casa sudo[3535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:00 volumio.casa sudo[3535]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:00 volumio.casa systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 10 19:22:00 volumio.casa sudo[3537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 10 19:22:00 volumio.casa sudo[3537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:00 volumio.casa volumio[1240]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 10 19:22:00 volumio.casa volumio[1240]: info: MPD Permissions set Sep 10 19:22:00 volumio.casa volumio[1240]: info: MPD Permissions set Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa systemd[1]: mpd.service: Deactivated successfully. Sep 10 19:22:00 volumio.casa systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 10 19:22:00 volumio.casa systemd[1]: mpd.socket: Deactivated successfully. Sep 10 19:22:00 volumio.casa systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 10 19:22:00 volumio.casa systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:00 volumio.casa volumio[1240]: info: Starting Shairport Sync Sep 10 19:22:00 volumio.casa volumio[1240]: info: Starting Shairport Sync Sep 10 19:22:00 volumio.casa sudo[3556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 10 19:22:00 volumio.casa sudo[3556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:00 volumio.casa systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 10 19:22:00 volumio.casa systemd[1]: Starting mpd.service - Music Player Daemon... Sep 10 19:22:00 volumio.casa sudo[3558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 10 19:22:00 volumio.casa sudo[3558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:00 volumio.casa systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 10 19:22:00 volumio.casa systemd[1]: shairport-sync.service: Deactivated successfully. Sep 10 19:22:00 volumio.casa systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 10 19:22:00 volumio.casa systemd[1]: shairport-sync.service: Consumed 1.517s CPU time. Sep 10 19:22:00 volumio.casa systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 10 19:22:00 volumio.casa sudo[3556]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:00 volumio.casa sudo[3558]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:00 volumio.casa volumio[1240]: info: Shairport-Sync Started Sep 10 19:22:00 volumio.casa volumio[1240]: info: Shairport-Sync Started Sep 10 19:22:00 volumio.casa sudo[3562]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 10 19:22:00 volumio.casa sudo[3562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 10 19:22:00 volumio.casa sudo[3562]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:00 volumio.casa volumio[1240]: info: Discovery: adding fd2856d8-41c8-4eae-a864-8397c4a12855 Sep 10 19:22:00 volumio.casa volumio[1240]: info: Discovery: Found device Volumio.casa Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:00 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:00 volumio.casa volumio[1240]: info: Discovery: this is already registered, fd2856d8-41c8-4eae-a864-8397c4a12855 Sep 10 19:22:00 volumio.casa volumio[1240]: info: Discovery: Found device Volumio.casa Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:00 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:00 volumio.casa volumio[1240]: info: Discovery: this is already registered, fd2856d8-41c8-4eae-a864-8397c4a12855 Sep 10 19:22:00 volumio.casa volumio[1240]: info: Discovery: Found device Volumio.casa Sep 10 19:22:00 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:00 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:00 volumio.casa mpd[3579]: 2025-09-10T19:22:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 10 19:22:00 volumio.casa systemd[1]: Started mpd.service - Music Player Daemon. Sep 10 19:22:00 volumio.casa sudo[3537]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:00 volumio.casa sudo[3527]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:00 volumio.casa volumio[1240]: error: MPD error: The expression evaluated to a falsy value: Sep 10 19:22:00 volumio.casa volumio[1240]: assert.ok(self.idling) Sep 10 19:22:00 volumio.casa volumio[1240]: error: The expression evaluated to a falsy value: Sep 10 19:22:00 volumio.casa volumio[1240]: assert.ok(self.idling) Sep 10 19:22:00 volumio.casa volumio[1240]: error: updateQueue error: null Sep 10 19:22:01 volumio.casa volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:22:01 volumio.casa volumio[1240]: info: Retrieving Cloud Streaming UI Sep 10 19:22:01 volumio.casa volumio[1240]: info: Getting Tidal Cloud Configuration Sep 10 19:22:01 volumio.casa volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:22:01 volumio.casa volumio[1240]: info: Getting Qobuz Cloud Configuration Sep 10 19:22:01 volumio.casa volumio[1240]: info: Asking plugin for UI Config Sep 10 19:22:01 volumio.casa volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:22:01 volumio.casa volumio[1240]: info: Getting Spotify Cloud Configuration Sep 10 19:22:01 volumio.casa volumio[1240]: info: Asking plugin for UI Config Sep 10 19:22:01 volumio.casa volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:22:01 volumio.casa volumio[1240]: info: Saving Spotify Acccount Sep 10 19:22:01 volumio.casa volumio[1240]: info: Got Tidal Cloud Configuration Sep 10 19:22:01 volumio.casa volumio[1240]: info: Got it Sep 10 19:22:01 volumio.casa volumio[1240]: info: Got it Sep 10 19:22:01 volumio.casa volumio[1240]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 10 19:22:01 volumio.casa volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:22:01 volumio.casa volumio[1240]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Sep 10 19:22:02 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Sep 10 19:22:02 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Sep 10 19:22:02 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Sep 10 19:22:02 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Sep 10 19:22:02 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Sep 10 19:22:03 volumio.casa sudo[3502]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:03 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Sep 10 19:22:03 volumio.casa volumio[1240]: info: CoreCommandRouter::Reload Ui Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Sep 10 19:22:04 volumio.casa volumio[1240]: info: Wizard terminated Successfully Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::volumiosetStartupVolume Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 19:22:04 volumio.casa sudo[3601]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 10 19:22:04 volumio.casa sudo[3601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:04 volumio.casa sudo[3603]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 10 19:22:04 volumio.casa sudo[3601]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:04 volumio.casa sudo[3603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:04 volumio.casa sudo[3603]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreStateMachine::getcurrentVolume Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioRetrievevolume Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreStateMachine::pushState Sep 10 19:22:04 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioPushState Sep 10 19:22:04 volumio.casa volumio[1240]: verbose: New Socket.io Connection to 192.168.97.110 from 192.168.97.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 10 19:22:04 volumio.casa sudo[3622]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 10 19:22:04 volumio.casa sudo[3622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:04 volumio.casa sudo[3624]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 10 19:22:04 volumio.casa sudo[3624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:04 volumio.casa sudo[3622]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:04 volumio.casa sudo[3624]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:04 volumio.casa volumio[1240]: verbose: New Socket.io Connection to 192.168.97.110 from 192.168.97.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetVisibleSources Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:04 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 10 19:22:04 volumio.casa volumio[1240]: info: Received Get System Info Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 19:22:04 volumio.casa volumio[1240]: info: Discovery: Getting this device information Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:04 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:04 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:04 volumio.casa volumio[1240]: info: Listing playlists Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 10 19:22:04 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 10 19:22:04 volumio.casa sudo[3628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Sep 10 19:22:04 volumio.casa sudo[3628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:04 volumio.casa sudo[3630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Sep 10 19:22:04 volumio.casa sudo[3630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:04 volumio.casa sudo[3630]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:04 volumio.casa sudo[3628]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:04 volumio.casa volumio[1240]: info: Internal perms successfully set Sep 10 19:22:04 volumio.casa volumio[1240]: info: smb.conf Permissions set Sep 10 19:22:04 volumio.casa sudo[3634]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 10 19:22:04 volumio.casa sudo[3634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:04 volumio.casa sudo[3634]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:04 volumio.casa sudo[3636]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 10 19:22:04 volumio.casa sudo[3636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:04 volumio.casa sudo[3636]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:04 volumio.casa sudo[3640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Sep 10 19:22:04 volumio.casa sudo[3640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:04 volumio.casa sudo[3642]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 10 19:22:04 volumio.casa sudo[3642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:05 volumio.casa nmbd[1111]: [2025/09/10 19:22:05.003284, 0] ../../source3/nmbd/nmbd.c:59(terminate) Sep 10 19:22:05 volumio.casa nmbd[1111]: Got SIGTERM: going down... Sep 10 19:22:05 volumio.casa systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Sep 10 19:22:05 volumio.casa systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Sep 10 19:22:05 volumio.casa systemd[1]: nmbd.service: Deactivated successfully. Sep 10 19:22:05 volumio.casa systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Sep 10 19:22:05 volumio.casa sudo[3642]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:05 volumio.casa volumio[1240]: info: Upmpdcli Daemon Started Sep 10 19:22:05 volumio.casa systemd[1]: Starting nmbd.service - Samba NMB Daemon... Sep 10 19:22:05 volumio.casa upmpdcli[3663]: writing RSA key Sep 10 19:22:05 volumio.casa nmbd[3668]: [2025/09/10 19:22:05.147045, 0] ../../source3/nmbd/nmbd.c:901(main) Sep 10 19:22:05 volumio.casa nmbd[3668]: nmbd version 4.17.8-Raspbian started. Sep 10 19:22:05 volumio.casa nmbd[3668]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Sep 10 19:22:05 volumio.casa nmbd[3668]: [2025/09/10 19:22:05.148282, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 10 19:22:05 volumio.casa nmbd[3668]: started asyncdns process 3669 Sep 10 19:22:05 volumio.casa systemd[1]: Started nmbd.service - Samba NMB Daemon. Sep 10 19:22:05 volumio.casa sudo[3640]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:05 volumio.casa sudo[3671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Sep 10 19:22:05 volumio.casa sudo[3671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:05 volumio.casa systemd[1]: Stopping smbd.service - Samba SMB Daemon... Sep 10 19:22:05 volumio.casa systemd[1]: smbd.service: Deactivated successfully. Sep 10 19:22:05 volumio.casa systemd[1]: Stopped smbd.service - Samba SMB Daemon. Sep 10 19:22:05 volumio.casa systemd[1]: Starting smbd.service - Samba SMB Daemon... Sep 10 19:22:05 volumio.casa smbd[3677]: [2025/09/10 19:22:05.263642, 0] ../../source3/smbd/server.c:1741(main) Sep 10 19:22:05 volumio.casa smbd[3677]: smbd version 4.17.8-Raspbian started. Sep 10 19:22:05 volumio.casa smbd[3677]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Sep 10 19:22:05 volumio.casa systemd[1]: Started smbd.service - Samba SMB Daemon. Sep 10 19:22:05 volumio.casa sudo[3671]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:05 volumio.casa volumio[1240]: info: SAMBA Restarted Sep 10 19:22:05 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:05 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:06 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 10 19:22:06 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 19:22:06 volumio.casa volumio[1240]: info: Received Get System Info Sep 10 19:22:06 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 19:22:06 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 19:22:06 volumio.casa volumio[1240]: info: Discovery: Getting this device information Sep 10 19:22:06 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:06 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:06 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 19:22:06 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 19:22:06 volumio.casa volumio[1240]: info: Received Get System Info Sep 10 19:22:06 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 19:22:06 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 19:22:06 volumio.casa volumio[1240]: info: Discovery: Getting this device information Sep 10 19:22:06 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:06 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:06 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 19:22:08 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Sep 10 19:22:10 volumio.casa volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:22:10 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 10 19:22:11 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 10 19:22:11 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 10 19:22:11 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 10 19:22:11 volumio.casa volumio[1240]: info: Received Get System Version Sep 10 19:22:11 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 10 19:22:11 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 10 19:22:11 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 10 19:22:11 volumio.casa volumio-remote-updater[909]: Test mode disabled Sep 10 19:22:11 volumio.casa volumio-remote-updater[909]: Alpha mode disabled Sep 10 19:22:11 volumio.casa volumio-remote-updater[909]: Alpha legacy test mode disabled Sep 10 19:22:11 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 10 19:22:11 volumio.casa volumio[1240]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 10 19:22:11 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 10 19:22:13 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 10 19:22:13 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 10 19:22:13 volumio.casa systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 10 19:22:13 volumio.casa systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 10 19:22:13 volumio.casa dbus-daemon[894]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.54' (uid=0 pid=3708 comm="timedatectl show --property=NTPSynchronized --valu") Sep 10 19:22:13 volumio.casa systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 10 19:22:13 volumio.casa volumio-remote-updater[909]: Test mode disabled Sep 10 19:22:13 volumio.casa volumio-remote-updater[909]: Alpha mode disabled Sep 10 19:22:13 volumio.casa volumio-remote-updater[909]: Alpha legacy test mode disabled Sep 10 19:22:13 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 10 19:22:13 volumio.casa dbus-daemon[894]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 10 19:22:13 volumio.casa systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 10 19:22:13 volumio.casa setdatetime-helper.sh[3705]: Time is not synchronized. Attempting to sync... Sep 10 19:22:13 volumio.casa sudo[3717]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 10 Sep 2025 17:22:13 GMT#015' Sep 10 19:22:13 volumio.casa sudo[3717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 10 19:22:13 volumio.casa setdatetime-helper.sh[3718]: Wed Sep 10 19:22:13 CEST 2025 Sep 10 19:22:13 volumio.casa systemd-journald[446]: Time jumped backwards, rotating. Sep 10 19:22:13 volumio.casa setdatetime-helper.sh[3705]: Time synchronized successfully. Sep 10 19:22:13 volumio.casa systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 10 19:22:13 volumio.casa sudo[3717]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:13 volumio.casa systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 10 19:22:13 volumio.casa volumio[1240]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 10 19:22:13 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 10 19:22:13 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 10 19:22:15 volumio.casa volumio[1240]: info: CoreCommandRouter::Close All Modals sent Sep 10 19:22:15 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Sep 10 19:22:17 volumio.casa volumio[1240]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Sep 10 19:22:17 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Sep 10 19:22:17 volumio.casa volumio[1240]: info: Setting active UI to: {"uiPrettyName":"Manifest","uiName":"manifest","uiPath":"/volumio/http/www4"} Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::Reload Ui Sep 10 19:22:18 volumio.casa sudo[3738]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 10 19:22:18 volumio.casa sudo[3738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:18 volumio.casa sudo[3740]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 10 19:22:18 volumio.casa sudo[3740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:18 volumio.casa sudo[3738]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:18 volumio.casa sudo[3740]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:18 volumio.casa volumio[1240]: verbose: New Socket.io Connection to 192.168.97.110 from 192.168.97.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetVisibleSources Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:18 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 10 19:22:18 volumio.casa volumio[1240]: info: Received Get System Info Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 19:22:18 volumio.casa volumio[1240]: info: Discovery: Getting this device information Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:18 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:18 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:18 volumio.casa volumio[1240]: info: Listing playlists Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 10 19:22:18 volumio.casa volumio[1240]: info: Received Get System Version Sep 10 19:22:18 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 10 19:22:19 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 10 19:22:19 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 10 19:22:20 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 19:22:20 volumio.casa volumio[1240]: info: Received Get System Info Sep 10 19:22:20 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 19:22:20 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 19:22:20 volumio.casa volumio[1240]: info: Discovery: Getting this device information Sep 10 19:22:20 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:20 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:20 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 19:22:21 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 19:22:21 volumio.casa volumio[1240]: info: Received Get System Info Sep 10 19:22:21 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 19:22:21 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 19:22:21 volumio.casa volumio[1240]: info: Discovery: Getting this device information Sep 10 19:22:21 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:21 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:21 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 19:22:21 volumio.casa volumio[1240]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Sep 10 19:22:21 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Sep 10 19:22:21 volumio.casa volumio[1240]: info: Setting active UI to: {"uiPrettyName":"Contemporary","uiName":"contemporary","uiPath":"/volumio/http/www3"} Sep 10 19:22:21 volumio.casa sudo[3766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/active_volumio_ui Sep 10 19:22:21 volumio.casa sudo[3766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:21 volumio.casa sudo[3766]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:22 volumio.casa volumio[1240]: info: CoreCommandRouter::Reload Ui Sep 10 19:22:22 volumio.casa sudo[3769]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 10 19:22:22 volumio.casa sudo[3769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:22 volumio.casa sudo[3769]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:22 volumio.casa sudo[3772]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 10 19:22:23 volumio.casa sudo[3772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:23 volumio.casa sudo[3772]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:23 volumio.casa volumio[1240]: verbose: New Socket.io Connection to 192.168.97.110 from 192.168.97.108 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:23 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetVisibleSources Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 10 19:22:23 volumio.casa volumio[1240]: info: Received Get System Version Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 10 19:22:23 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Sep 10 19:22:25 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 19:22:25 volumio.casa volumio[1240]: info: Received Get System Info Sep 10 19:22:25 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 19:22:25 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 19:22:25 volumio.casa volumio[1240]: info: Discovery: Getting this device information Sep 10 19:22:25 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:25 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:25 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 19:22:25 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 10 19:22:25 volumio.casa volumio[1240]: info: Received Get System Info Sep 10 19:22:25 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 19:22:25 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 19:22:25 volumio.casa volumio[1240]: info: Discovery: Getting this device information Sep 10 19:22:25 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:25 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:25 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 19:22:27 volumio.casa volumio[1240]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 10 19:22:27 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 10 19:22:27 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Sep 10 19:22:27 volumio.casa sudo[3813]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Sep 10 19:22:27 volumio.casa sudo[3824]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 10 19:22:27 volumio.casa sudo[3824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:27 volumio.casa sudo[3813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:27 volumio.casa sudo[3818]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 10 19:22:27 volumio.casa sudo[3818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:27 volumio.casa sudo[3824]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:27 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 10 19:22:27 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 10 19:22:27 volumio.casa sudo[3813]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:27 volumio.casa sudo[3835]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 10 19:22:27 volumio.casa sudo[3818]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:27 volumio.casa sudo[3835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:27 volumio.casa sudo[3828]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 10 19:22:27 volumio.casa sudo[3828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:27 volumio.casa sudo[3835]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:27 volumio.casa sudo[3838]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 10 19:22:27 volumio.casa sudo[3838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:27 volumio.casa sudo[3842]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 10 19:22:27 volumio.casa sudo[3842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:27 volumio.casa sudo[3828]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:27 volumio.casa sudo[3838]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:31 volumio.casa sudo[3842]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:41 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 10 19:22:41 volumio.casa volumio[1240]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 10 19:22:41 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 10 19:22:41 volumio.casa volumio[1240]: info: Received Get System Version Sep 10 19:22:41 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 10 19:22:41 volumio.casa volumio[1240]: info: Received Get System Info Sep 10 19:22:41 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 19:22:41 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 19:22:41 volumio.casa volumio[1240]: info: Discovery: Getting this device information Sep 10 19:22:41 volumio.casa volumio[1240]: info: CoreCommandRouter::volumioGetState Sep 10 19:22:41 volumio.casa volumio[1240]: info: CorePlayQueue::getTrack 0 Sep 10 19:22:41 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 19:22:41 volumio.casa volumio[1240]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Sep 10 19:22:41 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Sep 10 19:22:42 volumio.casa systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 10 19:22:44 volumio.casa volumio[1240]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Sep 10 19:22:44 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Sep 10 19:22:44 volumio.casa sudo[3882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Sep 10 19:22:44 volumio.casa sudo[3882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:44 volumio.casa sudo[3882]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:44 volumio.casa sudo[3884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Sep 10 19:22:44 volumio.casa sudo[3884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:44 volumio.casa sudo[3884]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:44 volumio.casa volumio[1240]: info: Permissions for /etc/network/interfaces set Sep 10 19:22:44 volumio.casa volumio[1240]: info: Restarting Wired Network Sep 10 19:22:44 volumio.casa volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Sep 10 19:22:44 volumio.casa volumio[1240]: info: Discovery: Restarting Advertising due to device name change Sep 10 19:22:44 volumio.casa sudo[3887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Sep 10 19:22:44 volumio.casa sudo[3887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:44 volumio.casa avahi-daemon[891]: Withdrawing address record for 192.168.97.110 on eth0. Sep 10 19:22:44 volumio.casa avahi-daemon[891]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.97.110. Sep 10 19:22:44 volumio.casa avahi-daemon[891]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 10 19:22:44 volumio.casa sudo[3887]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:44 volumio.casa dhcpcd[978]: eth0: pid 3888 deleted IP address 192.168.97.110/24 Sep 10 19:22:44 volumio.casa dhcpcd[978]: wlan0: adding default route Sep 10 19:22:44 volumio.casa volumio[1240]: info: Discovery: A device disappeared from network Sep 10 19:22:44 volumio.casa dhcpcd[978]: eth0: deleting route to 192.168.97.0/24 Sep 10 19:22:44 volumio.casa dhcpcd[978]: eth0: deleting default route via 192.168.97.10 Sep 10 19:22:44 volumio.casa dhcpcd[835]: eth0: pid 3888 deleted IP address 192.168.97.110/24 Sep 10 19:22:44 volumio.casa dhcpcd[835]: eth0: deleting route to 192.168.97.0/24 Sep 10 19:22:44 volumio.casa dhcpcd[835]: eth0: deleting default route via 192.168.97.10 Sep 10 19:22:44 volumio.casa sudo[3889]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Sep 10 19:22:44 volumio.casa sudo[3889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:44 volumio.casa dnsmasq[1894]: reading /etc/resolv.conf Sep 10 19:22:44 volumio.casa dnsmasq[1894]: using nameserver 208.67.222.222#53 Sep 10 19:22:44 volumio.casa dnsmasq[1894]: using nameserver 208.67.220.220#53 Sep 10 19:22:44 volumio.casa dnsmasq[1894]: reading /etc/resolv.conf Sep 10 19:22:44 volumio.casa dnsmasq[1894]: using nameserver 208.67.222.222#53 Sep 10 19:22:44 volumio.casa dnsmasq[1894]: using nameserver 208.67.220.220#53 Sep 10 19:22:44 volumio.casa kernel: macb 1f00100000.ethernet eth0: Link is Down Sep 10 19:22:44 volumio.casa dhcpcd[978]: eth0: soliciting a DHCP lease Sep 10 19:22:44 volumio.casa dhcpcd[835]: eth0: soliciting a DHCP lease Sep 10 19:22:44 volumio.casa systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Sep 10 19:22:44 volumio.casa systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Sep 10 19:22:44 volumio.casa systemd[1]: welcome.service: Deactivated successfully. Sep 10 19:22:44 volumio.casa systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 10 19:22:44 volumio.casa systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 10 19:22:44 volumio.casa dhcpcd[835]: eth0: carrier lost Sep 10 19:22:44 volumio.casa dhcpcd[978]: eth0: carrier lost Sep 10 19:22:44 volumio.casa kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock unregistered. Sep 10 19:22:44 volumio.casa sudo[3889]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:44 volumio.casa sudo[3917]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Sep 10 19:22:44 volumio.casa sudo[3917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 10 19:22:44 volumio.casa dnsmasq[1894]: reading /etc/resolv.conf Sep 10 19:22:44 volumio.casa dnsmasq[1894]: using nameserver 208.67.222.222#53 Sep 10 19:22:44 volumio.casa dnsmasq[1894]: using nameserver 208.67.220.220#53 Sep 10 19:22:44 volumio.casa dnsmasq[1894]: using nameserver 208.67.222.222#53 Sep 10 19:22:44 volumio.casa dnsmasq[1894]: using nameserver 208.67.220.220#53 Sep 10 19:22:44 volumio.casa dnsmasq[1894]: reading /etc/resolv.conf Sep 10 19:22:44 volumio.casa dnsmasq[1894]: using nameserver 208.67.222.222#53 Sep 10 19:22:44 volumio.casa dnsmasq[1894]: using nameserver 208.67.220.220#53 Sep 10 19:22:44 volumio.casa sudo[3917]: pam_unix(sudo:session): session closed for user root Sep 10 19:22:44 volumio.casa kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Sep 10 19:22:44 volumio.casa kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Sep 10 19:22:44 volumio.casa kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Sep 10 19:22:44 volumio.casa systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 10 19:22:44 volumio.casa welcome[3914]: Resolved ip:[2] 192.168.211.1 169.254.130.49 Sep 10 19:22:44 volumio.casa systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 10 19:22:44 volumio.casa systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Sep 10 19:22:45 volumio.casa ifplugd(eth0)[1126]: Link beat lost. Sep 10 19:22:45 volumio.casa volumio[1240]: info: Discovery: A device disappeared from network Sep 10 19:22:45 volumio.casa volumio[1240]: info: Discovery: A device disappeared from network Sep 10 19:22:46 volumio.casa ntpd[1049]: IO: Deleting interface #4 eth0, 192.168.97.110#123, interface stats: received=180, sent=181, dropped=1, active_time=372 secs Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 162.159.200.1 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 185.198.109.227 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 212.227.232.46 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 178.215.228.24 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 5.250.184.159 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 90.68.206.60 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 5.250.191.170 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 213.165.71.32 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 195.95.153.59 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 84.77.195.114 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 162.159.200.123 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 82.223.203.159 unlink local addr 192.168.97.110 -> Sep 10 19:22:46 volumio.casa ntpd[1049]: PROTO: 89.140.186.3 unlink local addr 192.168.97.110 -> Sep 10 19:22:49 volumio.casa dhcpcd[835]: eth0: carrier acquired Sep 10 19:22:49 volumio.casa dhcpcd[978]: eth0: carrier acquired Sep 10 19:22:49 volumio.casa dhcpcd[835]: eth0: config file changed, expiring leases Sep 10 19:22:49 volumio.casa dhcpcd[978]: eth0: config file changed, expiring leases Sep 10 19:22:49 volumio.casa kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Sep 10 19:22:49 volumio.casa dhcpcd[835]: eth0: IAID 67:57:a4:41 Sep 10 19:22:49 volumio.casa dhcpcd[835]: eth0: probing address 192.168.97.17/24 Sep 10 19:22:49 volumio.casa dhcpcd[978]: eth0: IAID 67:57:a4:41 Sep 10 19:22:49 volumio.casa dhcpcd[978]: eth0: probing address 192.168.97.17/24 Sep 10 19:22:49 volumio.casa ifplugd(eth0)[1126]: Link beat detected. Sep 10 19:22:49 volumio.casa dhcpcd[978]: eth0: soliciting an IPv6 router Sep 10 19:22:49 volumio.casa dhcpcd[835]: eth0: soliciting an IPv6 router Sep 10 19:22:49 volumio.casa volumio[1240]: info: Discovery: Started advertising with name: Volumio.casa Sep 10 19:22:53 volumio.casa dhcpcd[835]: eth0: using static address 192.168.97.17/24 Sep 10 19:22:53 volumio.casa dhcpcd[835]: eth0: adding route to 192.168.97.0/24 Sep 10 19:22:53 volumio.casa dhcpcd[835]: eth0: adding default route via 192.168.97.10 Sep 10 19:22:53 volumio.casa avahi-daemon[891]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.97.17. Sep 10 19:22:53 volumio.casa avahi-daemon[891]: New relevant interface eth0.IPv4 for mDNS. Sep 10 19:22:53 volumio.casa avahi-daemon[891]: Registering new address record for 192.168.97.17 on eth0.IPv4. Sep 10 19:22:53 volumio.casa systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Sep 10 19:22:53 volumio.casa systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Sep 10 19:22:53 volumio.casa systemd[1]: welcome.service: Deactivated successfully. Sep 10 19:22:53 volumio.casa systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 10 19:22:53 volumio.casa systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 10 19:22:53 volumio.casa dnsmasq[1894]: reading /etc/resolv.conf Sep 10 19:22:53 volumio.casa dnsmasq[1894]: using nameserver 192.168.97.10#53 Sep 10 19:22:53 volumio.casa dnsmasq[1894]: using nameserver 208.67.222.222#53 Sep 10 19:22:53 volumio.casa dnsmasq[1894]: using nameserver 208.67.222.222#53 Sep 10 19:22:53 volumio.casa dnsmasq[1894]: using nameserver 208.67.220.220#53 Sep 10 19:22:53 volumio.casa systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 10 19:22:53 volumio.casa welcome[3980]: Resolved ip:[3] 192.168.97.17 192.168.211.1 169.254.130.49 Sep 10 19:22:53 volumio.casa systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 10 19:22:53 volumio.casa systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Sep 10 19:22:53 volumio.casa volumio[1240]: compat.c: read() failed: Resource temporarily unavailable Sep 10 19:22:53 volumio.casa volumio[1240]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 19:22:53 volumio.casa volumio[1240]: Error: dns service error: unknown Sep 10 19:22:53 volumio.casa volumio[1240]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Sep 10 19:22:53 volumio.casa volumio[1240]: errorCode: -65537 Sep 10 19:22:53 volumio.casa volumio[1240]: } Sep 10 19:22:53 volumio.casa volumio[1240]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 19:22:53 volumio.casa sudo[4003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-10 19:21' Sep 10 19:22:53 volumio.casa sudo[4003]: 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"