May 27 10:41:17 volumio sudo[10281]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 10:41:17 volumio sudo[10281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:17 volumio sudo[10283]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 10:41:17 volumio sudo[10281]: pam_unix(sudo:session): session closed for user root May 27 10:41:17 volumio sudo[10283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:17 volumio sudo[10283]: pam_unix(sudo:session): session closed for user root May 27 10:41:17 volumio volumio[928]: verbose: New Socket.io Connection to 192.168.7.70 from 192.168.7.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 31 May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::volumioGetVisibleSources May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 27 10:41:17 volumio volumio[928]: info: Received Get System Info May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 10:41:17 volumio volumio[928]: info: Discovery: Getting this device information May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::volumioGetState May 27 10:41:17 volumio volumio[928]: info: CorePlayQueue::getTrack 0 May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 10:41:17 volumio volumio-remote-updater[577]: No test mode May 27 10:41:17 volumio volumio-remote-updater[577]: No alpha test mode May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:41:17 volumio volumio[928]: info: Retrieving Cloud Streaming UI May 27 10:41:17 volumio volumio[928]: info: Getting Tidal Cloud Configuration May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:41:17 volumio volumio[928]: info: Getting Qobuz Cloud Configuration May 27 10:41:17 volumio volumio[928]: info: Asking plugin for UI Config May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:41:17 volumio volumio[928]: info: Getting Spotify Cloud Configuration May 27 10:41:17 volumio volumio[928]: info: Asking plugin for UI Config May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:41:17 volumio volumio[928]: info: Saving Spotify Acccount May 27 10:41:17 volumio volumio[928]: info: Got Tidal Cloud Configuration May 27 10:41:17 volumio volumio[928]: info: Got it May 27 10:41:17 volumio volumio[928]: info: Got it May 27 10:41:17 volumio volumio[928]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:41:17 volumio volumio[928]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 27 10:41:17 volumio volumio[928]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 27 10:41:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 27 10:41:18 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 27 10:41:18 volumio volumio[928]: info: Loading i18n strings for locale de May 27 10:41:18 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 27 10:41:18 volumio volumio[928]: info: Setting timezone to Europe/Zurich May 27 10:41:18 volumio sudo[10294]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 27 10:41:18 volumio sudo[10294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:18 volumio sudo[10294]: pam_unix(sudo:session): session closed for user root May 27 10:41:18 volumio sudo[10299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Zurich /etc/localtime May 27 10:41:18 volumio sudo[10299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:18 volumio sudo[10299]: pam_unix(sudo:session): session closed for user root May 27 10:41:19 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 27 10:41:19 volumio sudo[10305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 27 10:41:19 volumio sudo[10305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:19 volumio dbus-daemon[563]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.151' (uid=0 pid=10307 comm="timedatectl show --property=NTPSynchronized --valu") May 27 10:41:19 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 27 10:41:19 volumio sudo[10305]: pam_unix(sudo:session): session closed for user root May 27 10:41:19 volumio sudo[10312]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Zurich May 27 10:41:19 volumio sudo[10312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:19 volumio dbus-daemon[563]: [system] Successfully activated service 'org.freedesktop.timedate1' May 27 10:41:19 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 27 10:41:19 volumio setdatetime-helper.sh[10298]: Time is not synchronized. Attempting to sync... May 27 10:41:19 volumio sudo[10312]: pam_unix(sudo:session): session closed for user root May 27 10:41:19 volumio volumio[928]: Updating browse sources language May 27 10:41:19 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 10:41:19 volumio volumio[928]: info: Fetching Streaming Services browse cache May 27 10:41:19 volumio sudo[10325]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 27 May 2025 08:41:19 GMT#015' May 27 10:41:19 volumio sudo[10325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 10:41:19 volumio setdatetime-helper.sh[10335]: Tue May 27 10:41:19 CEST 2025 May 27 10:41:19 volumio sudo[10325]: pam_unix(sudo:session): session closed for user root May 27 10:41:19 volumio setdatetime-helper.sh[10298]: Time synchronized successfully. May 27 10:41:19 volumio systemd-journald[318]: Time jumped backwards, rotating. May 27 10:41:19 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 27 10:41:19 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 27 10:41:19 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 27 10:41:27 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 27 10:41:27 volumio volumio[928]: info: Loading i18n strings for locale de May 27 10:41:28 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 27 10:41:28 volumio volumio[928]: info: Setting timezone to Europe/Zurich May 27 10:41:28 volumio sudo[10342]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 27 10:41:28 volumio sudo[10342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:28 volumio sudo[10342]: pam_unix(sudo:session): session closed for user root May 27 10:41:28 volumio sudo[10346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Zurich /etc/localtime May 27 10:41:28 volumio sudo[10346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:28 volumio sudo[10346]: pam_unix(sudo:session): session closed for user root May 27 10:41:28 volumio sudo[10351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 27 10:41:28 volumio sudo[10351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:28 volumio sudo[10351]: pam_unix(sudo:session): session closed for user root May 27 10:41:28 volumio sudo[10355]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Zurich May 27 10:41:28 volumio sudo[10355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:28 volumio sudo[10355]: pam_unix(sudo:session): session closed for user root May 27 10:41:28 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 27 10:41:28 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 27 10:41:28 volumio sudo[10360]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 27 10:41:28 volumio sudo[10360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:28 volumio volumio[928]: Updating browse sources language May 27 10:41:28 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 10:41:28 volumio volumio[928]: info: Fetching Streaming Services browse cache May 27 10:41:32 volumio sudo[10360]: pam_unix(sudo:session): session closed for user root May 27 10:41:34 volumio sudo[10379]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 10:41:34 volumio sudo[10379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:34 volumio sudo[10379]: pam_unix(sudo:session): session closed for user root May 27 10:41:34 volumio sudo[10381]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 10:41:34 volumio sudo[10381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:41:34 volumio sudo[10381]: pam_unix(sudo:session): session closed for user root May 27 10:41:34 volumio volumio[928]: verbose: New Socket.io Connection to 192.168.7.70 from 192.168.7.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 31 May 27 10:41:34 volumio volumio[928]: info: CoreCommandRouter::volumioGetVisibleSources May 27 10:41:34 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 10:41:35 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 27 10:41:35 volumio volumio[928]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 27 10:41:35 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 27 10:41:35 volumio volumio[928]: info: Received Get System Version May 27 10:41:35 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 10:41:35 volumio volumio[928]: info: Received Get System Info May 27 10:41:35 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 10:41:35 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 10:41:35 volumio volumio[928]: info: Discovery: Getting this device information May 27 10:41:35 volumio volumio[928]: info: CoreCommandRouter::volumioGetState May 27 10:41:35 volumio volumio[928]: info: CorePlayQueue::getTrack 0 May 27 10:41:35 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 10:41:48 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 27 10:41:48 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 27 10:41:48 volumio volumio-remote-updater[577]: No test mode May 27 10:41:48 volumio volumio-remote-updater[577]: No alpha test mode May 27 10:41:48 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 27 10:41:49 volumio volumio[928]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 27 10:41:49 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 27 10:41:58 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 27 10:41:58 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 27 10:41:58 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 27 10:41:58 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 27 10:41:58 volumio volumio-remote-updater[577]: No test mode May 27 10:41:58 volumio volumio-remote-updater[577]: No alpha test mode May 27 10:41:58 volumio volumio[928]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 27 10:41:58 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 27 10:42:05 volumio sudo[10432]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 10:42:05 volumio sudo[10432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:06 volumio sudo[10434]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 10:42:06 volumio sudo[10434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:06 volumio sudo[10432]: pam_unix(sudo:session): session closed for user root May 27 10:42:06 volumio sudo[10434]: pam_unix(sudo:session): session closed for user root May 27 10:42:06 volumio volumio[928]: verbose: New Socket.io Connection to 192.168.7.70 from 192.168.7.64 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::volumioGetVisibleSources May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 27 10:42:06 volumio volumio[928]: info: Received Get System Info May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 10:42:06 volumio volumio[928]: info: Discovery: Getting this device information May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::volumioGetState May 27 10:42:06 volumio volumio[928]: info: CorePlayQueue::getTrack 0 May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 10:42:06 volumio volumio-remote-updater[577]: No test mode May 27 10:42:06 volumio volumio-remote-updater[577]: No alpha test mode May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:42:06 volumio volumio[928]: info: Retrieving Cloud Streaming UI May 27 10:42:06 volumio volumio[928]: info: Getting Tidal Cloud Configuration May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:42:06 volumio volumio[928]: info: Getting Qobuz Cloud Configuration May 27 10:42:06 volumio volumio[928]: info: Asking plugin for UI Config May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:42:06 volumio volumio[928]: info: Getting Spotify Cloud Configuration May 27 10:42:06 volumio volumio[928]: info: Asking plugin for UI Config May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:42:06 volumio volumio[928]: info: Saving Spotify Acccount May 27 10:42:06 volumio volumio[928]: info: Got Tidal Cloud Configuration May 27 10:42:06 volumio volumio[928]: info: Got it May 27 10:42:06 volumio volumio[928]: info: Got it May 27 10:42:06 volumio volumio[928]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:42:06 volumio volumio[928]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 27 10:42:06 volumio volumio[928]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 27 10:42:06 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 27 10:42:07 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 27 10:42:07 volumio volumio[928]: info: Loading i18n strings for locale de May 27 10:42:07 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 27 10:42:07 volumio volumio[928]: info: Setting timezone to Europe/Zurich May 27 10:42:07 volumio sudo[10444]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 27 10:42:07 volumio sudo[10444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:07 volumio sudo[10444]: pam_unix(sudo:session): session closed for user root May 27 10:42:07 volumio sudo[10448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Zurich /etc/localtime May 27 10:42:07 volumio sudo[10448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:07 volumio sudo[10448]: pam_unix(sudo:session): session closed for user root May 27 10:42:07 volumio sudo[10452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 27 10:42:07 volumio sudo[10452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:07 volumio sudo[10452]: pam_unix(sudo:session): session closed for user root May 27 10:42:07 volumio sudo[10457]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Zurich May 27 10:42:07 volumio sudo[10457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:07 volumio dbus-daemon[563]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.155' (uid=0 pid=10458 comm="/usr/bin/timedatectl set-timezone Europe/Zurich") May 27 10:42:07 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 27 10:42:08 volumio dbus-daemon[563]: [system] Successfully activated service 'org.freedesktop.timedate1' May 27 10:42:08 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 27 10:42:08 volumio sudo[10457]: pam_unix(sudo:session): session closed for user root May 27 10:42:08 volumio volumio[928]: info: Fetching Streaming Services browse cache May 27 10:42:08 volumio volumio[928]: Updating browse sources language May 27 10:42:08 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 10:42:08 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 27 10:42:08 volumio volumio[928]: info: MyVolumio login type: Token May 27 10:42:09 volumio volumio[928]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 27 10:42:10 volumio volumio[928]: info: MyVolumio token set successfully May 27 10:42:10 volumio volumio[928]: info: MYVOLUMIO: Adding device May 27 10:42:10 volumio volumio[928]: info: MYVOLUMIO: Evaluating Server May 27 10:42:10 volumio volumio[928]: info: MyVolumio Plan changed: premium May 27 10:42:10 volumio volumio[928]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 27 10:42:10 volumio volumio[928]: info: Removing browser output: myVolumio user plan is not superstar May 27 10:42:10 volumio volumio[928]: info: Removing audio output: May 27 10:42:10 volumio volumio[928]: info: MYVOLUMIO: Adding device May 27 10:42:10 volumio volumio[928]: info: MYVOLUMIO: Evaluating Server May 27 10:42:10 volumio volumio[928]: info: Remote config written successfully May 27 10:42:10 volumio volumio[928]: info: Starting Tunnel 1 May 27 10:42:10 volumio volumio[928]: info: Starting Tunnel Connection Checker May 27 10:42:10 volumio volumio[928]: info: MYVolumio Device enabled May 27 10:42:10 volumio volumio[928]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 27 10:42:10 volumio volumio[928]: info: MyVolumio status changed May 27 10:42:10 volumio volumio[928]: info: Streaming services startup May 27 10:42:10 volumio volumio[928]: info: Starting Streaming Daemon May 27 10:42:11 volumio sudo[10508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 27 10:42:11 volumio sudo[10508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:11 volumio volumio[928]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' May 27 10:42:11 volumio sudo[10508]: pam_unix(sudo:session): session closed for user root May 27 10:42:11 volumio volumio[928]: error: Cannot start Volumio Streaming Daemon May 27 10:42:11 volumio volumio[928]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 27 10:42:11 volumio volumio[928]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 27 10:42:11 volumio volumio[928]: info: Setting Geolocation for MyVolumio to eu6 May 27 10:42:11 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:11 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:11 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:11 volumio volumio[928]: info: Setting Geolocation for MyVolumio to eu7 May 27 10:42:11 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:11 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:11 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:11 volumio volumio[928]: info: Successfully Added MyVolumio device May 27 10:42:11 volumio volumio[928]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! May 27 10:42:11 volumio volumio[928]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 27 10:42:11 volumio volumio[928]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart May 27 10:42:11 volumio volumio[928]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 27 10:42:13 volumio sudo[10513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 27 10:42:13 volumio sudo[10513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:13 volumio sudo[10513]: pam_unix(sudo:session): session closed for user root May 27 10:42:13 volumio volumio[928]: info: MRS: MultiRoom plugin initialized May 27 10:42:13 volumio volumio[928]: info: MRS: STOPPING SNAPCLIENT May 27 10:42:13 volumio volumio[928]: info: MRS: Snap server stop May 27 10:42:13 volumio volumio[928]: info: MRS: STOPPING volumioStreaming May 27 10:42:13 volumio sudo[10530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 27 10:42:13 volumio sudo[10530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:13 volumio sudo[10532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 27 10:42:13 volumio volumio[928]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 27 10:42:13 volumio sudo[10532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:13 volumio sudo[10534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 27 10:42:13 volumio sudo[10534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:13 volumio sudo[10538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 27 10:42:13 volumio sudo[10538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:13 volumio sudo[10538]: pam_unix(sudo:session): session closed for user root May 27 10:42:13 volumio volumio[928]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 27 10:42:13 volumio volumio[928]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 27 10:42:13 volumio volumio[928]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 27 10:42:13 volumio volumio[928]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 27 10:42:13 volumio sudo[10532]: pam_unix(sudo:session): session closed for user root May 27 10:42:13 volumio sudo[10530]: pam_unix(sudo:session): session closed for user root May 27 10:42:13 volumio volumio[928]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 27 10:42:13 volumio sudo[10534]: pam_unix(sudo:session): session closed for user root May 27 10:42:13 volumio volumio[928]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 27 10:42:13 volumio volumio[928]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 27 10:42:13 volumio volumio[928]: info: Preparing to generate the ALSA configuration file May 27 10:42:13 volumio volumio[928]: info: Updating MyVolumio device info May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:13 volumio volumio[928]: info: Updating MyVolumio device info May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 27 10:42:13 volumio volumio[928]: info: Successfully Added MyVolumio device May 27 10:42:13 volumio volumio[928]: info: MRS: Removed streaming files May 27 10:42:13 volumio volumio[928]: info: MRS: volumioStreaming STOPPED May 27 10:42:13 volumio volumio[928]: info: MRS: SNAPSERVER STOPPED May 27 10:42:13 volumio volumio[928]: info: MRS: SNAPCLIENT STOPPED May 27 10:42:13 volumio volumio[928]: info: Asound.conf file unchanged, so no further update is needed May 27 10:42:13 volumio volumio[928]: info: Output device has changed, restarting MPD May 27 10:42:13 volumio volumio[928]: info: Output device has changed, restarting Shairport Sync May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:13 volumio sudo[10543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 10:42:13 volumio sudo[10543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:13 volumio sudo[10545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 10:42:13 volumio sudo[10545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:13 volumio sudo[10543]: pam_unix(sudo:session): session closed for user root May 27 10:42:13 volumio volumio[928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 10:42:13 volumio volumio[928]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:13 volumio volumio[928]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 27 10:42:13 volumio volumio[928]: ------------------------------------ BT MESSAGE: [FUNC] onStart May 27 10:42:13 volumio volumio[928]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service May 27 10:42:13 volumio volumio[928]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp May 27 10:42:13 volumio volumio[928]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ May 27 10:42:13 volumio volumio[928]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. May 27 10:42:13 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 27 10:42:13 volumio volumio[928]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 27 10:42:13 volumio volumio[928]: info: MRS: Pushing multiroomSync output for this device May 27 10:42:13 volumio volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:13 volumio volumio[928]: info: Adding audio output: May 27 10:42:13 volumio volumio[928]: info: Adding audio output: May 27 10:42:13 volumio volumio[928]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 10:42:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:13 volumio volumio[928]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 27 10:42:14 volumio volumio[928]: info: Adding METAVOLUMIO REST API Endpoints May 27 10:42:14 volumio volumio[928]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 27 10:42:14 volumio volumio[928]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 27 10:42:14 volumio volumio[928]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 27 10:42:14 volumio volumio[928]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 27 10:42:14 volumio volumio[928]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 27 10:42:14 volumio volumio[928]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 27 10:42:14 volumio volumio[928]: info: Preparing CD Folders May 27 10:42:14 volumio volumio[928]: info: Adding CD REST API Endpoints May 27 10:42:14 volumio volumio[928]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 27 10:42:14 volumio volumio[928]: info: Starting UDEV Watcher for CD May 27 10:42:14 volumio volumio[928]: info: Detecting CD presence with UDEV May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 27 10:42:14 volumio volumio[928]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 27 10:42:14 volumio volumio[928]: info: Adding inputs REST Endpoints May 27 10:42:14 volumio volumio[928]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 27 10:42:14 volumio volumio[928]: info: Scanning Audio Inputs May 27 10:42:14 volumio bluetoothd[700]: Adv Monitor app :1.159 disconnected from D-Bus May 27 10:42:14 volumio volumio[928]: info: Checking against Known Cards name May 27 10:42:14 volumio volumio[928]: info: Adding Server instance for streaming May 27 10:42:14 volumio volumio[928]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 27 10:42:14 volumio volumio[928]: error: Hi Res Audio Failed Login: Missing Login Data May 27 10:42:14 volumio volumio[928]: info: Adding HIGHRESAUDIO REST API Endpoints May 27 10:42:14 volumio volumio[928]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 27 10:42:14 volumio volumio[928]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 27 10:42:14 volumio volumio[928]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 27 10:42:14 volumio volumio[928]: info: Stopping AccessToken refresher cron May 27 10:42:14 volumio sudo[10563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 27 10:42:14 volumio sudo[10563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:14 volumio volumio[928]: info: AccessToken refresher cron started May 27 10:42:14 volumio volumio[928]: info: Stopping AccessToken refresher cron for QOBUZ May 27 10:42:14 volumio volumio[928]: info: AccessToken refresher cron started for QOBUZ May 27 10:42:14 volumio volumio[928]: info: Adding TIDAL REST API Endpoints May 27 10:42:14 volumio volumio[928]: info: Adding QOBUZ REST API Endpoints May 27 10:42:14 volumio sudo[10563]: pam_unix(sudo:session): session closed for user root May 27 10:42:14 volumio volumio[928]: info: MRS: Adding multiroomSync output May 27 10:42:14 volumio volumio[928]: info: Adding audio output: May 27 10:42:14 volumio volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:14 volumio volumio[928]: info: Successfully Updated MyVolumio device May 27 10:42:14 volumio volumio[928]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on May 27 10:42:14 volumio volumio[928]: info: MPD Permissions set May 27 10:42:14 volumio systemd[1]: mpd.service: Deactivated successfully. May 27 10:42:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 27 10:42:14 volumio systemd[1]: mpd.service: Consumed 5.188s CPU time. May 27 10:42:14 volumio systemd[1]: mpd.socket: Deactivated successfully. May 27 10:42:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 27 10:42:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 27 10:42:14 volumio sudo[10567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 27 10:42:14 volumio sudo[10567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: Starting Shairport Sync May 27 10:42:14 volumio volumio[928]: info: Preparing to generate the ALSA configuration file May 27 10:42:14 volumio volumio[928]: info: Asound.conf file unchanged, so no further update is needed May 27 10:42:14 volumio volumio[928]: info: Output device has changed, restarting MPD May 27 10:42:14 volumio sudo[10577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 10:42:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 27 10:42:14 volumio sudo[10577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 27 10:42:14 volumio volumio[928]: info: Output device has changed, restarting Shairport Sync May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:14 volumio sudo[10582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 10:42:14 volumio sudo[10582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:14 volumio sudo[10585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 10:42:14 volumio sudo[10582]: pam_unix(sudo:session): session closed for user root May 27 10:42:14 volumio sudo[10585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:14 volumio volumio[928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 10:42:14 volumio volumio[928]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: Preparing to generate the ALSA configuration file May 27 10:42:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 27 10:42:14 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. May 27 10:42:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 27 10:42:14 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:14 volumio systemd[1]: shairport-sync.service: Consumed 19.107s CPU time. May 27 10:42:14 volumio volumiobt[10594]: INFO [BTSTART] Ensuring Bluetooth directory exists... May 27 10:42:14 volumio sudo[10567]: pam_unix(sudo:session): session closed for user root May 27 10:42:14 volumio sudo[10595]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth May 27 10:42:14 volumio sudo[10595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:14 volumio volumio[928]: info: MPD Permissions set May 27 10:42:14 volumio sudo[10595]: pam_unix(sudo:session): session closed for user root May 27 10:42:14 volumio volumio[928]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::volumioStop May 27 10:42:14 volumio volumio[928]: info: CoreStateMachine::stop May 27 10:42:14 volumio volumio[928]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 10:42:14 volumio volumio[928]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart May 27 10:42:14 volumio volumio[928]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio sudo[10597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio sudo[10597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio sudo[10597]: pam_unix(sudo:session): session closed for user root May 27 10:42:14 volumio volumiobt[10602]: INFO [BTSTART] Powering on Bluetooth if needed... May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:14 volumio volumio[928]: info: Asound.conf file unchanged, so no further update is needed May 27 10:42:14 volumio volumio[928]: info: Output device has changed, restarting MPD May 27 10:42:14 volumio sudo[10577]: pam_unix(sudo:session): session closed for user root May 27 10:42:14 volumio systemd[1]: mpd.service: Deactivated successfully. May 27 10:42:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 27 10:42:14 volumio systemd[1]: mpd.socket: Deactivated successfully. May 27 10:42:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 27 10:42:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 27 10:42:14 volumio volumio[928]: info: Output device has changed, restarting Shairport Sync May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 27 10:42:14 volumio sudo[10609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 10:42:14 volumio sudo[10609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:14 volumio sudo[10609]: pam_unix(sudo:session): session closed for user root May 27 10:42:14 volumio sudo[10611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 10:42:14 volumio sudo[10611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:14 volumio volumio[928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 10:42:14 volumio volumio[928]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio bluetoothd[700]: Adv Monitor app :1.160 disconnected from D-Bus May 27 10:42:14 volumio volumiobt[10634]: INFO [BTSTART] Making Bluetooth discoverable and pairable... May 27 10:42:14 volumio systemd[1]: mpd.service: Deactivated successfully. May 27 10:42:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 27 10:42:14 volumio volumio[928]: info: MPD Permissions set May 27 10:42:14 volumio volumio[928]: info: Shairport-Sync Started May 27 10:42:14 volumio systemd[1]: mpd.socket: Deactivated successfully. May 27 10:42:14 volumio volumio[928]: info: Starting Shairport Sync May 27 10:42:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 27 10:42:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio sudo[10640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 10:42:14 volumio sudo[10640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:14 volumio volumiobt[10636]: [75B blob data] May 27 10:42:14 volumio volumiobt[10636]: [bluetooth]# pairable on May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:14 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 27 10:42:14 volumio bluetoothd[700]: Adv Monitor app :1.163 disconnected from D-Bus May 27 10:42:14 volumio volumiobt[10636]: [bluetooth]# May 27 10:42:14 volumio volumiobt[10650]: INFO [BTSTART] Registering Bluetooth agent... May 27 10:42:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 27 10:42:14 volumio volumio[928]: info: Starting Shairport Sync May 27 10:42:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 27 10:42:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 27 10:42:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:15 volumio sudo[10654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 10:42:15 volumio sudo[10654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:15 volumio sudo[10640]: pam_unix(sudo:session): session closed for user root May 27 10:42:15 volumio bluetoothd[700]: Adv Monitor app :1.164 disconnected from D-Bus May 27 10:42:15 volumio volumio[928]: info: Shairport-Sync Started May 27 10:42:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 27 10:42:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 27 10:42:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:15 volumio sudo[10654]: pam_unix(sudo:session): session closed for user root May 27 10:42:15 volumio volumio[928]: info: Shairport-Sync Started May 27 10:42:15 volumio volumiobt[10657]: No agent is registered May 27 10:42:15 volumio bluetoothd[700]: Adv Monitor app :1.165 disconnected from D-Bus May 27 10:42:15 volumio volumiobt[10659]: INFO [BTSTART] Agent registered successfully. May 27 10:42:15 volumio volumiobt[10660]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... May 27 10:42:15 volumio volumio[928]: info: Successfully Updated MyVolumio device May 27 10:42:15 volumio sudo[10651]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 27 10:42:15 volumio sudo[10651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 10:42:15 volumio sudo[10651]: pam_unix(sudo:session): session closed for user root May 27 10:42:15 volumio sudo[10685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 27 10:42:15 volumio sudo[10685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:15 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 27 10:42:15 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 27 10:42:16 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. May 27 10:42:16 volumio sudo[10685]: pam_unix(sudo:session): session closed for user root May 27 10:42:16 volumio volumio[928]: info: Remote SSH Started May 27 10:42:16 volumio autossh[10688]: port set to 0, monitoring disabled May 27 10:42:16 volumio autossh[10688]: starting ssh (count 1) May 27 10:42:16 volumio autossh[10688]: ssh child pid is 10691 May 27 10:42:16 volumio volumio[928]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 27 10:42:16 volumio volumio[928]: info: CoreCommandRouter::volumioGetState May 27 10:42:16 volumio volumiossh-tunnel[10691]: Warning: Permanently added '[eu7.myvolumio.org]:2222' (RSA) to the list of known hosts. May 27 10:42:16 volumio volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:16 volumio volumiobt[10661]: INFO:a2dp-agent:Connecting to system bus. May 27 10:42:16 volumio volumiobt[10661]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 May 27 10:42:16 volumio volumiobt[10661]: INFO:a2dp-agent:Enabling infinite discovery on adapter. May 27 10:42:16 volumio volumiobt[10661]: INFO:a2dp-agent:Registering agent. May 27 10:42:16 volumio volumiobt[10661]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... May 27 10:42:16 volumio volumiobt[10661]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. May 27 10:42:17 volumio volumio[928]: info: TidalConnect service stoped! May 27 10:42:17 volumio volumio[928]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:17 volumio volumio[928]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:17 volumio sudo[10701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 27 10:42:17 volumio sudo[10701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:17 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 27 10:42:17 volumio sudo[10701]: pam_unix(sudo:session): session closed for user root May 27 10:42:17 volumio volumio[928]: info: Executing endpoint tc_getconfig May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 27 10:42:17 volumio vtcs[10704]: STARTING TidalConnect services, version: 1.5.0.45 May 27 10:42:17 volumio vtcs[10704]: STARTED TidalConnect services. May 27 10:42:17 volumio volumio[928]: info: Executing endpoint tc_connect May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 27 10:42:17 volumio volumio[928]: info: Connecting to TidalConnect May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::servicePushState May 27 10:42:17 volumio volumio[928]: info: CoreStateMachine::pushState May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:17 volumio volumio[928]: info: CorePlayQueue::getTrack 0 May 27 10:42:17 volumio volumio[928]: 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} May 27 10:42:17 volumio volumio[928]: verbose: CURRENT POSITION 0 May 27 10:42:17 volumio volumio[928]: info: CoreStateMachine::syncState stateService stop May 27 10:42:17 volumio volumio[928]: info: CoreStateMachine::syncState currentStatus stop May 27 10:42:17 volumio volumio[928]: info: CoreStateMachine::pushState May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:17 volumio volumio[928]: info: No code May 27 10:42:17 volumio volumio[928]: info: CoreStateMachine::pushState May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::servicePushState May 27 10:42:17 volumio volumio[928]: info: CoreStateMachine::pushState May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:17 volumio volumio[928]: info: CorePlayQueue::getTrack 0 May 27 10:42:17 volumio volumio[928]: 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} May 27 10:42:17 volumio volumio[928]: verbose: CURRENT POSITION 0 May 27 10:42:17 volumio volumio[928]: info: CoreStateMachine::syncState stateService stop May 27 10:42:17 volumio volumio[928]: info: CoreStateMachine::syncState currentStatus stop May 27 10:42:17 volumio volumio[928]: info: CoreStateMachine::pushState May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:17 volumio volumio[928]: info: No code May 27 10:42:17 volumio volumio[928]: info: CoreStateMachine::pushState May 27 10:42:17 volumio volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:17 volumio volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:18 volumio volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:18 volumio volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:18 volumio volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:18 volumio volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:18 volumio mpd[10677]: 2025-05-27T10:42:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 27 10:42:18 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 27 10:42:18 volumio sudo[10585]: pam_unix(sudo:session): session closed for user root May 27 10:42:18 volumio sudo[10611]: pam_unix(sudo:session): session closed for user root May 27 10:42:18 volumio sudo[10545]: pam_unix(sudo:session): session closed for user root May 27 10:42:18 volumio volumio[928]: error: MPD error: The expression evaluated to a falsy value: May 27 10:42:18 volumio volumio[928]: assert.ok(self.idling) May 27 10:42:18 volumio volumio[928]: error: The expression evaluated to a falsy value: May 27 10:42:18 volumio volumio[928]: assert.ok(self.idling) May 27 10:42:18 volumio volumio[928]: error: MPD error: The expression evaluated to a falsy value: May 27 10:42:18 volumio volumio[928]: assert.ok(self.idling) May 27 10:42:18 volumio volumio[928]: error: The expression evaluated to a falsy value: May 27 10:42:18 volumio volumio[928]: assert.ok(self.idling) May 27 10:42:18 volumio volumio[928]: error: updateQueue error: null May 27 10:42:18 volumio volumio[928]: info: MRS: Getting audio outputs on start May 27 10:42:18 volumio volumio[928]: info: MRS: Requesting all other devices output May 27 10:42:20 volumio volumio[928]: info: TidalConnect service started! May 27 10:42:22 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 27 10:42:22 volumio volumio[928]: info: System name has changed, restarting Shairport Sync May 27 10:42:22 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:22 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:22 volumio volumio[928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 10:42:22 volumio volumio[928]: info: Updating MyVolumio device info May 27 10:42:22 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:22 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:22 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:22 volumio vtcs[10704]: [2025-05-27 10:42:22.800] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 27 10:42:22 volumio vtcs[10704]: [2025-05-27 10:42:22.800] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe May 27 10:42:22 volumio vtcs[10704]: [2025-05-27 10:42:22.801] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected May 27 10:42:22 volumio volumio[928]: info: Discovery: Restarting Advertising due to device name change May 27 10:42:22 volumio sudo[10741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 27 10:42:22 volumio sudo[10741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:22 volumio sudo[10743]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 27 10:42:22 volumio sudo[10743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:22 volumio sudo[10743]: pam_unix(sudo:session): session closed for user root May 27 10:42:22 volumio sudo[10746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 27 10:42:22 volumio sudo[10746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:22 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices May 27 10:42:22 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 27 10:42:22 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:22 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 10:42:22 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 27 10:42:22 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 27 10:42:22 volumio volumio[928]: info: Starting Shairport Sync May 27 10:42:22 volumio systemd[1]: vtcs.service: Deactivated successfully. May 27 10:42:22 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 27 10:42:22 volumio sudo[10746]: pam_unix(sudo:session): session closed for user root May 27 10:42:23 volumio sudo[10741]: pam_unix(sudo:session): session closed for user root May 27 10:42:23 volumio sudo[10757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts May 27 10:42:23 volumio setdatetime-helper.sh[10749]: Time is not synchronized. Attempting to sync... May 27 10:42:23 volumio sudo[10757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:23 volumio sudo[10758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 10:42:23 volumio sudo[10758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:23 volumio sudo[10757]: pam_unix(sudo:session): session closed for user root May 27 10:42:23 volumio volumio[928]: info: Permissions for /etc/hosts set May 27 10:42:23 volumio volumio[928]: info: Hostname now is volumio-master May 27 10:42:23 volumio sudo[10768]: volumio : unable to resolve host volumio: System error May 27 10:42:23 volumio sudo[10768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio-master May 27 10:42:23 volumio sudo[10768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:23 volumio-master sudo[10768]: pam_unix(sudo:session): session closed for user root May 27 10:42:23 volumio-master volumio[928]: info: New hostname set May 27 10:42:23 volumio-master systemd[1]: Starting fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels... May 27 10:42:23 volumio-master sudo[10772]: volumio : unable to resolve host volumio: System error May 27 10:42:23 volumio-master sudo[10772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ May 27 10:42:23 volumio-master sudo[10772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:23 volumio-master systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 27 10:42:23 volumio-master systemd[1]: shairport-sync.service: Deactivated successfully. May 27 10:42:23 volumio-master systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:23 volumio-master systemd[1]: shairport-sync.service: Consumed 1.552s CPU time. May 27 10:42:23 volumio-master systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:23 volumio-master sudo[10758]: pam_unix(sudo:session): session closed for user root May 27 10:42:23 volumio-master volumio[928]: info: Shairport-Sync Started May 27 10:42:23 volumio-master systemd[1]: fix-alpha-hosts.service: Deactivated successfully. May 27 10:42:23 volumio-master systemd[1]: Finished fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels. May 27 10:42:23 volumio-master sudo[10779]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 27 May 2025 08:42:23 GMT#015' May 27 10:42:23 volumio-master sudo[10772]: pam_unix(sudo:session): session closed for user root May 27 10:42:23 volumio-master sudo[10779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 10:42:23 volumio-master setdatetime-helper.sh[10781]: Tue May 27 10:42:23 CEST 2025 May 27 10:42:23 volumio-master systemd-journald[318]: Time jumped backwards, rotating. May 27 10:42:23 volumio-master volumio[928]: info: Permissions for /etc/avahi/services/volumio.service May 27 10:42:23 volumio-master sudo[10779]: pam_unix(sudo:session): session closed for user root May 27 10:42:23 volumio-master setdatetime-helper.sh[10749]: Time synchronized successfully. May 27 10:42:23 volumio-master systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 27 10:42:23 volumio-master systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 27 10:42:23 volumio-master volumio[928]: info: Avahi name changed to volumio-master May 27 10:42:23 volumio-master avahi-daemon[556]: Files changed, reloading. May 27 10:42:23 volumio-master avahi-daemon[556]: Loading service file /services/volumio.service. May 27 10:42:23 volumio-master volumio[928]: info: Successfully Updated MyVolumio device May 27 10:42:23 volumio-master avahi-daemon[556]: Service "Volumio Master" (/services/volumio.service) successfully established. May 27 10:42:23 volumio-master volumio[928]: info: Discovery: A device disappeared from network May 27 10:42:23 volumio-master volumio[928]: info: Discovery: Device volumio disappeared from network May 27 10:42:23 volumio-master volumio[928]: info: Discovery: A device disappeared from network May 27 10:42:23 volumio-master volumio[928]: info: Discovery: A device disappeared from network May 27 10:42:25 volumio-master volumio[928]: info: TidalConnect service stoped! May 27 10:42:25 volumio-master volumio[928]: info: TidalConnect service stoped! May 27 10:42:25 volumio-master volumio[928]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:25 volumio-master volumio[928]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:25 volumio-master sudo[10806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 27 10:42:25 volumio-master sudo[10806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:25 volumio-master systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 27 10:42:25 volumio-master sudo[10806]: pam_unix(sudo:session): session closed for user root May 27 10:42:26 volumio-master volumio[928]: info: Executing endpoint tc_getconfig May 27 10:42:26 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 27 10:42:26 volumio-master vtcs[10809]: STARTING TidalConnect services, version: 1.5.0.45 May 27 10:42:26 volumio-master vtcs[10809]: STARTED TidalConnect services. May 27 10:42:26 volumio-master volumio[928]: info: Executing endpoint tc_connect May 27 10:42:26 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 27 10:42:26 volumio-master volumio[928]: info: Connecting to TidalConnect May 27 10:42:26 volumio-master volumio[928]: info: CoreCommandRouter::servicePushState May 27 10:42:26 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:26 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:26 volumio-master volumio[928]: info: CorePlayQueue::getTrack 0 May 27 10:42:26 volumio-master volumio[928]: 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} May 27 10:42:26 volumio-master volumio[928]: verbose: CURRENT POSITION 0 May 27 10:42:26 volumio-master volumio[928]: info: CoreStateMachine::syncState stateService stop May 27 10:42:26 volumio-master volumio[928]: info: CoreStateMachine::syncState currentStatus stop May 27 10:42:26 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:26 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:26 volumio-master volumio[928]: info: No code May 27 10:42:26 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:26 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:26 volumio-master volumio[928]: info: CoreCommandRouter::servicePushState May 27 10:42:26 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:26 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:26 volumio-master volumio[928]: info: CorePlayQueue::getTrack 0 May 27 10:42:26 volumio-master volumio[928]: 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} May 27 10:42:26 volumio-master volumio[928]: verbose: CURRENT POSITION 0 May 27 10:42:26 volumio-master volumio[928]: info: CoreStateMachine::syncState stateService stop May 27 10:42:26 volumio-master volumio[928]: info: CoreStateMachine::syncState currentStatus stop May 27 10:42:26 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:26 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:26 volumio-master volumio[928]: info: No code May 27 10:42:26 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:26 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:26 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:26 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:26 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:26 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:26 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:26 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:26 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:27 volumio-master volumio[928]: info: Discovery: Started advertising with name: Volumio Master May 27 10:42:28 volumio-master volumio[928]: info: Discovery: adding 0bb8bd0a-66e3-41de-8ea2-33a1ef34ff43 May 27 10:42:28 volumio-master volumio[928]: info: Discovery: Found device Volumio Master May 27 10:42:28 volumio-master volumio[928]: info: CoreCommandRouter::volumioGetState May 27 10:42:28 volumio-master volumio[928]: info: Discovery: this is already registered, 0bb8bd0a-66e3-41de-8ea2-33a1ef34ff43 May 27 10:42:28 volumio-master volumio[928]: info: Discovery: Found device Volumio Master May 27 10:42:28 volumio-master volumio[928]: info: CoreCommandRouter::volumioGetState May 27 10:42:28 volumio-master volumio[928]: info: Discovery: this is already registered, 0bb8bd0a-66e3-41de-8ea2-33a1ef34ff43 May 27 10:42:28 volumio-master volumio[928]: info: Discovery: Found device Volumio Master May 27 10:42:28 volumio-master volumio[928]: info: CoreCommandRouter::volumioGetState May 27 10:42:28 volumio-master volumio[928]: verbose: New Socket.io Connection to 192.168.7.70:3000 from 192.168.7.203 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 27 10:42:28 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 27 10:42:28 volumio-master volumio[928]: info: CoreCommandRouter::volumioGetState May 27 10:42:28 volumio-master volumio[928]: info: TidalConnect service started! May 27 10:42:32 volumio-master sudo[10845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf May 27 10:42:32 volumio-master sudo[10845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:32 volumio-master sudo[10847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL May 27 10:42:32 volumio-master sudo[10847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:32 volumio-master sudo[10847]: pam_unix(sudo:session): session closed for user root May 27 10:42:32 volumio-master sudo[10845]: pam_unix(sudo:session): session closed for user root May 27 10:42:32 volumio-master volumio[928]: info: Internal perms successfully set May 27 10:42:32 volumio-master volumio[928]: info: smb.conf Permissions set May 27 10:42:32 volumio-master sudo[10851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service May 27 10:42:32 volumio-master sudo[10851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:32 volumio-master nmbd[859]: [2025/05/27 10:42:32.727640, 0] ../../source3/nmbd/nmbd.c:59(terminate) May 27 10:42:32 volumio-master systemd[1]: Stopping nmbd.service - Samba NMB Daemon... May 27 10:42:32 volumio-master nmbd[859]: Got SIGTERM: going down... May 27 10:42:32 volumio-master sudo[10854]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 10:42:32 volumio-master sudo[10854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:32 volumio-master systemd[1]: nmbd.service: Deactivated successfully. May 27 10:42:32 volumio-master sudo[10856]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 10:42:32 volumio-master systemd[1]: Stopped nmbd.service - Samba NMB Daemon. May 27 10:42:32 volumio-master systemd[1]: nmbd.service: Consumed 2.593s CPU time. May 27 10:42:32 volumio-master sudo[10856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:32 volumio-master sudo[10854]: pam_unix(sudo:session): session closed for user root May 27 10:42:32 volumio-master sudo[10856]: pam_unix(sudo:session): session closed for user root May 27 10:42:32 volumio-master sudo[10861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 27 10:42:32 volumio-master sudo[10861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:32 volumio-master systemd[1]: Starting nmbd.service - Samba NMB Daemon... May 27 10:42:32 volumio-master sudo[10861]: pam_unix(sudo:session): session closed for user root May 27 10:42:32 volumio-master volumio[928]: info: Upmpdcli Daemon Started May 27 10:42:33 volumio-master nmbd[10866]: [2025/05/27 10:42:33.290032, 0] ../../source3/nmbd/nmbd.c:901(main) May 27 10:42:33 volumio-master nmbd[10866]: nmbd version 4.17.8-Raspbian started. May 27 10:42:33 volumio-master nmbd[10866]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 27 10:42:33 volumio-master nmbd[10866]: [2025/05/27 10:42:33.295244, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) May 27 10:42:33 volumio-master nmbd[10866]: started asyncdns process 10867 May 27 10:42:33 volumio-master systemd[1]: Started nmbd.service - Samba NMB Daemon. May 27 10:42:33 volumio-master sudo[10851]: pam_unix(sudo:session): session closed for user root May 27 10:42:33 volumio-master nmbd[10866]: [2025/05/27 10:42:33.318164, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 27 10:42:33 volumio-master nmbd[10866]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.7.70 for name WORKGROUP<1d>. May 27 10:42:33 volumio-master nmbd[10866]: This response was from IP 192.168.7.171, reporting an IP address of 192.168.7.71. May 27 10:42:33 volumio-master nmbd[10866]: [2025/05/27 10:42:33.318500, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 27 10:42:33 volumio-master nmbd[10866]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.7.70 for name WORKGROUP<1d>. May 27 10:42:33 volumio-master nmbd[10866]: This response was from IP 192.168.7.71, reporting an IP address of 192.168.7.71. May 27 10:42:33 volumio-master sudo[10869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service May 27 10:42:33 volumio-master sudo[10869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:33 volumio-master systemd[1]: Stopping smbd.service - Samba SMB Daemon... May 27 10:42:33 volumio-master systemd[1]: smbd.service: Deactivated successfully. May 27 10:42:33 volumio-master systemd[1]: Stopped smbd.service - Samba SMB Daemon. May 27 10:42:33 volumio-master systemd[1]: smbd.service: Consumed 3.618s CPU time. May 27 10:42:33 volumio-master systemd[1]: Starting smbd.service - Samba SMB Daemon... May 27 10:42:33 volumio-master smbd[10874]: [2025/05/27 10:42:33.799411, 0] ../../source3/smbd/server.c:1741(main) May 27 10:42:33 volumio-master smbd[10874]: smbd version 4.17.8-Raspbian started. May 27 10:42:33 volumio-master smbd[10874]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 27 10:42:33 volumio-master systemd[1]: Started smbd.service - Samba SMB Daemon. May 27 10:42:33 volumio-master sudo[10869]: pam_unix(sudo:session): session closed for user root May 27 10:42:33 volumio-master volumio[928]: info: SAMBA Restarted May 27 10:42:37 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 27 10:42:37 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 27 10:42:38 volumio-master volumio-remote-updater[577]: No test mode May 27 10:42:38 volumio-master volumio-remote-updater[577]: Alpha test mode enabled May 27 10:42:38 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 27 10:42:38 volumio-master volumio[928]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

NEW ADDITIONS

\n\n

FIXES

\n\n","title":"Update v4.001","updateavailable":true} May 27 10:42:38 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 27 10:42:45 volumio-master volumio[928]: info: Preparing to save Alsa Options, stopping services first May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::volumioGetState May 27 10:42:45 volumio-master volumio[928]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"hifiberry-dacplus","label":"HiFiBerry DAC Plus"},"output_device":{"value":1,"label":"HiFiBerry DAC Plus"},"disallowPush":true} May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:45 volumio-master volumio[928]: info: Enabling I2S DAC: HiFiBerry DAC Plus May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:45 volumio-master sudo[10895]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 27 10:42:45 volumio-master sudo[10895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:45 volumio-master sudo[10895]: pam_unix(sudo:session): session closed for user root May 27 10:42:45 volumio-master volumio[928]: info: No Overlays Loaded May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:45 volumio-master sudo[10898]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus May 27 10:42:45 volumio-master sudo[10898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:45 volumio-master volumio[928]: warn: Unable to locate the device HiFiBerry DAC Plus a reboot may be required. May 27 10:42:45 volumio-master kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status May 27 10:42:45 volumio-master kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status May 27 10:42:45 volumio-master kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible May 27 10:42:45 volumio-master kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller May 27 10:42:45 volumio-master kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status May 27 10:42:45 volumio-master sudo[10898]: pam_unix(sudo:session): session closed for user root May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:45 volumio-master volumio[928]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC Plus May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 27 10:42:45 volumio-master volumio[928]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 27 10:42:45 volumio-master volumio[928]: info: Disabling external Volume Control May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 27 10:42:45 volumio-master volumio[928]: info: Preparing to generate the ALSA configuration file May 27 10:42:45 volumio-master kernel: snd-rpi-hifiberry-dacplus soc:sound: activating headphone amplifier May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 27 10:42:45 volumio-master volumio[928]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 27 10:42:45 volumio-master volumio[928]: info: Reading ALSA contributions from plugins. May 27 10:42:45 volumio-master volumio[928]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. May 27 10:42:45 volumio-master volumio[928]: info: I2S Param [object Object] successfully enabled May 27 10:42:45 volumio-master kernel: tpa6130a2 1-0060: supply Vdd not found, using dummy regulator May 27 10:42:45 volumio-master sudo[10936]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 27 10:42:45 volumio-master sudo[10938]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 27 10:42:45 volumio-master sudo[10938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:45 volumio-master sudo[10936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:45 volumio-master sudo[10938]: pam_unix(sudo:session): session closed for user root May 27 10:42:45 volumio-master volumio[928]: info: VolumeController:: Volume=undefined Mute =false May 27 10:42:45 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:45 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:45 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:45 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:45 volumio-master (udev-worker)[10912]: controlC2: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 2' failed with exit code 99. May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 10:42:46 volumio-master volumio[928]: info: Getting Alsa Cards List without I2S DAC May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 27 10:42:46 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:46 volumio-master volumio[928]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 27 10:42:46 volumio-master volumio[928]: wlan0 Interface doesn't support scanning : Device or resource busy May 27 10:42:46 volumio-master volumio[928]: info: Cannot use regular scanning, forcing with ap-force May 27 10:42:46 volumio-master sudo[10972]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 27 10:42:46 volumio-master sudo[10972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master sudo[10972]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master volumio[928]: command failed: Device or resource busy (-16) May 27 10:42:46 volumio-master volumio[928]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 27 10:42:46 volumio-master volumio[928]: command failed: Device or resource busy (-16) May 27 10:42:46 volumio-master volumio[928]: info: Asound.conf file written May 27 10:42:46 volumio-master sudo[10976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 27 10:42:46 volumio-master sudo[10976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master sudo[10976]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: No state is present for card b1 May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: /usr/share/alsa/init/bcm2835:14: control element not found May 27 10:42:46 volumio-master volumio[928]: No state is present for card b1 May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: No state is present for card Headphones May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: /usr/share/alsa/init/bcm2835:20: control element not found May 27 10:42:46 volumio-master volumio[928]: No state is present for card Headphones May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: No state is present for card sndrpihifiberry May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: Found hardware: "HifiberryDacp" "" "" "" "" May 27 10:42:46 volumio-master volumio[928]: Hardware is initialized using a generic method May 27 10:42:46 volumio-master volumio[928]: No state is present for card sndrpihifiberry May 27 10:42:46 volumio-master volumio[928]: info: Output device has changed, restarting MPD May 27 10:42:46 volumio-master volumio[928]: info: Output device has changed, restarting Shairport Sync May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:46 volumio-master sudo[10982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 10:42:46 volumio-master sudo[10982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master sudo[10984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 10:42:46 volumio-master sudo[10984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master sudo[10982]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master volumio[928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 10:42:46 volumio-master volumio[928]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master vtcs[10809]: [2025-05-27 10:42:46.286] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 27 10:42:46 volumio-master vtcs[10809]: [2025-05-27 10:42:46.287] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected May 27 10:42:46 volumio-master vtcs[10809]: [2025-05-27 10:42:46.288] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe May 27 10:42:46 volumio-master volumio[928]: info: Output device has changed, restarting MPD May 27 10:42:46 volumio-master volumio[928]: info: Output device has changed, restarting Shairport Sync May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:46 volumio-master sudo[10994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 27 10:42:46 volumio-master sudo[10994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master systemd[1]: Stopping mpd.service - Music Player Daemon... May 27 10:42:46 volumio-master sudo[10996]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 10:42:46 volumio-master sudo[10996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master sudo[10996]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master volumio[928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 10:42:46 volumio-master volumio[928]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master sudo[11000]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 10:42:46 volumio-master sudo[11000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 27 10:42:46 volumio-master systemd[1]: vtcs.service: Killing process 10815 (vtcs) with signal SIGKILL. May 27 10:42:46 volumio-master systemd[1]: vtcs.service: Deactivated successfully. May 27 10:42:46 volumio-master systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 27 10:42:46 volumio-master systemd[1]: mpd.service: Deactivated successfully. May 27 10:42:46 volumio-master systemd[1]: Stopped mpd.service - Music Player Daemon. May 27 10:42:46 volumio-master systemd[1]: mpd.service: Consumed 3.208s CPU time. May 27 10:42:46 volumio-master sudo[11009]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 27 10:42:46 volumio-master sudo[11009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master sudo[10994]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master volumio[928]: info: MPD Permissions set May 27 10:42:46 volumio-master volumio[928]: info: MPD Permissions set May 27 10:42:46 volumio-master sudo[11011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 27 10:42:46 volumio-master sudo[11011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master systemd[1]: mpd.socket: Deactivated successfully. May 27 10:42:46 volumio-master sudo[11014]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 27 10:42:46 volumio-master sudo[11014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 27 10:42:46 volumio-master volumio[928]: info: Starting Shairport Sync May 27 10:42:46 volumio-master systemd[1]: Starting mpd.service - Music Player Daemon... May 27 10:42:46 volumio-master volumio[928]: info: Starting Shairport Sync May 27 10:42:46 volumio-master sudo[11034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 10:42:46 volumio-master sudo[11034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master volumio[928]: info: Preparing to generate the ALSA configuration file May 27 10:42:46 volumio-master sudo[11032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 10:42:46 volumio-master sudo[11032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master volumio[928]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 27 10:42:46 volumio-master volumio[928]: info: Reading ALSA contributions from plugins. May 27 10:42:46 volumio-master volumio[928]: info: Asound.conf file written May 27 10:42:46 volumio-master sudo[11009]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master sudo[11040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 27 10:42:46 volumio-master sudo[11040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master sudo[11040]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master sudo[11011]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master sudo[11014]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: No state is present for card b1 May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: /usr/share/alsa/init/bcm2835:14: control element not found May 27 10:42:46 volumio-master volumio[928]: No state is present for card b1 May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: No state is present for card Headphones May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: /usr/share/alsa/init/bcm2835:20: control element not found May 27 10:42:46 volumio-master volumio[928]: No state is present for card Headphones May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: No state is present for card sndrpihifiberry May 27 10:42:46 volumio-master volumio[928]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 27 10:42:46 volumio-master volumio[928]: Found hardware: "HifiberryDacp" "" "" "" "" May 27 10:42:46 volumio-master volumio[928]: Hardware is initialized using a generic method May 27 10:42:46 volumio-master volumio[928]: No state is present for card sndrpihifiberry May 27 10:42:46 volumio-master volumio[928]: info: Output device has changed, restarting MPD May 27 10:42:46 volumio-master sudo[11030]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 27 10:42:46 volumio-master systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 27 10:42:46 volumio-master sudo[11030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 10:42:46 volumio-master systemd[1]: shairport-sync.service: Deactivated successfully. May 27 10:42:46 volumio-master systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:46 volumio-master systemd[1]: shairport-sync.service: Consumed 1.688s CPU time. May 27 10:42:46 volumio-master sudo[11030]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master volumio[928]: info: Output device has changed, restarting Shairport Sync May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:46 volumio-master sudo[11049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 10:42:46 volumio-master sudo[11049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master sudo[11052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 10:42:46 volumio-master systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:46 volumio-master sudo[11052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master sudo[11032]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master sudo[11049]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master sudo[11034]: pam_unix(sudo:session): session closed for user root May 27 10:42:46 volumio-master volumio[928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 10:42:46 volumio-master volumio[928]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master systemd[1]: mpd.service: Deactivated successfully. May 27 10:42:46 volumio-master systemd[1]: Stopped mpd.service - Music Player Daemon. May 27 10:42:46 volumio-master systemd[1]: mpd.socket: Deactivated successfully. May 27 10:42:46 volumio-master systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 27 10:42:46 volumio-master systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 27 10:42:46 volumio-master volumio[928]: info: Preparing to generate the ALSA configuration file May 27 10:42:46 volumio-master sudo[11077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 27 10:42:46 volumio-master sudo[11077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:46 volumio-master volumio[928]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 27 10:42:46 volumio-master volumio[928]: info: Reading ALSA contributions from plugins. May 27 10:42:46 volumio-master volumio[928]: info: MPD Permissions set May 27 10:42:46 volumio-master volumio[928]: info: Shairport-Sync Started May 27 10:42:46 volumio-master volumio[928]: info: Shairport-Sync Started May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:46 volumio-master systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 27 10:42:46 volumio-master systemd[1]: Starting mpd.service - Music Player Daemon... May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: Starting Shairport Sync May 27 10:42:47 volumio-master sudo[11081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 27 10:42:47 volumio-master sudo[11081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:47 volumio-master volumio[928]: info: Asound.conf file unchanged, so no further update is needed May 27 10:42:47 volumio-master volumio[928]: info: Output device has changed, restarting MPD May 27 10:42:47 volumio-master sudo[11077]: pam_unix(sudo:session): session closed for user root May 27 10:42:47 volumio-master volumio[928]: info: Output device has changed, restarting Shairport Sync May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:47 volumio-master sudo[11094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 10:42:47 volumio-master sudo[11094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:47 volumio-master volumio[928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 10:42:47 volumio-master volumio[928]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master sudo[11089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 10:42:47 volumio-master sudo[11083]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 27 10:42:47 volumio-master sudo[11083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 10:42:47 volumio-master sudo[11092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 10:42:47 volumio-master sudo[11089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:47 volumio-master sudo[11081]: pam_unix(sudo:session): session closed for user root May 27 10:42:47 volumio-master sudo[11092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:47 volumio-master sudo[11083]: pam_unix(sudo:session): session closed for user root May 27 10:42:47 volumio-master sudo[11092]: pam_unix(sudo:session): session closed for user root May 27 10:42:47 volumio-master volumio[928]: info: MPD Permissions set May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master sudo[11111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 27 10:42:47 volumio-master sudo[11111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:47 volumio-master sudo[11109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 27 10:42:47 volumio-master sudo[11109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 10:42:47 volumio-master volumio[928]: info: Starting Shairport Sync May 27 10:42:47 volumio-master systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 27 10:42:47 volumio-master systemd[1]: shairport-sync.service: Deactivated successfully. May 27 10:42:47 volumio-master systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:47 volumio-master systemd[1]: mpd.service: Deactivated successfully. May 27 10:42:47 volumio-master systemd[1]: Stopped mpd.service - Music Player Daemon. May 27 10:42:47 volumio-master sudo[11120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 10:42:47 volumio-master sudo[11120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:47 volumio-master systemd[1]: mpd.socket: Deactivated successfully. May 27 10:42:47 volumio-master systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 27 10:42:47 volumio-master systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 27 10:42:47 volumio-master systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 27 10:42:47 volumio-master systemd[1]: Starting mpd.service - Music Player Daemon... May 27 10:42:47 volumio-master systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 27 10:42:47 volumio-master sudo[11089]: pam_unix(sudo:session): session closed for user root May 27 10:42:47 volumio-master volumio[928]: info: Shairport-Sync Started May 27 10:42:47 volumio-master sudo[11120]: pam_unix(sudo:session): session closed for user root May 27 10:42:47 volumio-master volumio[928]: info: Shairport-Sync Started May 27 10:42:47 volumio-master sudo[11109]: pam_unix(sudo:session): session closed for user root May 27 10:42:47 volumio-master volumio[928]: (node:928) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 27 10:42:47 volumio-master volumio[928]: (Use `node --trace-warnings ...` to show where the warning was created) May 27 10:42:47 volumio-master volumio[928]: (node:928) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 27 10:42:47 volumio-master volumio[928]: (node:928) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 27 10:42:47 volumio-master volumio[928]: (node:928) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 27 10:42:47 volumio-master volumio[928]: (node:928) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 27 10:42:47 volumio-master sudo[11111]: pam_unix(sudo:session): session closed for user root May 27 10:42:47 volumio-master sudo[11123]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 27 10:42:47 volumio-master sudo[11123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 10:42:47 volumio-master sudo[11123]: pam_unix(sudo:session): session closed for user root May 27 10:42:49 volumio-master volumio[928]: info: TidalConnect service stoped! May 27 10:42:49 volumio-master volumio[928]: info: TidalConnect service stoped! May 27 10:42:49 volumio-master volumio[928]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 27 10:42:49 volumio-master volumio[928]: info: CoreStateMachine::getcurrentVolume May 27 10:42:49 volumio-master volumio[928]: info: CoreCommandRouter::volumioRetrievevolume May 27 10:42:49 volumio-master volumio[928]: info: TidalConnect service stoped! May 27 10:42:49 volumio-master volumio[928]: info: TidalConnect service stoped! May 27 10:42:49 volumio-master volumio[928]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:49 volumio-master volumio[928]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:49 volumio-master volumio[928]: info: VolumeController:: Volume=100 Mute =false May 27 10:42:49 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:49 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 10:42:49 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:49 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:49 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:49 volumio-master volumio[928]: info: CoreStateMachine::updateTrackBlock May 27 10:42:49 volumio-master volumio[928]: info: CorePlayQueue::getTrackBlock May 27 10:42:49 volumio-master volumio[928]: info: CoreCommandRouter::volumioRetrievevolume May 27 10:42:49 volumio-master volumio[928]: info: VolumeController:: Volume=100 Mute =false May 27 10:42:49 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:49 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:49 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:49 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:49 volumio-master sudo[11178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 27 10:42:49 volumio-master sudo[11178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:49 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:49 volumio-master volumio[928]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:49 volumio-master volumio[928]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:49 volumio-master sudo[11181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 27 10:42:49 volumio-master sudo[11181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:49 volumio-master sudo[10936]: pam_unix(sudo:session): session closed for user root May 27 10:42:50 volumio-master volumio[928]: info: TidalConnect service stoped! May 27 10:42:50 volumio-master systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 27 10:42:50 volumio-master sudo[11178]: pam_unix(sudo:session): session closed for user root May 27 10:42:50 volumio-master sudo[11181]: pam_unix(sudo:session): session closed for user root May 27 10:42:50 volumio-master volumio[928]: info: TidalConnect service stoped! May 27 10:42:50 volumio-master volumio[928]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:50 volumio-master volumio[928]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:50 volumio-master sudo[11196]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 27 10:42:50 volumio-master sudo[11196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:50 volumio-master sudo[11196]: pam_unix(sudo:session): session closed for user root May 27 10:42:50 volumio-master volumio[928]: info: Executing endpoint tc_getconfig May 27 10:42:50 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 27 10:42:50 volumio-master vtcs[11184]: STARTING TidalConnect services, version: 1.5.0.45 May 27 10:42:50 volumio-master vtcs[11184]: STARTED TidalConnect services. May 27 10:42:50 volumio-master volumio[928]: info: Executing endpoint tc_connect May 27 10:42:50 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 27 10:42:50 volumio-master volumio[928]: info: Connecting to TidalConnect May 27 10:42:50 volumio-master volumio[928]: info: CoreCommandRouter::servicePushState May 27 10:42:50 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:50 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:50 volumio-master volumio[928]: info: CorePlayQueue::getTrack 0 May 27 10:42:50 volumio-master volumio[928]: 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} May 27 10:42:50 volumio-master volumio[928]: verbose: CURRENT POSITION 0 May 27 10:42:50 volumio-master volumio[928]: info: CoreStateMachine::syncState stateService stop May 27 10:42:50 volumio-master volumio[928]: info: CoreStateMachine::syncState currentStatus stop May 27 10:42:50 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:50 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:50 volumio-master volumio[928]: info: No code May 27 10:42:50 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:50 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:50 volumio-master volumio[928]: info: CoreCommandRouter::servicePushState May 27 10:42:50 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:50 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:50 volumio-master volumio[928]: info: CorePlayQueue::getTrack 0 May 27 10:42:50 volumio-master volumio[928]: 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} May 27 10:42:50 volumio-master volumio[928]: verbose: CURRENT POSITION 0 May 27 10:42:50 volumio-master volumio[928]: info: CoreStateMachine::syncState stateService stop May 27 10:42:50 volumio-master volumio[928]: info: CoreStateMachine::syncState currentStatus stop May 27 10:42:50 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:50 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:50 volumio-master volumio[928]: info: No code May 27 10:42:50 volumio-master volumio[928]: info: CoreStateMachine::pushState May 27 10:42:50 volumio-master volumio[928]: info: CoreCommandRouter::volumioPushState May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output update for this device May 27 10:42:50 volumio-master volumio[928]: info: MRS: Pushing multiroomSync output May 27 10:42:50 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:50 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:50 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:50 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:50 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:50 volumio-master volumio[928]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VQlAKtsHptQbI1v5V5F7H7rDF4f1.12f3bde77f86658755fe9e838c810a4c.state.status' May 27 10:42:50 volumio-master mpd[11142]: 2025-05-27T10:42:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 27 10:42:50 volumio-master systemd[1]: Started mpd.service - Music Player Daemon. May 27 10:42:50 volumio-master sudo[11052]: pam_unix(sudo:session): session closed for user root May 27 10:42:50 volumio-master sudo[11000]: pam_unix(sudo:session): session closed for user root May 27 10:42:50 volumio-master sudo[11094]: pam_unix(sudo:session): session closed for user root May 27 10:42:50 volumio-master sudo[10984]: pam_unix(sudo:session): session closed for user root May 27 10:42:50 volumio-master volumio[928]: info: TidalConnect service stoped! May 27 10:42:50 volumio-master volumio[928]: error: MPD error: The expression evaluated to a falsy value: May 27 10:42:50 volumio-master volumio[928]: assert.ok(self.idling) May 27 10:42:50 volumio-master volumio[928]: error: The expression evaluated to a falsy value: May 27 10:42:50 volumio-master volumio[928]: assert.ok(self.idling) May 27 10:42:50 volumio-master volumio[928]: info: TidalConnect service stoped! May 27 10:42:50 volumio-master volumio[928]: error: MPD error: The expression evaluated to a falsy value: May 27 10:42:50 volumio-master volumio[928]: assert.ok(self.idling) May 27 10:42:50 volumio-master volumio[928]: error: The expression evaluated to a falsy value: May 27 10:42:50 volumio-master volumio[928]: assert.ok(self.idling) May 27 10:42:50 volumio-master volumio[928]: error: MPD error: The expression evaluated to a falsy value: May 27 10:42:50 volumio-master volumio[928]: assert.ok(self.idling) May 27 10:42:50 volumio-master volumio[928]: error: The expression evaluated to a falsy value: May 27 10:42:50 volumio-master volumio[928]: assert.ok(self.idling) May 27 10:42:50 volumio-master volumio[928]: error: updateQueue error: null May 27 10:42:50 volumio-master volumio[928]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:50 volumio-master volumio[928]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 27 10:42:50 volumio-master sudo[11224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 27 10:42:50 volumio-master sudo[11224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:50 volumio-master sudo[11224]: pam_unix(sudo:session): session closed for user root May 27 10:42:52 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork May 27 10:42:52 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo May 27 10:42:52 volumio-master sudo[11228]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 10:42:52 volumio-master sudo[11228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:52 volumio-master sudo[11228]: pam_unix(sudo:session): session closed for user root May 27 10:42:52 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 27 10:42:52 volumio-master volumio[928]: info: Saving new wireless network May 27 10:42:52 volumio-master sudo[11231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 27 10:42:52 volumio-master sudo[11231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:52 volumio-master sudo[11231]: pam_unix(sudo:session): session closed for user root May 27 10:42:52 volumio-master volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 27 10:42:52 volumio-master volumio[928]: info: Discovery: Restarting Advertising due to device name change May 27 10:42:52 volumio-master sudo[11234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 27 10:42:52 volumio-master sudo[11234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:52 volumio-master systemd[1]: Starting wireless.service - Wireless Services... May 27 10:42:52 volumio-master systemd[1]: systemd-timedated.service: Deactivated successfully. May 27 10:42:53 volumio-master volumio[928]: info: TidalConnect service started! May 27 10:42:53 volumio-master systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... May 27 10:42:53 volumio-master dnsmasq[948]: exiting on receipt of SIGTERM May 27 10:42:53 volumio-master systemd[1]: dnsmasq.service: Deactivated successfully. May 27 10:42:53 volumio-master systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. May 27 10:42:53 volumio-master systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... May 27 10:42:53 volumio-master volumio[928]: info: TidalConnect service started! May 27 10:42:53 volumio-master volumio[928]: info: Discovery: A device disappeared from network May 27 10:42:53 volumio-master volumio[928]: info: Discovery: A device disappeared from network May 27 10:42:53 volumio-master volumio[928]: info: Discovery: A device disappeared from network May 27 10:42:53 volumio-master volumio[928]: info: TidalConnect service started! May 27 10:42:53 volumio-master dhcpcd[628]: wlan0: carrier lost May 27 10:42:53 volumio-master systemd[1]: hostapd.service: Deactivated successfully. May 27 10:42:53 volumio-master systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. May 27 10:42:53 volumio-master avahi-daemon[556]: Withdrawing address record for 169.254.53.88 on wlan0. May 27 10:42:53 volumio-master sudo[11268]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 27 10:42:53 volumio-master sudo[11268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 10:42:53 volumio-master dhcpcd[628]: wlan0: deleting route to 169.254.0.0/16 May 27 10:42:53 volumio-master avahi-daemon[556]: Withdrawing address record for 192.168.211.1 on wlan0. May 27 10:42:53 volumio-master avahi-daemon[556]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 27 10:42:53 volumio-master avahi-daemon[556]: Interface wlan0.IPv4 no longer relevant for mDNS. May 27 10:42:53 volumio-master sudo[11268]: pam_unix(sudo:session): session closed for user root May 27 10:42:53 volumio-master sudo[11278]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 27 10:42:53 volumio-master sudo[11278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 10:42:53 volumio-master systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 27 10:42:53 volumio-master systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 27 10:42:53 volumio-master systemd[1]: welcome.service: Deactivated successfully. May 27 10:42:53 volumio-master systemd[1]: Stopped welcome.service - Show a welcome message on console. May 27 10:42:53 volumio-master systemd[1]: Stopping welcome.service - Show a welcome message on console... May 27 10:42:53 volumio-master volumio[928]: info: TidalConnect service started! May 27 10:42:53 volumio-master systemd[1]: Starting welcome.service - Show a welcome message on console... May 27 10:42:54 volumio-master sudo[11278]: pam_unix(sudo:session): session closed for user root May 27 10:42:54 volumio-master welcome[11290]: Resolved ip:[1] 192.168.7.70 May 27 10:42:54 volumio-master systemd[1]: Finished welcome.service - Show a welcome message on console. May 27 10:42:54 volumio-master systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 27 10:42:54 volumio-master systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 27 10:42:54 volumio-master systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 27 10:42:54 volumio-master systemd[1]: welcome.service: Deactivated successfully. May 27 10:42:54 volumio-master systemd[1]: Stopped welcome.service - Show a welcome message on console. May 27 10:42:54 volumio-master systemd[1]: Stopping welcome.service - Show a welcome message on console... May 27 10:42:54 volumio-master sudo[11320]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 27 10:42:54 volumio-master sudo[11320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:54 volumio-master kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 27 10:42:54 volumio-master sudo[11320]: pam_unix(sudo:session): session closed for user root May 27 10:42:54 volumio-master sudo[11323]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 27 10:42:54 volumio-master sudo[11323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:54 volumio-master systemd[1]: Starting welcome.service - Show a welcome message on console... May 27 10:42:54 volumio-master sudo[11323]: pam_unix(sudo:session): session closed for user root May 27 10:42:54 volumio-master welcome[11317]: Resolved ip:[1] 192.168.7.70 May 27 10:42:54 volumio-master systemd[1]: Finished welcome.service - Show a welcome message on console. May 27 10:42:54 volumio-master sudo[11333]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 27 10:42:54 volumio-master sudo[11333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:54 volumio-master systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 27 10:42:54 volumio-master sudo[11333]: pam_unix(sudo:session): session closed for user root May 27 10:42:54 volumio-master sudo[11337]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 27 10:42:54 volumio-master sudo[11337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:56 volumio-master ntpd[784]: IO: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=5831 secs May 27 10:42:56 volumio-master ntpd[784]: IO: Deleting interface #4 wlan0, 169.254.53.88#123, interface stats: received=0, sent=0, dropped=0, active_time=5820 secs May 27 10:42:57 volumio-master volumio[928]: info: Discovery: Started advertising with name: Volumio Master May 27 10:42:57 volumio-master sudo[11337]: pam_unix(sudo:session): session closed for user root May 27 10:42:57 volumio-master sudo[11344]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 27 10:42:57 volumio-master sudo[11344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:57 volumio-master sudo[11344]: pam_unix(sudo:session): session closed for user root May 27 10:42:57 volumio-master sudo[11346]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN May 27 10:42:57 volumio-master sudo[11346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 10:42:57 volumio-master sudo[11346]: pam_unix(sudo:session): session closed for user root May 27 10:42:57 volumio-master sudo[11354]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 27 10:42:57 volumio-master sudo[11354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 10:42:57 volumio-master sudo[11354]: pam_unix(sudo:session): session closed for user root May 27 10:42:57 volumio-master sudo[11356]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 27 10:42:57 volumio-master sudo[11356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 10:42:58 volumio-master volumio[928]: compat.c: read() failed: Resource temporarily unavailable May 27 10:42:58 volumio-master volumio[928]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 10:42:58 volumio-master volumio[928]: Error: dns service error: unknown May 27 10:42:58 volumio-master volumio[928]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 27 10:42:58 volumio-master volumio[928]: errorCode: -65537 May 27 10:42:58 volumio-master volumio[928]: } May 27 10:42:58 volumio-master volumio[928]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 10:42:58 volumio-master sudo[11356]: pam_unix(sudo:session): session closed for user root May 27 10:42:58 volumio-master wpa_supplicant[11373]: Successfully initialized wpa_supplicant May 27 10:42:58 volumio-master sudo[11374]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 27 10:42:58 volumio-master sudo[11374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 10:42:58 volumio-master kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 27 10:42:58 volumio-master dhcpcd[628]: ps_ctl_dispatch: cannot handle another client May 27 10:42:58 volumio-master dhcpcd[628]: control_free: No such file or directory May 27 10:42:58 volumio-master sudo[11374]: pam_unix(sudo:session): session closed for user root May 27 10:42:58 volumio-master wpa_supplicant[11373]: nl80211: kernel reports: Registration to specific type not supported May 27 10:42:58 volumio-master sudo[11382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-27 10:41' May 27 10:42:58 volumio-master sudo[11382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"