Feb 22 15:34:21 livingroomvolumio volumio5-onboarding[1509]: time=2026-02-22T15:34:21.283-05:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=172.20.24.220:47858 Feb 22 15:34:21 livingroomvolumio volumio5-onboarding[1509]: time=2026-02-22T15:34:21.283-05:00 level=INFO msg="connection to address closed" component=conn/multi addr=172.20.24.220:47858 Feb 22 15:34:21 livingroomvolumio volumio5-onboarding[1509]: time=2026-02-22T15:34:21.290-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=172.20.24.220:47174 Feb 22 15:34:31 livingroomvolumio sudo[4411]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:34:31 livingroomvolumio sudo[4411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:31 livingroomvolumio sudo[4411]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:31 livingroomvolumio sudo[4413]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:34:31 livingroomvolumio sudo[4413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:31 livingroomvolumio sudo[4413]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:31 livingroomvolumio volumio[1222]: verbose: New Socket.io Connection to 172.20.24.166 from 172.20.24.220 UA: Mozilla/5.0 (Linux; Android 16; Pixel 10 Build/BP4A.260205.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Received Get System Info Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Discovery: Getting this device information Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:34:32 livingroomvolumio volumio-remote-updater[804]: Test mode disabled Feb 22 15:34:32 livingroomvolumio volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:34:32 livingroomvolumio volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Asking plugin for UI Config Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Asking plugin for UI Config Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Saving Spotify Acccount Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Got it Feb 22 15:34:32 livingroomvolumio volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Got it Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:34:32 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:34:33 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:34:33 livingroomvolumio volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:34:33 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:34:33 livingroomvolumio volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:34:33 livingroomvolumio sudo[4425]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:34:33 livingroomvolumio sudo[4425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:33 livingroomvolumio sudo[4425]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:33 livingroomvolumio sudo[4429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:34:33 livingroomvolumio sudo[4429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:33 livingroomvolumio sudo[4429]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:33 livingroomvolumio sudo[4433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:34:33 livingroomvolumio sudo[4433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:33 livingroomvolumio sudo[4433]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:33 livingroomvolumio sudo[4437]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:34:33 livingroomvolumio sudo[4437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:33 livingroomvolumio dbus-daemon[777]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.61' (uid=0 pid=4438 comm="/usr/bin/timedatectl set-timezone America/New_York") Feb 22 15:34:33 livingroomvolumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Feb 22 15:34:33 livingroomvolumio dbus-daemon[777]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 22 15:34:33 livingroomvolumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Feb 22 15:34:33 livingroomvolumio sudo[4437]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:33 livingroomvolumio volumio[1222]: Updating browse sources language Feb 22 15:34:33 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:34:33 livingroomvolumio volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:34:33 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:34:34 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:34:37 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:34:37 livingroomvolumio volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:34:37 livingroomvolumio volumio[1222]: Updating browse sources language Feb 22 15:34:37 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:34:37 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:34:37 livingroomvolumio volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:34:38 livingroomvolumio sudo[4458]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:34:38 livingroomvolumio sudo[4458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:38 livingroomvolumio sudo[4458]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:38 livingroomvolumio sudo[4462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:34:38 livingroomvolumio sudo[4462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:38 livingroomvolumio sudo[4462]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:38 livingroomvolumio sudo[4466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:34:38 livingroomvolumio sudo[4466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:38 livingroomvolumio sudo[4466]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:38 livingroomvolumio sudo[4470]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:34:38 livingroomvolumio sudo[4470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:38 livingroomvolumio sudo[4470]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:38 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 22 15:34:38 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 22 15:34:38 livingroomvolumio sudo[4473]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 15:34:38 livingroomvolumio sudo[4473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:38 livingroomvolumio volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:34:41 livingroomvolumio sudo[4473]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:41 livingroomvolumio volumio5-onboarding[1509]: time=2026-02-22T15:34:41.447-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 22 15:34:41 livingroomvolumio volumio[1222]: info: Received Get System Info Feb 22 15:34:41 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:34:41 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:34:41 livingroomvolumio volumio[1222]: info: Discovery: Getting this device information Feb 22 15:34:41 livingroomvolumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:41 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:34:41 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 15:34:41 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 15:34:47 livingroomvolumio ntpd[1030]: PROTO: 172.104.209.204 unlink local addr 172.20.24.166 -> Feb 22 15:34:50 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:34:50 livingroomvolumio volumio[1222]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 22 15:34:50 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 22 15:34:50 livingroomvolumio volumio[1222]: info: Received Get System Version Feb 22 15:34:50 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 15:34:50 livingroomvolumio volumio[1222]: info: Received Get System Info Feb 22 15:34:50 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:34:50 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:34:50 livingroomvolumio volumio[1222]: info: Discovery: Getting this device information Feb 22 15:34:50 livingroomvolumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:50 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: System name has changed, restarting Shairport Sync Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: Updating MyVolumio device info Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: QobuzConnect: setDeactiveState invoked Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:53 livingroomvolumio vtcs[3997]: [2026-02-22 15:34:53.425] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 22 15:34:53 livingroomvolumio vtcs[3997]: [2026-02-22 15:34:53.426] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: Discovery: Restarting Advertising Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: Discovery: Stopping existing advertisement Feb 22 15:34:53 livingroomvolumio sudo[4497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:34:53 livingroomvolumio sudo[4497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:53 livingroomvolumio sudo[4499]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 22 15:34:53 livingroomvolumio sudo[4499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:53 livingroomvolumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 22 15:34:53 livingroomvolumio systemd[1]: vtcs.service: Killing process 4001 (vtcs) with signal SIGKILL. Feb 22 15:34:53 livingroomvolumio sudo[4503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:34:53 livingroomvolumio sudo[4503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:53 livingroomvolumio sudo[4499]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:53 livingroomvolumio systemd[1]: vtcs.service: Deactivated successfully. Feb 22 15:34:53 livingroomvolumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 22 15:34:53 livingroomvolumio sudo[4497]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 22 15:34:53 livingroomvolumio sudo[4510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 15:34:53 livingroomvolumio sudo[4510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 15:34:53 livingroomvolumio sudo[4503]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:34:53 livingroomvolumio sudo[4510]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: Starting Shairport Sync Feb 22 15:34:53 livingroomvolumio sudo[4518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 15:34:53 livingroomvolumio sudo[4518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:53 livingroomvolumio sudo[4519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Feb 22 15:34:53 livingroomvolumio sudo[4519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:53 livingroomvolumio sudo[4519]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:53 livingroomvolumio sudo[4522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 15:34:53 livingroomvolumio sudo[4522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:53 livingroomvolumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 22 15:34:53 livingroomvolumio qobuz-connect[3947]: 20260222 15:34:53.598 [3947.3947] INFO SampleApp: Stopping Local configuration server Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: Permissions for /etc/hosts set Feb 22 15:34:53 livingroomvolumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 15:34:53 livingroomvolumio volumio[1222]: info: Hostname now is livingrm Feb 22 15:34:53 livingroomvolumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 15:34:53 livingroomvolumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:34:53 livingroomvolumio systemd[1]: shairport-sync.service: Consumed 1.998s CPU time. Feb 22 15:34:53 livingroomvolumio sudo[4526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname livingrm Feb 22 15:34:53 livingroomvolumio sudo[4526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:53 livingrm sudo[4526]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:53 livingrm sudo[4529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Feb 22 15:34:53 livingrm volumio[1222]: info: New hostname set Feb 22 15:34:53 livingrm sudo[4529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:53 livingrm sudo[4529]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:53 livingrm volumio[1222]: info: Permissions for /etc/avahi/services/volumio.service Feb 22 15:34:53 livingrm avahi-daemon[773]: Files changed, reloading. Feb 22 15:34:53 livingrm avahi-daemon[773]: Service group file /services/volumio.service changed, reloading. Feb 22 15:34:53 livingrm volumio[1222]: info: Avahi name changed to livingrm Feb 22 15:34:53 livingrm systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:34:53 livingrm sudo[4522]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:53 livingrm volumio[1222]: info: Shairport-Sync Started Feb 22 15:34:53 livingrm volumio[1222]: (node:1222) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 22 15:34:53 livingrm volumio[1222]: (Use `node --trace-warnings ...` to show where the warning was created) Feb 22 15:34:53 livingrm volumio[1222]: (node:1222) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 22 15:34:53 livingrm volumio[1222]: (node:1222) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 22 15:34:53 livingrm volumio[1222]: (node:1222) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 22 15:34:53 livingrm volumio[1222]: (node:1222) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 22 15:34:53 livingrm volumio[1222]: info: Successfully Updated MyVolumio device Feb 22 15:34:54 livingrm volumio[1222]: info: Discovery: A device disappeared from network Feb 22 15:34:54 livingrm volumio[1222]: info: Discovery: Device livingroomvolumio disappeared from network Feb 22 15:34:54 livingrm volumio[1222]: info: Discovery: A device disappeared from network Feb 22 15:34:54 livingrm avahi-daemon[773]: Service "LivingRm" (/services/volumio.service) successfully established. Feb 22 15:34:54 livingrm qobuz-connect[3947]: 20260222 15:34:54.961 [3947.3947] INFO SampleApp: shat down connection on UNIX socket Feb 22 15:34:54 livingrm volumio[1222]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 22 15:34:54 livingrm volumio[1222]: info: QobuzConnect: setDeactiveState invoked Feb 22 15:34:54 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:54 livingrm systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 22 15:34:54 livingrm systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 15:34:55 livingrm systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 15:34:55 livingrm sudo[4518]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:55 livingrm volumio[1222]: info: Executing endpoint qc_getconfig Feb 22 15:34:55 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 22 15:34:55 livingrm qobuz-connect[4561]: 20260222 15:34:55.083 [4561.4561] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 22 15:34:55 livingrm volumio[1222]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 22 15:34:55 livingrm volumio[1222]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 22 15:34:55 livingrm qobuz-connect[4561]: 20260222 15:34:55.087 [4561.4561] INFO VolumeManager: [0x1373588]: Setting new playback volume: 75 Feb 22 15:34:55 livingrm qobuz-connect[4561]: 20260222 15:34:55.087 [4561.4561] INFO VolumeManager: [0x1373588]: Setting new mute state: 0 Feb 22 15:34:55 livingrm qobuz-connect[4561]: 20260222 15:34:55.087 [4561.4561] INFO QobuzConnect: [0x1373f58]: Client initialized! Feb 22 15:34:55 livingrm qobuz-connect[4561]: 20260222 15:34:55.087 [4561.4561] INFO SampleApp: Starting Avahi advertising, name: LivingRm, service name: _qobuz-connect._tcp Feb 22 15:34:55 livingrm qobuz-connect[4561]: 20260222 15:34:55.101 [4561.4561] INFO LocalConfigManager: [0x1372e68]: Starting Local Configuration server Feb 22 15:34:55 livingrm qobuz-connect[4561]: 20260222 15:34:55.101 [4561.4561] INFO SampleApp: Starting Local configuration server Feb 22 15:34:55 livingrm qobuz-connect[4561]: 20260222 15:34:55.101 [4561.4561] INFO SampleApp: Connected to UNIX socket client 0x135d818 Feb 22 15:34:55 livingrm qobuz-connect[4561]: 20260222 15:34:55.233 [4561.4561] INFO SampleApp: Playback volume changed: 75 Feb 22 15:34:55 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:56 livingrm volumio[1222]: info: TidalConnect service stoped! Feb 22 15:34:56 livingrm volumio[1222]: info: TidalConnect service stoped! Feb 22 15:34:56 livingrm volumio[1222]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 22 15:34:56 livingrm volumio[1222]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 22 15:34:56 livingrm sudo[4576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 22 15:34:56 livingrm sudo[4576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:56 livingrm systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 22 15:34:56 livingrm sudo[4576]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:56 livingrm volumio[1222]: info: Executing endpoint tc_getconfig Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 22 15:34:56 livingrm vtcs[4579]: STARTING TidalConnect services, version: 1.6.1 Feb 22 15:34:56 livingrm vtcs[4579]: STARTED TidalConnect services. Feb 22 15:34:56 livingrm volumio[1222]: info: Executing endpoint tc_connect Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 22 15:34:56 livingrm volumio[1222]: info: Connecting to TidalConnect Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::servicePushState Feb 22 15:34:56 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:56 livingrm volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 22 15:34:56 livingrm volumio[1222]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 22 15:34:56 livingrm volumio[1222]: verbose: CURRENT POSITION 0 Feb 22 15:34:56 livingrm volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 22 15:34:56 livingrm volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 22 15:34:56 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:56 livingrm volumio[1222]: info: No code Feb 22 15:34:56 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::servicePushState Feb 22 15:34:56 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:56 livingrm volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 22 15:34:56 livingrm volumio[1222]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 22 15:34:56 livingrm volumio[1222]: verbose: CURRENT POSITION 0 Feb 22 15:34:56 livingrm volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 22 15:34:56 livingrm volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 22 15:34:56 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:56 livingrm volumio[1222]: info: No code Feb 22 15:34:56 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:34:56 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:34:56 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.837-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.837-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" id= title= Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.839-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.839-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" id= title= Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.840-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.841-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" id= title= Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.842-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.843-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" id= title= Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.844-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.844-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" id= title= Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.845-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.846-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:47174 @ 0x20c3590" id= title= Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.846-05:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=172.20.24.220:47174 Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.846-05:00 level=INFO msg="connection to address closed" component=conn/multi addr=172.20.24.220:47174 Feb 22 15:34:56 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:34:56.859-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=172.20.24.220:58586 Feb 22 15:34:56 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:34:56 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:34:56 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:34:56 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:34:56 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:34:56 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:34:57 livingrm sudo[4596]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:34:57 livingrm sudo[4596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:57 livingrm sudo[4596]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:57 livingrm sudo[4598]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:34:57 livingrm sudo[4598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:57 livingrm sudo[4598]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:57 livingrm volumio[1222]: verbose: New Socket.io Connection to 172.20.24.166 from 172.20.24.220 UA: Mozilla/5.0 (Linux; Android 16; Pixel 10 Build/BP4A.260205.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 22 15:34:57 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:34:57 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:34:57 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:34:57 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:34:57 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:34:57 livingrm volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:34:57 livingrm volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:34:57 livingrm volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:34:57 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:34:57 livingrm volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:34:57 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:34:57 livingrm volumio[1222]: info: Saving Spotify Acccount Feb 22 15:34:57 livingrm volumio[1222]: info: Got it Feb 22 15:34:57 livingrm volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:34:57 livingrm volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:34:57 livingrm volumio[1222]: info: Got it Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:34:57 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:34:57 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:34:58 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:34:58 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:34:58 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:34:58 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:34:58 livingrm sudo[4609]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:34:58 livingrm sudo[4609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:58 livingrm sudo[4609]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:58 livingrm sudo[4613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:34:58 livingrm sudo[4613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:58 livingrm sudo[4613]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:58 livingrm sudo[4617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:34:58 livingrm sudo[4617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:58 livingrm sudo[4617]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:58 livingrm sudo[4621]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:34:58 livingrm sudo[4621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:58 livingrm sudo[4621]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:58 livingrm volumio[1222]: info: Discovery: Started advertising with name: LivingRm Feb 22 15:34:58 livingrm volumio[1222]: Updating browse sources language Feb 22 15:34:58 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:34:58 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:34:59 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:34:59 livingrm volumio[1222]: Updating browse sources language Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:34:59 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:34:59 livingrm sudo[4627]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:34:59 livingrm sudo[4627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:59 livingrm sudo[4627]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:59 livingrm sudo[4631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:34:59 livingrm sudo[4631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:59 livingrm sudo[4631]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:59 livingrm sudo[4635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:34:59 livingrm sudo[4635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:59 livingrm sudo[4635]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:59 livingrm sudo[4639]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:34:59 livingrm sudo[4639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:59 livingrm sudo[4639]: pam_unix(sudo:session): session closed for user root Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 22 15:34:59 livingrm sudo[4642]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 15:34:59 livingrm sudo[4642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:34:59 livingrm volumio[1222]: info: Discovery: adding 04e6dab3-e80e-40b8-ab6d-72f87afb8d55 Feb 22 15:34:59 livingrm volumio[1222]: info: Discovery: Found device LivingRm Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:59 livingrm volumio[1222]: info: Discovery: this is already registered, 04e6dab3-e80e-40b8-ab6d-72f87afb8d55 Feb 22 15:34:59 livingrm volumio[1222]: info: Discovery: Found device LivingRm Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:34:59 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:34:59 livingrm volumio[1222]: info: TidalConnect service started! Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:34:59 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:34:59 livingrm volumio[1222]: verbose: New Socket.io Connection to 172.20.24.166:3000 from 172.20.24.220 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 15:34:59 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 15:35:02 livingrm sudo[4642]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:02 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 22 15:35:02 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 22 15:35:02 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 15:35:02 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:02 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:02 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:02.644-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="172.20.24.220:58586 @ 0x24b6420" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 22 15:35:02 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:02 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:02 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:02 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:02 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:02 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:02 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 15:35:02 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 15:35:03 livingrm sudo[4650]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:35:03 livingrm sudo[4650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:03 livingrm sudo[4650]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:03 livingrm sudo[4652]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:35:03 livingrm sudo[4652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:03 livingrm sudo[4652]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:03 livingrm volumio[1222]: verbose: New Socket.io Connection to 172.20.24.166 from 172.20.24.220 UA: Mozilla/5.0 (Linux; Android 16; Pixel 10 Build/BP4A.260205.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 22 15:35:03 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:03 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:03 livingrm volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:35:03 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:03 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:03 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:03 livingrm volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:03 livingrm volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:35:03 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:03 livingrm volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:35:03 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:03 livingrm volumio[1222]: info: Saving Spotify Acccount Feb 22 15:35:03 livingrm volumio[1222]: info: Got it Feb 22 15:35:03 livingrm volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:35:03 livingrm volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:35:03 livingrm volumio[1222]: info: Got it Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:03 livingrm sudo[4661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Feb 22 15:35:03 livingrm sudo[4661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:03 livingrm sudo[4661]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:03 livingrm volumio[1222]: info: smb.conf Permissions set Feb 22 15:35:03 livingrm sudo[4663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Feb 22 15:35:03 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:03 livingrm sudo[4663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:03 livingrm sudo[4663]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:03 livingrm volumio[1222]: info: Internal perms successfully set Feb 22 15:35:03 livingrm sudo[4667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Feb 22 15:35:03 livingrm sudo[4667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:03 livingrm nmbd[1096]: [2026/02/22 15:35:03.519989, 0] ../../source3/nmbd/nmbd.c:59(terminate) Feb 22 15:35:03 livingrm nmbd[1096]: Got SIGTERM: going down... Feb 22 15:35:03 livingrm systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Feb 22 15:35:03 livingrm systemd[1]: nmbd.service: Deactivated successfully. Feb 22 15:35:03 livingrm systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Feb 22 15:35:03 livingrm sudo[4671]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:35:03 livingrm sudo[4671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:03 livingrm sudo[4673]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 15:35:03 livingrm sudo[4673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:03 livingrm sudo[4673]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:03 livingrm sudo[4671]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:03 livingrm sudo[4677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 22 15:35:03 livingrm sudo[4677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:03 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:03 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:03 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 22 15:35:03 livingrm systemd[1]: Starting nmbd.service - Samba NMB Daemon... Feb 22 15:35:03 livingrm sudo[4677]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:03 livingrm volumio[1222]: info: Upmpdcli Daemon Started Feb 22 15:35:03 livingrm nmbd[4683]: [2026/02/22 15:35:03.859998, 0] ../../source3/nmbd/nmbd.c:901(main) Feb 22 15:35:03 livingrm nmbd[4683]: nmbd version 4.17.8-Raspbian started. Feb 22 15:35:03 livingrm nmbd[4683]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 22 15:35:03 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:03 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:03 livingrm nmbd[4683]: [2026/02/22 15:35:03.864281, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Feb 22 15:35:03 livingrm nmbd[4683]: started asyncdns process 4684 Feb 22 15:35:03 livingrm systemd[1]: Started nmbd.service - Samba NMB Daemon. Feb 22 15:35:03 livingrm sudo[4667]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:03 livingrm sudo[4686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Feb 22 15:35:03 livingrm sudo[4686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:03 livingrm systemd[1]: Stopping smbd.service - Samba SMB Daemon... Feb 22 15:35:03 livingrm systemd[1]: smbd.service: Deactivated successfully. Feb 22 15:35:03 livingrm systemd[1]: Stopped smbd.service - Samba SMB Daemon. Feb 22 15:35:03 livingrm systemd[1]: Starting smbd.service - Samba SMB Daemon... Feb 22 15:35:04 livingrm smbd[4691]: [2026/02/22 15:35:04.197719, 0] ../../source3/smbd/server.c:1741(main) Feb 22 15:35:04 livingrm smbd[4691]: smbd version 4.17.8-Raspbian started. Feb 22 15:35:04 livingrm smbd[4691]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 22 15:35:04 livingrm systemd[1]: Started smbd.service - Samba SMB Daemon. Feb 22 15:35:04 livingrm sudo[4686]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:04 livingrm volumio[1222]: info: SAMBA Restarted Feb 22 15:35:04 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:04 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:04 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:04 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:04 livingrm sudo[4697]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:04 livingrm sudo[4697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:04 livingrm sudo[4697]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:04 livingrm sudo[4701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:04 livingrm sudo[4701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:04 livingrm sudo[4701]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:04 livingrm sudo[4705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:04 livingrm sudo[4705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:04 livingrm sudo[4705]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:04 livingrm sudo[4717]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:04 livingrm sudo[4717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:04 livingrm sudo[4717]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:04 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:04 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:04 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:04 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:05 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:06 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:06 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:06 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:06 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:06 livingrm sudo[4727]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:06 livingrm sudo[4727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:06 livingrm sudo[4727]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:06 livingrm sudo[4731]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:06 livingrm sudo[4731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:06 livingrm sudo[4731]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:06 livingrm sudo[4736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:06 livingrm sudo[4736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:06 livingrm sudo[4736]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:06 livingrm sudo[4740]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:06 livingrm sudo[4740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:06 livingrm sudo[4740]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:06 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 22 15:35:06 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 22 15:35:06 livingrm systemd[1]: Starting apt-daily.service - Daily apt download activities... Feb 22 15:35:06 livingrm sudo[4743]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 15:35:06 livingrm sudo[4743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:06 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:06 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:06 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:06 livingrm systemd[1]: apt-daily.service: Deactivated successfully. Feb 22 15:35:06 livingrm systemd[1]: Finished apt-daily.service - Daily apt download activities. Feb 22 15:35:08 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 22 15:35:08 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 15:35:08 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:08 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:08 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:08 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 22 15:35:08 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:08 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:09 livingrm sudo[4743]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:09 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:09 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:09 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:09 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:09 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:09 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:09 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:09.536-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="172.20.24.220:58586 @ 0x24b6420" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 22 15:35:09 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 15:35:09 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 15:35:14 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 22 15:35:14 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 22 15:35:14 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 15:35:14 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:14 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:21 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:35:21 livingrm volumio[1222]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 22 15:35:21 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 22 15:35:21 livingrm volumio[1222]: info: Received Get System Version Feb 22 15:35:21 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 15:35:21 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:21 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:21 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:21 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:21 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:21 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:28 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 22 15:35:28 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 15:35:28 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:28 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:28 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:28 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 22 15:35:28 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:28 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 15:35:29 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:29 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:29 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 22 15:35:29 livingrm volumio[1222]: info: Preparing to save Alsa Options, stopping services first Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:29 livingrm volumio[1222]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"hifiberry-amp2","label":"HiFiBerry Amp2"},"output_device":{"value":1,"label":"HiFiBerry Amp2"},"disallowPush":true} Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: Enabling I2S DAC: HiFiBerry Amp2 Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:29 livingrm sudo[4826]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Feb 22 15:35:29 livingrm sudo[4826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:29 livingrm sudo[4826]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:29 livingrm sudo[4830]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r hifiberry-amp Feb 22 15:35:29 livingrm sudo[4830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:29 livingrm sudo[4830]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:29 livingrm volumio[1222]: info: Removed overlay hifiberry-amp Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:29 livingrm sudo[4841]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus Feb 22 15:35:29 livingrm sudo[4841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:29 livingrm volumio[1222]: warn: Unable to locate the device HiFiBerry Amp2 a reboot may be required. Feb 22 15:35:29 livingrm kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Feb 22 15:35:29 livingrm kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Feb 22 15:35:29 livingrm kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Feb 22 15:35:29 livingrm kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Feb 22 15:35:29 livingrm kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Feb 22 15:35:29 livingrm sudo[4841]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry Amp2 Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 22 15:35:29 livingrm volumio[1222]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry Amp2 Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 22 15:35:29 livingrm volumio[1222]: info: Disabling external Volume Control Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Feb 22 15:35:29 livingrm volumio[1222]: info: Preparing to generate the ALSA configuration file Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:29 livingrm volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:35:29 livingrm volumio[1222]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 22 15:35:29 livingrm volumio[1222]: info: Reading ALSA contributions from plugins. Feb 22 15:35:29 livingrm volumio[1222]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Feb 22 15:35:29 livingrm volumio[1222]: info: I2S Param [object Object] successfully enabled Feb 22 15:35:29 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:29 livingrm volumio[1222]: info: Getting Alsa Cards List without I2S DAC Feb 22 15:35:29 livingrm (udev-worker)[4834]: controlC4: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 4' failed with exit code 99. Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 15:35:29 livingrm volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:29 livingrm volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:35:29 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:29 livingrm volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:35:29 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:29 livingrm volumio[1222]: info: Saving Spotify Acccount Feb 22 15:35:29 livingrm volumio[1222]: info: Got it Feb 22 15:35:29 livingrm volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:35:29 livingrm volumio[1222]: info: VolumeController:: Volume=undefined Mute =false Feb 22 15:35:29 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:29 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:29 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:29 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:29.695-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:58586 @ 0x24b6420" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:35:29 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:29.695-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:58586 @ 0x24b6420" id= title= Feb 22 15:35:29 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:29 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:29.704-05:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=172.20.24.220:58586 Feb 22 15:35:29 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:29.704-05:00 level=INFO msg="connection to address closed" component=conn/multi addr=172.20.24.220:58586 Feb 22 15:35:29 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:29.711-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=172.20.24.220:43644 Feb 22 15:35:29 livingrm volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:35:29 livingrm volumio[1222]: info: Got it Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:29 livingrm volumio[1222]: info: Asound.conf file unchanged, so no further update is needed Feb 22 15:35:29 livingrm volumio[1222]: info: Output device has changed, restarting MPD Feb 22 15:35:29 livingrm volumio[1222]: info: Output device has changed, restarting Shairport Sync Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:29 livingrm sudo[4891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 15:35:29 livingrm sudo[4891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:29 livingrm sudo[4891]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:29 livingrm volumio[1222]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 15:35:29 livingrm volumio[1222]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:29 livingrm sudo[4895]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 15:35:29 livingrm sudo[4895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:29 livingrm volumio[1222]: info: QobuzConnect: setDeactiveState invoked Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:29 livingrm vtcs[4579]: [2026-02-22 15:35:29.771] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 22 15:35:29 livingrm vtcs[4579]: [2026-02-22 15:35:29.771] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 22 15:35:29 livingrm systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 22 15:35:29 livingrm sudo[4903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:35:29 livingrm sudo[4905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:35:29 livingrm sudo[4905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:29 livingrm volumio[1222]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Feb 22 15:35:29 livingrm volumio[1222]: info: MPD Permissions set Feb 22 15:35:29 livingrm sudo[4903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:29 livingrm systemd[1]: mpd.service: Deactivated successfully. Feb 22 15:35:29 livingrm systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 15:35:29 livingrm systemd[1]: mpd.service: Consumed 1.469s CPU time. Feb 22 15:35:29 livingrm sudo[4911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 15:35:29 livingrm sudo[4911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:29 livingrm volumio[1222]: info: Starting Shairport Sync Feb 22 15:35:29 livingrm systemd[1]: mpd.socket: Deactivated successfully. Feb 22 15:35:29 livingrm systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 15:35:29 livingrm systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 15:35:29 livingrm sudo[4921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 15:35:29 livingrm sudo[4921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:29 livingrm systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 15:35:29 livingrm systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 15:35:29 livingrm systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 22 15:35:29 livingrm systemd[1]: vtcs.service: Killing process 4582 (vtcs) with signal SIGKILL. Feb 22 15:35:29 livingrm systemd[1]: vtcs.service: Deactivated successfully. Feb 22 15:35:29 livingrm systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 22 15:35:29 livingrm sudo[4911]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:29 livingrm sudo[4926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 15:35:29 livingrm sudo[4926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:29 livingrm sudo[4903]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:30 livingrm sudo[4905]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:30 livingrm systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 15:35:30 livingrm systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 15:35:30 livingrm systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:35:30 livingrm systemd[1]: shairport-sync.service: Consumed 1.649s CPU time. Feb 22 15:35:30 livingrm systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 22 15:35:30 livingrm qobuz-connect[4561]: 20260222 15:35:30.047 [4561.4561] INFO SampleApp: Stopping Local configuration server Feb 22 15:35:30 livingrm sudo[4931]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:35:30 livingrm sudo[4931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:30 livingrm sudo[4931]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:30 livingrm sudo[4933]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:35:30 livingrm sudo[4933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:30 livingrm sudo[4933]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:30 livingrm sudo[4925]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 15:35:30 livingrm sudo[4925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 15:35:30 livingrm sudo[4925]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:30 livingrm volumio[1222]: verbose: New Socket.io Connection to 172.20.24.166 from 172.20.24.220 UA: Mozilla/5.0 (Linux; Android 16; Pixel 10 Build/BP4A.260205.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 22 15:35:30 livingrm systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:35:30 livingrm sudo[4921]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:30 livingrm volumio[1222]: info: Shairport-Sync Started Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 22 15:35:30 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:30 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:30 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:30 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:30 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:30 livingrm volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:35:30 livingrm volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:30 livingrm volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:35:30 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:30 livingrm volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:35:30 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:30 livingrm volumio[1222]: info: Saving Spotify Acccount Feb 22 15:35:30 livingrm volumio[1222]: info: Got it Feb 22 15:35:30 livingrm volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:35:30 livingrm volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:35:30 livingrm volumio[1222]: info: Got it Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:30 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:30 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:31 livingrm qobuz-connect[4561]: 20260222 15:35:31.143 [4561.4561] INFO SampleApp: shat down connection on UNIX socket Feb 22 15:35:31 livingrm volumio[1222]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 22 15:35:31 livingrm volumio[1222]: info: QobuzConnect: setDeactiveState invoked Feb 22 15:35:31 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:31 livingrm systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 22 15:35:31 livingrm systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 15:35:31 livingrm systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 15:35:31 livingrm sudo[4926]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:31 livingrm volumio[1222]: info: Executing endpoint qc_getconfig Feb 22 15:35:31 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 22 15:35:31 livingrm qobuz-connect[4959]: 20260222 15:35:31.275 [4959.4959] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 22 15:35:31 livingrm qobuz-connect[4959]: 20260222 15:35:31.280 [4959.4959] INFO VolumeManager: [0xafa588]: Setting new playback volume: 75 Feb 22 15:35:31 livingrm volumio[1222]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 22 15:35:31 livingrm volumio[1222]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 22 15:35:31 livingrm qobuz-connect[4959]: 20260222 15:35:31.281 [4959.4959] INFO VolumeManager: [0xafa588]: Setting new mute state: 0 Feb 22 15:35:31 livingrm qobuz-connect[4959]: 20260222 15:35:31.281 [4959.4959] INFO QobuzConnect: [0xafaf58]: Client initialized! Feb 22 15:35:31 livingrm qobuz-connect[4959]: 20260222 15:35:31.281 [4959.4959] INFO SampleApp: Starting Avahi advertising, name: LivingRm, service name: _qobuz-connect._tcp Feb 22 15:35:31 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:31 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:31 livingrm qobuz-connect[4959]: 20260222 15:35:31.298 [4959.4959] INFO LocalConfigManager: [0xaf9e68]: Starting Local Configuration server Feb 22 15:35:31 livingrm qobuz-connect[4959]: 20260222 15:35:31.298 [4959.4959] INFO SampleApp: Starting Local configuration server Feb 22 15:35:31 livingrm qobuz-connect[4959]: 20260222 15:35:31.298 [4959.4959] INFO SampleApp: Connected to UNIX socket client 0xae4818 Feb 22 15:35:31 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:31 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:31 livingrm sudo[4967]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:31 livingrm sudo[4967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:31 livingrm sudo[4967]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:31 livingrm sudo[4971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:31 livingrm sudo[4971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:31 livingrm sudo[4971]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:31 livingrm sudo[4975]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:31 livingrm sudo[4975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:31 livingrm sudo[4975]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:31 livingrm qobuz-connect[4959]: 20260222 15:35:31.425 [4959.4959] INFO SampleApp: Playback volume changed: 75 Feb 22 15:35:31 livingrm sudo[4979]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:31 livingrm sudo[4979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:31 livingrm sudo[4979]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:31 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:31 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:31 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:31 livingrm mpd[4939]: 2026-02-22T15:35:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 15:35:31 livingrm systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 15:35:31 livingrm sudo[4895]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:31 livingrm volumio[1222]: error: updateQueue error: null Feb 22 15:35:31 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:31 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:31 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:31 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:31 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:31 livingrm sudo[4985]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:31 livingrm sudo[4985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:31 livingrm sudo[4985]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:31 livingrm sudo[4989]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:31 livingrm sudo[4989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:31 livingrm sudo[4989]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:31 livingrm sudo[4993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:31 livingrm sudo[4993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:31 livingrm sudo[4993]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:32 livingrm sudo[4997]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:32 livingrm sudo[4997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:32 livingrm sudo[4997]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:32 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 22 15:35:32 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 22 15:35:32 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:32 livingrm sudo[5000]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 15:35:32 livingrm sudo[5000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:32 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:32 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:32 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:32 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:33 livingrm volumio[1222]: info: TidalConnect service stoped! Feb 22 15:35:33 livingrm volumio[1222]: info: TidalConnect service stoped! Feb 22 15:35:33 livingrm volumio[1222]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 22 15:35:33 livingrm volumio[1222]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 22 15:35:33 livingrm sudo[5011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 22 15:35:33 livingrm sudo[5011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:33 livingrm systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 22 15:35:33 livingrm sudo[5011]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:33 livingrm volumio[1222]: info: Executing endpoint tc_getconfig Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 22 15:35:33 livingrm vtcs[5014]: STARTING TidalConnect services, version: 1.6.1 Feb 22 15:35:33 livingrm vtcs[5014]: STARTED TidalConnect services. Feb 22 15:35:33 livingrm volumio[1222]: info: Executing endpoint tc_connect Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 22 15:35:33 livingrm volumio[1222]: info: Connecting to TidalConnect Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::servicePushState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:33 livingrm volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 22 15:35:33 livingrm volumio[1222]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 22 15:35:33 livingrm volumio[1222]: verbose: CURRENT POSITION 0 Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:33 livingrm volumio[1222]: info: No code Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::servicePushState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:33 livingrm volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 22 15:35:33 livingrm volumio[1222]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 22 15:35:33 livingrm volumio[1222]: verbose: CURRENT POSITION 0 Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:33 livingrm volumio[1222]: info: No code Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.389-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.389-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" id= title= Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.391-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.391-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" id= title= Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.392-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.392-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" id= title= Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.393-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.393-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" id= title= Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.395-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.395-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" id= title= Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.396-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" state=STATUS_UNSPECIFIED positionMs= volume=86 Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.397-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:43644 @ 0x2380990" id= title= Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.416-05:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=172.20.24.220:43644 Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.416-05:00 level=INFO msg="connection to address closed" component=conn/multi addr=172.20.24.220:43644 Feb 22 15:35:33 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:33.426-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=172.20.24.220:43656 Feb 22 15:35:33 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:33 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:33 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:33 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:33 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:33 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::getcurrentVolume Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 15:35:33 livingrm volumio[1222]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Feb 22 15:35:33 livingrm volumio[1222]: info: VolumeController:: Volume=undefined Mute =false Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::updateTrackBlock Feb 22 15:35:33 livingrm volumio[1222]: info: CorePlayQueue::getTrackBlock Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 15:35:33 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:33 livingrm volumio[1222]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Feb 22 15:35:33 livingrm volumio[1222]: info: VolumeController:: Volume=undefined Mute =false Feb 22 15:35:33 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:33 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:33 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:33 livingrm sudo[5035]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:35:33 livingrm sudo[5035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:33 livingrm sudo[5035]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:33 livingrm sudo[5037]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:35:33 livingrm sudo[5037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:33 livingrm sudo[5037]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:33 livingrm volumio[1222]: verbose: New Socket.io Connection to 172.20.24.166 from 172.20.24.220 UA: Mozilla/5.0 (Linux; Android 16; Pixel 10 Build/BP4A.260205.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 22 15:35:33 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:33 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:33 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:33 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:33 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:33 livingrm volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:35:33 livingrm volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:33 livingrm volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:35:33 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:33 livingrm volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:35:33 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:33 livingrm volumio[1222]: info: Saving Spotify Acccount Feb 22 15:35:33 livingrm volumio[1222]: info: Got it Feb 22 15:35:33 livingrm volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:35:33 livingrm volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:35:33 livingrm volumio[1222]: info: Got it Feb 22 15:35:33 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:34 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 15:35:34 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:34 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:34 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:34 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:34 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:34 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:34 livingrm sudo[5062]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:34 livingrm sudo[5062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:34 livingrm sudo[5062]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:34 livingrm sudo[5066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:34 livingrm sudo[5066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:34 livingrm sudo[5066]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:34 livingrm sudo[5070]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:34 livingrm sudo[5070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:34 livingrm sudo[5070]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:34 livingrm sudo[5074]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:34 livingrm sudo[5074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:34 livingrm sudo[5074]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 22 15:35:34 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:34 livingrm sudo[5077]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 15:35:34 livingrm sudo[5077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:34 livingrm sudo[5077]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:34 livingrm volumio[1222]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 22 15:35:34 livingrm volumio[1222]: wlan0 Interface doesn't support scanning : Device or resource busy Feb 22 15:35:34 livingrm volumio[1222]: info: Cannot use regular scanning, forcing with ap-force Feb 22 15:35:34 livingrm sudo[5081]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 22 15:35:34 livingrm sudo[5081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:34 livingrm sudo[5081]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:34 livingrm volumio[1222]: command failed: Device or resource busy (-16) Feb 22 15:35:34 livingrm volumio[1222]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 22 15:35:34 livingrm volumio[1222]: command failed: Device or resource busy (-16) Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:34 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:34 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:34 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:34 livingrm sudo[5085]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:34 livingrm sudo[5085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:34 livingrm sudo[5085]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:34 livingrm sudo[5000]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:34 livingrm sudo[5089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:34 livingrm sudo[5089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:34 livingrm sudo[5089]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:34 livingrm sudo[5093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:34 livingrm sudo[5093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:34 livingrm sudo[5093]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:35 livingrm sudo[5097]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:35 livingrm sudo[5097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:35 livingrm sudo[5097]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:35 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:35 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:35 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:35 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:35 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:35 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:35 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:35 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:35 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:35 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:35.374-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="172.20.24.220:43656 @ 0x2380ff0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 22 15:35:35 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 15:35:35 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 15:35:35 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:35 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:36 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:36 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 22 15:35:36 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 22 15:35:36 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 15:35:36 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:36 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:36 livingrm volumio[1222]: info: TidalConnect service started! Feb 22 15:35:36 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 22 15:35:36 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 15:35:36 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:36 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:36 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:36 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 22 15:35:36 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:36 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 22 15:35:37 livingrm volumio[1222]: info: Preparing to save Alsa Options, stopping services first Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:37 livingrm volumio[1222]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"label":"HiFiBerry Amp2"},"output_device":{"value":1,"label":"HiFiBerry Amp2"},"disallowPush":true} Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry Amp2 Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 22 15:35:37 livingrm volumio[1222]: info: Updating Volume Controller Parameters: Device: 4 Name: HiFiBerry Amp2 Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 22 15:35:37 livingrm volumio[1222]: info: Disabling external Volume Control Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:37 livingrm volumio[1222]: info: Preparing to generate the ALSA configuration file Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:37 livingrm volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:35:37 livingrm volumio[1222]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 22 15:35:37 livingrm volumio[1222]: info: Reading ALSA contributions from plugins. Feb 22 15:35:37 livingrm volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:37 livingrm volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:35:37 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:37 livingrm volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:35:37 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:37 livingrm volumio[1222]: info: Saving Spotify Acccount Feb 22 15:35:37 livingrm volumio[1222]: info: Got it Feb 22 15:35:37 livingrm volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:35:37 livingrm volumio[1222]: info: VolumeController:: Volume=100 Mute =false Feb 22 15:35:37 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:37 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:37 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:37 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:37.680-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:43656 @ 0x2380ff0" state=STATUS_UNSPECIFIED positionMs= volume=100 Feb 22 15:35:37 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:37.681-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:43656 @ 0x2380ff0" id= title= Feb 22 15:35:37 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:37.688-05:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=172.20.24.220:43656 Feb 22 15:35:37 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:37.688-05:00 level=INFO msg="connection to address closed" component=conn/multi addr=172.20.24.220:43656 Feb 22 15:35:37 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:37.700-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=172.20.24.220:57078 Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:37 livingrm volumio[1222]: info: Getting Alsa Cards List without I2S DAC Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 15:35:37 livingrm volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:35:37 livingrm volumio[1222]: info: Got it Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:37 livingrm volumio[1222]: info: Asound.conf file unchanged, so no further update is needed Feb 22 15:35:37 livingrm volumio[1222]: info: Output device has changed, restarting MPD Feb 22 15:35:37 livingrm volumio[1222]: info: Output device has changed, restarting Shairport Sync Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:37 livingrm sudo[5129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 15:35:37 livingrm sudo[5129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:37 livingrm sudo[5129]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:37 livingrm sudo[5131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 15:35:37 livingrm sudo[5131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:37 livingrm volumio[1222]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 15:35:37 livingrm volumio[1222]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: QobuzConnect: setDeactiveState invoked Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:37 livingrm vtcs[5014]: [2026-02-22 15:35:37.854] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 22 15:35:37 livingrm vtcs[5014]: [2026-02-22 15:35:37.854] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 22 15:35:37 livingrm systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 22 15:35:37 livingrm sudo[5141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:35:37 livingrm sudo[5141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:37 livingrm sudo[5143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:35:37 livingrm sudo[5143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:37 livingrm systemd[1]: mpd.service: Deactivated successfully. Feb 22 15:35:37 livingrm systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 15:35:37 livingrm systemd[1]: mpd.service: Consumed 1.514s CPU time. Feb 22 15:35:37 livingrm systemd[1]: mpd.socket: Deactivated successfully. Feb 22 15:35:37 livingrm systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 15:35:37 livingrm systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 15:35:37 livingrm volumio[1222]: info: MPD Permissions set Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:37 livingrm sudo[5151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 15:35:37 livingrm sudo[5151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:37 livingrm volumio[1222]: info: Starting Shairport Sync Feb 22 15:35:37 livingrm volumio[1222]: info: Preparing to generate the ALSA configuration file Feb 22 15:35:37 livingrm systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 15:35:37 livingrm volumio[1222]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 22 15:35:37 livingrm volumio[1222]: info: Reading ALSA contributions from plugins. Feb 22 15:35:37 livingrm systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 15:35:37 livingrm sudo[5160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 15:35:37 livingrm sudo[5160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:37 livingrm volumio[1222]: info: Asound.conf file unchanged, so no further update is needed Feb 22 15:35:37 livingrm volumio[1222]: info: Output device has changed, restarting MPD Feb 22 15:35:37 livingrm systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 22 15:35:38 livingrm sudo[5151]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm systemd[1]: vtcs.service: Deactivated successfully. Feb 22 15:35:38 livingrm systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 22 15:35:38 livingrm sudo[5166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 15:35:38 livingrm sudo[5166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:38 livingrm volumio[1222]: info: Output device has changed, restarting Shairport Sync Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:38 livingrm sudo[5164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 15:35:38 livingrm sudo[5164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:38 livingrm sudo[5143]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm sudo[5168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 15:35:38 livingrm sudo[5168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:38 livingrm sudo[5141]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm sudo[5164]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm volumio[1222]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 15:35:38 livingrm volumio[1222]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:38 livingrm volumio[1222]: info: QobuzConnect: setDeactiveState invoked Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:38 livingrm systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 22 15:35:38 livingrm systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 15:35:38 livingrm systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 15:35:38 livingrm systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:35:38 livingrm systemd[1]: shairport-sync.service: Consumed 1.556s CPU time. Feb 22 15:35:38 livingrm sudo[5161]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 15:35:38 livingrm qobuz-connect[4959]: 20260222 15:35:38.101 [4959.4959] INFO SampleApp: Stopping Local configuration server Feb 22 15:35:38 livingrm sudo[5161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 15:35:38 livingrm sudo[5161]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm sudo[5180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:35:38 livingrm sudo[5180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:38 livingrm sudo[5183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:35:38 livingrm sudo[5183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:38 livingrm volumio[1222]: info: MPD Permissions set Feb 22 15:35:38 livingrm sudo[5191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 15:35:38 livingrm sudo[5191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:38 livingrm systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:35:38 livingrm systemd[1]: mpd.service: Deactivated successfully. Feb 22 15:35:38 livingrm systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 15:35:38 livingrm sudo[5193]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:35:38 livingrm sudo[5193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:38 livingrm sudo[5160]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm sudo[5193]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:38 livingrm sudo[5197]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:35:38 livingrm sudo[5197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:38 livingrm systemd[1]: mpd.socket: Deactivated successfully. Feb 22 15:35:38 livingrm systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 15:35:38 livingrm systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:38 livingrm systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 15:35:38 livingrm sudo[5197]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 15:35:38 livingrm sudo[5191]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm volumio[1222]: info: Shairport-Sync Started Feb 22 15:35:38 livingrm sudo[5180]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm volumio[1222]: info: Starting Shairport Sync Feb 22 15:35:38 livingrm sudo[5220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 15:35:38 livingrm sudo[5220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:38 livingrm sudo[5183]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm volumio[1222]: verbose: New Socket.io Connection to 172.20.24.166 from 172.20.24.220 UA: Mozilla/5.0 (Linux; Android 16; Pixel 10 Build/BP4A.260205.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 22 15:35:38 livingrm sudo[5224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 15:35:38 livingrm sudo[5224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:38 livingrm sudo[5218]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 15:35:38 livingrm sudo[5218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 15:35:38 livingrm systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 15:35:38 livingrm sudo[5218]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 15:35:38 livingrm systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 22 15:35:38 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:38 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:38 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:38 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:38 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:38 livingrm volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:35:38 livingrm volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:38 livingrm volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:35:38 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:38 livingrm volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:35:38 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:38 livingrm volumio[1222]: info: Saving Spotify Acccount Feb 22 15:35:38 livingrm volumio[1222]: info: Got it Feb 22 15:35:38 livingrm volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:35:38 livingrm systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:35:38 livingrm sudo[5224]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:38 livingrm volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:35:38 livingrm volumio[1222]: info: Got it Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:38 livingrm volumio[1222]: info: Shairport-Sync Started Feb 22 15:35:38 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:38 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:39 livingrm qobuz-connect[4959]: 20260222 15:35:39.322 [4959.4959] INFO SampleApp: shat down connection on UNIX socket Feb 22 15:35:39 livingrm volumio[1222]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 22 15:35:39 livingrm volumio[1222]: info: QobuzConnect: setDeactiveState invoked Feb 22 15:35:39 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:39 livingrm systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 22 15:35:39 livingrm systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 15:35:39 livingrm systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 15:35:39 livingrm sudo[5166]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm sudo[5220]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm volumio[1222]: info: Executing endpoint qc_getconfig Feb 22 15:35:39 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 22 15:35:39 livingrm qobuz-connect[5249]: 20260222 15:35:39.479 [5249.5249] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 22 15:35:39 livingrm volumio[1222]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 22 15:35:39 livingrm volumio[1222]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 22 15:35:39 livingrm qobuz-connect[5249]: 20260222 15:35:39.483 [5249.5249] INFO VolumeManager: [0x1768588]: Setting new playback volume: 75 Feb 22 15:35:39 livingrm qobuz-connect[5249]: 20260222 15:35:39.483 [5249.5249] INFO VolumeManager: [0x1768588]: Setting new mute state: 0 Feb 22 15:35:39 livingrm qobuz-connect[5249]: 20260222 15:35:39.483 [5249.5249] INFO QobuzConnect: [0x1768f58]: Client initialized! Feb 22 15:35:39 livingrm qobuz-connect[5249]: 20260222 15:35:39.483 [5249.5249] INFO SampleApp: Starting Avahi advertising, name: LivingRm, service name: _qobuz-connect._tcp Feb 22 15:35:39 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:39 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:39 livingrm qobuz-connect[5249]: 20260222 15:35:39.502 [5249.5249] INFO LocalConfigManager: [0x1767e68]: Starting Local Configuration server Feb 22 15:35:39 livingrm qobuz-connect[5249]: 20260222 15:35:39.502 [5249.5249] INFO SampleApp: Starting Local configuration server Feb 22 15:35:39 livingrm qobuz-connect[5249]: 20260222 15:35:39.503 [5249.5249] INFO SampleApp: Connected to UNIX socket client 0x1752818 Feb 22 15:35:39 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:39 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:39 livingrm sudo[5257]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:39 livingrm sudo[5257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:39 livingrm sudo[5257]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm sudo[5261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:39 livingrm sudo[5261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:39 livingrm sudo[5261]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm sudo[5265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:39 livingrm sudo[5265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:39 livingrm sudo[5265]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm qobuz-connect[5249]: 20260222 15:35:39.637 [5249.5249] INFO SampleApp: Playback volume changed: 75 Feb 22 15:35:39 livingrm sudo[5269]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:39 livingrm sudo[5269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:39 livingrm sudo[5269]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:39 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:39 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:39 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:39 livingrm sudo[5273]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:39 livingrm sudo[5273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:39 livingrm sudo[5273]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm sudo[5277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:39 livingrm sudo[5277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:39 livingrm sudo[5277]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm sudo[5281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:39 livingrm sudo[5281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:39 livingrm sudo[5281]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm sudo[5285]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:39 livingrm sudo[5285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:39 livingrm sudo[5285]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 22 15:35:39 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 22 15:35:39 livingrm mpd[5228]: 2026-02-22T15:35:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 15:35:39 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:39 livingrm systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 15:35:39 livingrm sudo[5168]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm sudo[5131]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:39 livingrm sudo[5288]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 15:35:39 livingrm sudo[5288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:39 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:39 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:39 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:39 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:39 livingrm volumio[1222]: error: MPD error: The expression evaluated to a falsy value: Feb 22 15:35:39 livingrm volumio[1222]: assert.ok(self.idling) Feb 22 15:35:39 livingrm volumio[1222]: error: The expression evaluated to a falsy value: Feb 22 15:35:39 livingrm volumio[1222]: assert.ok(self.idling) Feb 22 15:35:39 livingrm volumio[1222]: error: updateQueue error: null Feb 22 15:35:40 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:40 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:40 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:40 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:40 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 22 15:35:40 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 22 15:35:40 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 15:35:40 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:40 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:41 livingrm volumio[1222]: info: TidalConnect service stoped! Feb 22 15:35:41 livingrm volumio[1222]: info: TidalConnect service stoped! Feb 22 15:35:41 livingrm volumio[1222]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 22 15:35:41 livingrm volumio[1222]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 22 15:35:41 livingrm sudo[5304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 22 15:35:41 livingrm sudo[5304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:41 livingrm systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 22 15:35:41 livingrm sudo[5304]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:41 livingrm volumio[1222]: info: TidalConnect service stoped! Feb 22 15:35:41 livingrm volumio[1222]: info: TidalConnect service stoped! Feb 22 15:35:41 livingrm volumio[1222]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 22 15:35:41 livingrm volumio[1222]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 22 15:35:41 livingrm volumio[1222]: info: Executing endpoint tc_getconfig Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 22 15:35:41 livingrm vtcs[5307]: STARTING TidalConnect services, version: 1.6.1 Feb 22 15:35:41 livingrm vtcs[5307]: STARTED TidalConnect services. Feb 22 15:35:41 livingrm volumio[1222]: info: Executing endpoint tc_connect Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 22 15:35:41 livingrm volumio[1222]: info: Connecting to TidalConnect Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::servicePushState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:41 livingrm volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 22 15:35:41 livingrm volumio[1222]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 22 15:35:41 livingrm volumio[1222]: verbose: CURRENT POSITION 0 Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:41 livingrm sudo[5324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:41 livingrm sudo[5324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:41 livingrm volumio[1222]: info: No code Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::servicePushState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:41 livingrm volumio[1222]: info: CorePlayQueue::getTrack 0 Feb 22 15:35:41 livingrm volumio[1222]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 22 15:35:41 livingrm volumio[1222]: verbose: CURRENT POSITION 0 Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::syncState stateService stop Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::syncState currentStatus stop Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:41 livingrm volumio[1222]: info: No code Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:41 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:41.386-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:57078 @ 0x2380690" state=STATUS_UNSPECIFIED positionMs= volume=100 Feb 22 15:35:41 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:41.386-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:57078 @ 0x2380690" id= title= Feb 22 15:35:41 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:41.389-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:57078 @ 0x2380690" state=STATUS_UNSPECIFIED positionMs= volume=100 Feb 22 15:35:41 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:41.389-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:57078 @ 0x2380690" id= title= Feb 22 15:35:41 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:41.392-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:57078 @ 0x2380690" state=STATUS_UNSPECIFIED positionMs= volume=100 Feb 22 15:35:41 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:41.392-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:57078 @ 0x2380690" id= title= Feb 22 15:35:41 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:41.394-05:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=172.20.24.220:57078 Feb 22 15:35:41 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:41.394-05:00 level=INFO msg="connection to address closed" component=conn/multi addr=172.20.24.220:57078 Feb 22 15:35:41 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:41.408-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=172.20.24.220:57098 Feb 22 15:35:41 livingrm sudo[5324]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:41 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:41 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:41 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:41 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:41 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:41 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::getcurrentVolume Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 15:35:41 livingrm sudo[5336]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:35:41 livingrm sudo[5336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:41 livingrm sudo[5336]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:41 livingrm sudo[5338]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:35:41 livingrm sudo[5338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:41 livingrm sudo[5338]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:41 livingrm volumio[1222]: info: VolumeController:: Volume=100 Mute =false Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::updateTrackBlock Feb 22 15:35:41 livingrm volumio[1222]: info: CorePlayQueue::getTrackBlock Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 15:35:41 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:41 livingrm volumio[1222]: verbose: New Socket.io Connection to 172.20.24.166 from 172.20.24.220 UA: Mozilla/5.0 (Linux; Android 16; Pixel 10 Build/BP4A.260205.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 22 15:35:41 livingrm volumio[1222]: info: VolumeController:: Volume=100 Mute =false Feb 22 15:35:41 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:41 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:41 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 22 15:35:41 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:41 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:41 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:41 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:41 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:41 livingrm volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:35:41 livingrm volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:41 livingrm volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:35:41 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:41 livingrm volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:35:41 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:41 livingrm volumio[1222]: info: Saving Spotify Acccount Feb 22 15:35:41 livingrm volumio[1222]: info: Got it Feb 22 15:35:41 livingrm volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:35:41 livingrm volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:35:41 livingrm volumio[1222]: info: Got it Feb 22 15:35:41 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:42 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:42 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:42 livingrm sudo[5288]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:42 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:42 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:42 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:42 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:42 livingrm sudo[5353]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:42 livingrm sudo[5353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:42 livingrm sudo[5353]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:42 livingrm sudo[5357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:43 livingrm sudo[5357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:43 livingrm sudo[5357]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:43 livingrm sudo[5361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:43 livingrm sudo[5361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:43 livingrm sudo[5361]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:43 livingrm sudo[5365]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:43 livingrm sudo[5365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:43 livingrm sudo[5365]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:43 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:43 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:43 livingrm sudo[5369]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:43 livingrm sudo[5369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:43 livingrm sudo[5369]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:43 livingrm sudo[5373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:43 livingrm sudo[5373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:43 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:43.135-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="172.20.24.220:57098 @ 0x22c6390" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 22 15:35:43 livingrm sudo[5373]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:43 livingrm sudo[5377]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:43 livingrm sudo[5377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:43 livingrm sudo[5377]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:43 livingrm sudo[5381]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:43 livingrm sudo[5381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:43 livingrm sudo[5381]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 22 15:35:43 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:43 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:43 livingrm sudo[5384]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 15:35:43 livingrm sudo[5384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:43 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 15:35:43 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:43 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:43 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:43 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:44 livingrm volumio[1222]: info: TidalConnect service started! Feb 22 15:35:44 livingrm volumio[1222]: info: TidalConnect service started! Feb 22 15:35:44 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 22 15:35:44 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 22 15:35:44 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 15:35:44 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:44 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:44 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 22 15:35:45 livingrm volumio[1222]: info: Preparing to save Alsa Options, stopping services first Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:45 livingrm volumio[1222]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"label":"HiFiBerry Amp2"},"output_device":{"value":1,"label":"HiFiBerry Amp2"},"disallowPush":true} Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry Amp2 Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 22 15:35:45 livingrm volumio[1222]: info: Updating Volume Controller Parameters: Device: 4 Name: HiFiBerry Amp2 Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 22 15:35:45 livingrm volumio[1222]: info: Disabling external Volume Control Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:45 livingrm volumio[1222]: info: Preparing to generate the ALSA configuration file Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:45 livingrm volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:35:45 livingrm volumio[1222]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 22 15:35:45 livingrm volumio[1222]: info: Reading ALSA contributions from plugins. Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:45 livingrm volumio[1222]: info: Getting Alsa Cards List without I2S DAC Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 15:35:45 livingrm volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:45 livingrm volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:35:45 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:45 livingrm volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:35:45 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:45 livingrm volumio[1222]: info: Saving Spotify Acccount Feb 22 15:35:45 livingrm volumio[1222]: info: Got it Feb 22 15:35:45 livingrm volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:35:45 livingrm volumio[1222]: info: VolumeController:: Volume=100 Mute =false Feb 22 15:35:45 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:45 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:45 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:45 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:45.461-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:57098 @ 0x22c6390" state=STATUS_UNSPECIFIED positionMs= volume=100 Feb 22 15:35:45 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:45.461-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:57098 @ 0x22c6390" id= title= Feb 22 15:35:45 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:45.469-05:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=172.20.24.220:57098 Feb 22 15:35:45 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:45.469-05:00 level=INFO msg="connection to address closed" component=conn/multi addr=172.20.24.220:57098 Feb 22 15:35:45 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:45 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:45.474-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=172.20.24.220:43402 Feb 22 15:35:45 livingrm volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:35:45 livingrm volumio[1222]: info: Got it Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:45 livingrm volumio[1222]: info: Asound.conf file unchanged, so no further update is needed Feb 22 15:35:45 livingrm volumio[1222]: info: Output device has changed, restarting MPD Feb 22 15:35:45 livingrm volumio[1222]: info: Output device has changed, restarting Shairport Sync Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:45 livingrm sudo[5427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 15:35:45 livingrm sudo[5427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:45 livingrm sudo[5427]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:45 livingrm sudo[5429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 15:35:45 livingrm sudo[5429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:45 livingrm volumio[1222]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 15:35:45 livingrm volumio[1222]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: QobuzConnect: setDeactiveState invoked Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:45 livingrm vtcs[5307]: [2026-02-22 15:35:45.532] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 22 15:35:45 livingrm vtcs[5307]: [2026-02-22 15:35:45.533] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 22 15:35:45 livingrm systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 22 15:35:45 livingrm sudo[5439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:35:45 livingrm sudo[5439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:45 livingrm sudo[5441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:35:45 livingrm sudo[5441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:45 livingrm systemd[1]: mpd.service: Deactivated successfully. Feb 22 15:35:45 livingrm systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 15:35:45 livingrm systemd[1]: mpd.service: Consumed 1.562s CPU time. Feb 22 15:35:45 livingrm volumio[1222]: info: MPD Permissions set Feb 22 15:35:45 livingrm systemd[1]: mpd.socket: Deactivated successfully. Feb 22 15:35:45 livingrm sudo[5449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 15:35:45 livingrm systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 15:35:45 livingrm systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 15:35:45 livingrm sudo[5449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:45 livingrm volumio[1222]: info: Starting Shairport Sync Feb 22 15:35:45 livingrm systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 15:35:45 livingrm sudo[5458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 15:35:45 livingrm sudo[5458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:45 livingrm systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 15:35:45 livingrm systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 22 15:35:45 livingrm systemd[1]: vtcs.service: Deactivated successfully. Feb 22 15:35:45 livingrm systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 22 15:35:45 livingrm sudo[5449]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:45 livingrm sudo[5439]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:45 livingrm systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 15:35:45 livingrm systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 15:35:45 livingrm systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:35:45 livingrm sudo[5461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 15:35:45 livingrm systemd[1]: shairport-sync.service: Consumed 1.541s CPU time. Feb 22 15:35:45 livingrm sudo[5461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:45 livingrm sudo[5441]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:45 livingrm systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:35:45 livingrm sudo[5458]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:45 livingrm systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 22 15:35:45 livingrm qobuz-connect[5249]: 20260222 15:35:45.793 [5249.5249] INFO SampleApp: Stopping Local configuration server Feb 22 15:35:45 livingrm volumio[1222]: info: Shairport-Sync Started Feb 22 15:35:45 livingrm sudo[5459]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 15:35:45 livingrm sudo[5459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 15:35:45 livingrm sudo[5469]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 15:35:45 livingrm sudo[5467]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 15:35:45 livingrm sudo[5459]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:45 livingrm sudo[5469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:45 livingrm sudo[5467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:45 livingrm sudo[5469]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:45 livingrm sudo[5467]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:45 livingrm volumio[1222]: verbose: New Socket.io Connection to 172.20.24.166 from 172.20.24.220 UA: Mozilla/5.0 (Linux; Android 16; Pixel 10 Build/BP4A.260205.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 22 15:35:46 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:46 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:46 livingrm volumio-remote-updater[804]: Test mode disabled Feb 22 15:35:46 livingrm volumio-remote-updater[804]: Alpha mode disabled Feb 22 15:35:46 livingrm volumio-remote-updater[804]: Alpha legacy test mode disabled Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:46 livingrm volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:35:46 livingrm volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:46 livingrm volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:35:46 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:46 livingrm volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:35:46 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:46 livingrm volumio[1222]: info: Saving Spotify Acccount Feb 22 15:35:46 livingrm volumio[1222]: info: Got it Feb 22 15:35:46 livingrm volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:35:46 livingrm volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:35:46 livingrm volumio[1222]: info: Got it Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:46 livingrm sudo[5384]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:46 livingrm volumio[1222]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 15:35:46 livingrm volumio[1222]: info: Received Get System Info Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 15:35:46 livingrm volumio[1222]: info: Discovery: Getting this device information Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 15:35:46 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:47 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:47 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:47 livingrm sudo[5495]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:47 livingrm sudo[5495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:47 livingrm sudo[5495]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:47 livingrm sudo[5499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:47 livingrm sudo[5499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:47 livingrm sudo[5499]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:47 livingrm mpd[5473]: 2026-02-22T15:35:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 15:35:47 livingrm systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 15:35:47 livingrm sudo[5429]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:47 livingrm sudo[5505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:47 livingrm sudo[5505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:47 livingrm sudo[5505]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:47 livingrm sudo[5509]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:47 livingrm sudo[5509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:47 livingrm sudo[5509]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 22 15:35:47 livingrm volumio[1222]: info: Loading i18n strings for locale en Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 22 15:35:47 livingrm volumio[1222]: info: Setting timezone to America/New_York Feb 22 15:35:47 livingrm sudo[5513]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 22 15:35:47 livingrm sudo[5513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:47 livingrm sudo[5513]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:47 livingrm sudo[5517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/New_York /etc/localtime Feb 22 15:35:47 livingrm sudo[5517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:47 livingrm sudo[5517]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:47 livingrm sudo[5521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 22 15:35:47 livingrm sudo[5521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:47 livingrm sudo[5521]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:47 livingrm sudo[5525]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/New_York Feb 22 15:35:47 livingrm sudo[5525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:47 livingrm sudo[5525]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 22 15:35:47 livingrm sudo[5528]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 15:35:47 livingrm sudo[5528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:47 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:47 livingrm volumio[1222]: Updating browse sources language Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 15:35:47 livingrm volumio[1222]: error: updateQueue error: null Feb 22 15:35:47 livingrm qobuz-connect[5249]: 20260222 15:35:47.513 [5249.5249] INFO SampleApp: shat down connection on UNIX socket Feb 22 15:35:47 livingrm volumio[1222]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 22 15:35:47 livingrm volumio[1222]: info: QobuzConnect: setDeactiveState invoked Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:47 livingrm systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 22 15:35:47 livingrm systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 15:35:47 livingrm systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 15:35:47 livingrm sudo[5461]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:47 livingrm volumio[1222]: info: Executing endpoint qc_getconfig Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 22 15:35:47 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:47 livingrm qobuz-connect[5530]: 20260222 15:35:47.617 [5530.5530] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 22 15:35:47 livingrm volumio[1222]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 22 15:35:47 livingrm volumio[1222]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 22 15:35:47 livingrm qobuz-connect[5530]: 20260222 15:35:47.621 [5530.5530] INFO VolumeManager: [0x419588]: Setting new playback volume: 75 Feb 22 15:35:47 livingrm qobuz-connect[5530]: 20260222 15:35:47.621 [5530.5530] INFO VolumeManager: [0x419588]: Setting new mute state: 0 Feb 22 15:35:47 livingrm qobuz-connect[5530]: 20260222 15:35:47.621 [5530.5530] INFO QobuzConnect: [0x419f58]: Client initialized! Feb 22 15:35:47 livingrm qobuz-connect[5530]: 20260222 15:35:47.621 [5530.5530] INFO SampleApp: Starting Avahi advertising, name: LivingRm, service name: _qobuz-connect._tcp Feb 22 15:35:47 livingrm qobuz-connect[5530]: 20260222 15:35:47.633 [5530.5530] INFO LocalConfigManager: [0x418e68]: Starting Local Configuration server Feb 22 15:35:47 livingrm qobuz-connect[5530]: 20260222 15:35:47.633 [5530.5530] INFO SampleApp: Starting Local configuration server Feb 22 15:35:47 livingrm qobuz-connect[5530]: 20260222 15:35:47.633 [5530.5530] INFO SampleApp: Connected to UNIX socket client 0x403818 Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:47 livingrm volumio[1222]: info: Fetching Streaming Services browse cache Feb 22 15:35:47 livingrm qobuz-connect[5530]: 20260222 15:35:47.767 [5530.5530] INFO SampleApp: Playback volume changed: 75 Feb 22 15:35:47 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:48 livingrm volumio[1222]: info: TidalConnect service stoped! Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 22 15:35:48 livingrm volumio[1222]: info: Preparing to save Alsa Options, stopping services first Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:48 livingrm volumio[1222]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"label":"HiFiBerry Amp2"},"output_device":{"value":1,"label":"HiFiBerry Amp2"},"disallowPush":true} Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry Amp2 Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 22 15:35:48 livingrm volumio[1222]: info: Updating Volume Controller Parameters: Device: 4 Name: HiFiBerry Amp2 Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 22 15:35:48 livingrm volumio[1222]: info: Disabling external Volume Control Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:48 livingrm volumio[1222]: info: Preparing to generate the ALSA configuration file Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:48 livingrm volumio[1222]: info: Retrieving Cloud Streaming UI Feb 22 15:35:48 livingrm volumio[1222]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 22 15:35:48 livingrm volumio[1222]: info: Reading ALSA contributions from plugins. Feb 22 15:35:48 livingrm volumio[1222]: info: TidalConnect service stoped! Feb 22 15:35:48 livingrm volumio[1222]: info: VolumeController:: Volume=100 Mute =false Feb 22 15:35:48 livingrm volumio[1222]: info: CoreStateMachine::pushState Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::volumioPushState Feb 22 15:35:48 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output update for this device Feb 22 15:35:48 livingrm volumio[1222]: info: MRS: Pushing multiroomSync output Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:48 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:48.873-05:00 level=INFO msg="emitting player state changed event" component=server peer="172.20.24.220:43402 @ 0x24b62d0" state=STATUS_UNSPECIFIED positionMs= volume=100 Feb 22 15:35:48 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:48.874-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="172.20.24.220:43402 @ 0x24b62d0" id= title= Feb 22 15:35:48 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:48.880-05:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=172.20.24.220:43402 Feb 22 15:35:48 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:48.880-05:00 level=INFO msg="connection to address closed" component=conn/multi addr=172.20.24.220:43402 Feb 22 15:35:48 livingrm volumio[1222]: info: Getting Tidal Cloud Configuration Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:48 livingrm volumio5-onboarding[1509]: time=2026-02-22T15:35:48.885-05:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=172.20.24.220:43414 Feb 22 15:35:48 livingrm volumio[1222]: info: Getting Qobuz Cloud Configuration Feb 22 15:35:48 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:48 livingrm volumio[1222]: info: Getting Spotify Cloud Configuration Feb 22 15:35:48 livingrm volumio[1222]: info: Asking plugin for UI Config Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:48 livingrm volumio[1222]: info: Saving Spotify Acccount Feb 22 15:35:48 livingrm volumio[1222]: info: Got it Feb 22 15:35:48 livingrm volumio[1222]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 15:35:48 livingrm volumio[1222]: info: Getting Alsa Cards List without I2S DAC Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 15:35:48 livingrm volumio[1222]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.wPUiSMu8kjg2ecrRPoI1h7G0VGB3.c068015a5aa67c6de325405606d50a39.state.status' Feb 22 15:35:48 livingrm volumio[1222]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 22 15:35:48 livingrm volumio[1222]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 22 15:35:48 livingrm volumio[1222]: info: Got it Feb 22 15:35:48 livingrm volumio[1222]: info: Got Tidal Cloud Configuration Feb 22 15:35:48 livingrm volumio[1222]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 15:35:48 livingrm volumio[1222]: info: Asound.conf file unchanged, so no further update is needed Feb 22 15:35:49 livingrm volumio[1222]: info: Output device has changed, restarting MPD Feb 22 15:35:49 livingrm volumio[1222]: info: Output device has changed, restarting Shairport Sync Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:49 livingrm sudo[5571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 15:35:49 livingrm sudo[5571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:49 livingrm sudo[5571]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:49 livingrm volumio[1222]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 15:35:49 livingrm volumio[1222]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 15:35:49 livingrm sudo[5573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:49 livingrm sudo[5573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:49 livingrm volumio[1222]: info: QobuzConnect: setDeactiveState invoked Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::volumioGetState Feb 22 15:35:49 livingrm systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 22 15:35:49 livingrm sudo[5583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:35:49 livingrm sudo[5583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:49 livingrm sudo[5585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 15:35:49 livingrm sudo[5585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:49 livingrm volumio[1222]: info: MPD Permissions set Feb 22 15:35:49 livingrm systemd[1]: mpd.service: Deactivated successfully. Feb 22 15:35:49 livingrm systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 15:35:49 livingrm systemd[1]: mpd.service: Consumed 1.492s CPU time. Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:49 livingrm systemd[1]: mpd.socket: Deactivated successfully. Feb 22 15:35:49 livingrm systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 15:35:49 livingrm systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 15:35:49 livingrm sudo[5593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 15:35:49 livingrm sudo[5595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 22 15:35:49 livingrm sudo[5593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:49 livingrm sudo[5595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 15:35:49 livingrm volumio[1222]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 15:35:49 livingrm volumio[1222]: info: Starting Shairport Sync Feb 22 15:35:49 livingrm sudo[5605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 15:35:49 livingrm sudo[5605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:49 livingrm systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 15:35:49 livingrm systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 15:35:49 livingrm sudo[5583]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:49 livingrm sudo[5585]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:49 livingrm sudo[5593]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:49 livingrm volumio[1222]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 15:35:49 livingrm systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 15:35:49 livingrm volumio[1222]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Feb 22 15:35:49 livingrm volumio[1222]: Job for vtcs.service canceled. Feb 22 15:35:49 livingrm volumio[1222]: at ChildProcess.exithandler (node:child_process:421:12) Feb 22 15:35:49 livingrm volumio[1222]: at ChildProcess.emit (node:events:514:28) Feb 22 15:35:49 livingrm volumio[1222]: at maybeClose (node:internal/child_process:1105:16) Feb 22 15:35:49 livingrm volumio[1222]: at Socket. (node:internal/child_process:457:11) Feb 22 15:35:49 livingrm volumio[1222]: at Socket.emit (node:events:514:28) Feb 22 15:35:49 livingrm volumio[1222]: at Pipe. (node:net:337:12) { Feb 22 15:35:49 livingrm volumio[1222]: code: 1, Feb 22 15:35:49 livingrm volumio[1222]: killed: false, Feb 22 15:35:49 livingrm volumio[1222]: signal: null, Feb 22 15:35:49 livingrm volumio[1222]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Feb 22 15:35:49 livingrm volumio[1222]: stdout: '', Feb 22 15:35:49 livingrm volumio[1222]: stderr: 'Job for vtcs.service canceled.\n' Feb 22 15:35:49 livingrm volumio[1222]: } Feb 22 15:35:49 livingrm volumio[1222]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 15:35:49 livingrm systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 22 15:35:49 livingrm systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 15:35:49 livingrm systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:35:49 livingrm systemd[1]: shairport-sync.service: Consumed 1.530s CPU time. Feb 22 15:35:49 livingrm sudo[5595]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:49 livingrm sudo[5608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 15:35:49 livingrm sudo[5608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 15:35:49 livingrm systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 15:35:49 livingrm sudo[5605]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:49 livingrm systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 22 15:35:49 livingrm sudo[5607]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 15:35:49 livingrm sudo[5607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 15:35:49 livingrm sudo[5607]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:49 livingrm qobuz-connect[5530]: 20260222 15:35:49.331 [5530.5530] INFO SampleApp: Stopping Local configuration server Feb 22 15:35:49 livingrm qobuz-connect[5530]: 20260222 15:35:49.644 [5530.5530] INFO SampleApp: shat down connection on UNIX socket Feb 22 15:35:49 livingrm systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 22 15:35:49 livingrm systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 15:35:49 livingrm systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 15:35:49 livingrm sudo[5608]: pam_unix(sudo:session): session closed for user root Feb 22 15:35:49 livingrm sudo[5645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 15:34' Feb 22 15:35:49 livingrm sudo[5645]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"