Feb 11 20:07:02 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 11 20:07:04 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 11 20:07:04 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:04 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:04 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:04 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:04 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:07:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 11 20:07:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 11 20:07:04 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 11 20:07:04 volumio sudo[2180]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:04 volumio volumio[1354]: info: Upmpdcli Daemon Started Feb 11 20:07:07 volumio sudo[2253]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 20:07:07 volumio sudo[2253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:07 volumio sudo[2253]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:07 volumio sudo[2255]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 20:07:07 volumio sudo[2255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:07 volumio sudo[2255]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:07 volumio sudo[2259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 11 20:07:07 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:08 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:08 volumio volumio[1354]: info: Upmpdcli Daemon Started Feb 11 20:07:09 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:09 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:09 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:09 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:09 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:07:10 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 20:07:10 volumio volumio[1354]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 11 20:07:10 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:10 volumio volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 11 20:07:12 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 20:07:12 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:12 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 20:07:12 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 11 20:07:12 volumio volumio-remote-updater[1015]: Test mode disabled Feb 11 20:07:12 volumio volumio-remote-updater[1015]: Alpha mode disabled Feb 11 20:07:12 volumio volumio-remote-updater[1015]: Alpha legacy test mode disabled Feb 11 20:07:13 volumio sudo[2286]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 20:07:13 volumio sudo[2286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:13 volumio sudo[2286]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:13 volumio sudo[2288]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 20:07:13 volumio sudo[2288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:13 volumio sudo[2288]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:13 volumio volumio[1354]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 20:07:13 volumio volumio[1354]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.5 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 11 20:07:13 volumio volumio[1354]: info: Received Get System Info Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 20:07:13 volumio volumio[1354]: info: Discovery: Getting this device information Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:13 volumio volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 20:07:13 volumio volumio-remote-updater[1015]: Test mode disabled Feb 11 20:07:13 volumio volumio-remote-updater[1015]: Alpha mode disabled Feb 11 20:07:13 volumio volumio-remote-updater[1015]: Alpha legacy test mode disabled Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:13 volumio volumio[1354]: info: Retrieving Cloud Streaming UI Feb 11 20:07:13 volumio volumio[1354]: info: Getting Tidal Cloud Configuration Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:13 volumio volumio[1354]: info: Getting Qobuz Cloud Configuration Feb 11 20:07:13 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:13 volumio volumio[1354]: info: Getting Spotify Cloud Configuration Feb 11 20:07:13 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:13 volumio volumio[1354]: info: Saving Spotify Acccount Feb 11 20:07:13 volumio volumio[1354]: info: Got Tidal Cloud Configuration Feb 11 20:07:13 volumio volumio[1354]: info: Got it Feb 11 20:07:13 volumio volumio[1354]: info: Got it Feb 11 20:07:13 volumio volumio[1354]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:13 volumio volumio[1354]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 11 20:07:13 volumio volumio[1354]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 20:07:13 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 20:07:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 11 20:07:14 volumio volumio[1354]: info: Loading i18n strings for locale it Feb 11 20:07:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 11 20:07:14 volumio volumio[1354]: info: Setting timezone to Europe/Rome Feb 11 20:07:14 volumio sudo[2298]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 11 20:07:14 volumio sudo[2298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:14 volumio sudo[2298]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:14 volumio sudo[2302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Feb 11 20:07:14 volumio sudo[2302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:14 volumio sudo[2302]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:14 volumio sudo[2306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 11 20:07:14 volumio sudo[2306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:14 volumio sudo[2306]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:14 volumio sudo[2310]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Feb 11 20:07:14 volumio sudo[2310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:14 volumio dbus-daemon[1003]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.35' (uid=0 pid=2311 comm="/usr/bin/timedatectl set-timezone Europe/Rome") Feb 11 20:07:14 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Feb 11 20:07:14 volumio dbus-daemon[1003]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 11 20:07:14 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Feb 11 20:07:14 volumio sudo[2310]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:14 volumio volumio[1354]: Updating browse sources language Feb 11 20:07:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 20:07:14 volumio volumio[1354]: info: Fetching Streaming Services browse cache Feb 11 20:07:14 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:14 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:14 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:14 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:14 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 11 20:07:15 volumio volumio[1354]: info: MyVolumio login type: Token Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 20:07:15 volumio volumio[1354]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 11 20:07:15 volumio volumio[1354]: info: Completed starting MyVolumio Plugin Feb 11 20:07:15 volumio volumio[1354]: [Metrics] CommandRouter: 35s 503.53ms Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::volumiosetStartupVolume Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::Close All Modals sent Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::Close All Modals sent Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:15 volumio volumio[1354]: info: Retrieving Cloud Streaming UI Feb 11 20:07:15 volumio volumio[1354]: info: Getting Tidal Cloud Configuration Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:15 volumio volumio[1354]: info: Getting Qobuz Cloud Configuration Feb 11 20:07:15 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:15 volumio volumio[1354]: info: Getting Spotify Cloud Configuration Feb 11 20:07:15 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:15 volumio volumio[1354]: info: Saving Spotify Acccount Feb 11 20:07:15 volumio volumio[1354]: info: Got Tidal Cloud Configuration Feb 11 20:07:15 volumio volumio[1354]: info: Got it Feb 11 20:07:15 volumio volumio[1354]: info: Got it Feb 11 20:07:15 volumio volumio[1354]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:15 volumio volumio[1354]: info: Retrieving Cloud Streaming UI Feb 11 20:07:15 volumio volumio[1354]: info: Getting Tidal Cloud Configuration Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:15 volumio volumio[1354]: info: Getting Qobuz Cloud Configuration Feb 11 20:07:15 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:15 volumio volumio[1354]: info: Getting Spotify Cloud Configuration Feb 11 20:07:15 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:15 volumio volumio[1354]: info: Saving Spotify Acccount Feb 11 20:07:15 volumio volumio[1354]: info: Got Tidal Cloud Configuration Feb 11 20:07:15 volumio volumio[1354]: info: Got it Feb 11 20:07:15 volumio volumio[1354]: info: Got it Feb 11 20:07:15 volumio volumio[1354]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:15 volumio volumio[1354]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:15 volumio volumio[1354]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 11 20:07:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 11 20:07:16 volumio volumio[1354]: info: MyVolumio login type: Token Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 20:07:16 volumio volumio[1354]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 11 20:07:16 volumio volumio[1354]: info: MyVolumio token set successfully Feb 11 20:07:16 volumio volumio[1354]: info: MYVOLUMIO: Adding device Feb 11 20:07:16 volumio volumio[1354]: info: MYVOLUMIO: Evaluating Server Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 11 20:07:16 volumio volumio[1354]: info: Version has changed, forcing UI Reload Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::Reload Ui Feb 11 20:07:16 volumio volumio[1354]: info: MyVolumio Plan changed: premium Feb 11 20:07:16 volumio volumio[1354]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 11 20:07:16 volumio volumio[1354]: info: Removing browser output: myVolumio user plan is not superstar Feb 11 20:07:16 volumio volumio[1354]: info: Removing audio output: Feb 11 20:07:16 volumio volumio[1354]: info: MYVOLUMIO: Adding device Feb 11 20:07:16 volumio volumio[1354]: info: MYVOLUMIO: Evaluating Server Feb 11 20:07:16 volumio sudo[2342]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 20:07:16 volumio sudo[2342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:16 volumio sudo[2342]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:16 volumio volumio[1354]: info: Remote config written successfully Feb 11 20:07:16 volumio volumio[1354]: info: Starting Tunnel 1 Feb 11 20:07:16 volumio volumio[1354]: info: Starting Tunnel Connection Checker Feb 11 20:07:16 volumio sudo[2344]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 20:07:16 volumio sudo[2344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:16 volumio sudo[2344]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:16 volumio volumio[1354]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.5 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 11 20:07:16 volumio volumio[1354]: info: Received Get System Info Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 20:07:16 volumio volumio[1354]: info: Discovery: Getting this device information Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:16 volumio volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:16 volumio volumio[1354]: info: Retrieving Cloud Streaming UI Feb 11 20:07:16 volumio volumio-remote-updater[1015]: Test mode disabled Feb 11 20:07:16 volumio volumio-remote-updater[1015]: Alpha mode disabled Feb 11 20:07:16 volumio volumio-remote-updater[1015]: Alpha legacy test mode disabled Feb 11 20:07:16 volumio volumio[1354]: info: Getting Tidal Cloud Configuration Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:16 volumio volumio[1354]: info: Getting Qobuz Cloud Configuration Feb 11 20:07:16 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:16 volumio volumio[1354]: info: Getting Spotify Cloud Configuration Feb 11 20:07:16 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:16 volumio volumio[1354]: info: Saving Spotify Acccount Feb 11 20:07:16 volumio volumio[1354]: info: Got Tidal Cloud Configuration Feb 11 20:07:16 volumio volumio[1354]: info: Got it Feb 11 20:07:16 volumio volumio[1354]: info: Got it Feb 11 20:07:16 volumio volumio[1354]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:16 volumio volumio[1354]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 11 20:07:16 volumio volumio[1354]: info: MYVolumio Device enabled Feb 11 20:07:16 volumio volumio[1354]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 11 20:07:16 volumio volumio[1354]: info: MyVolumio status changed Feb 11 20:07:16 volumio volumio[1354]: info: Streaming services startup Feb 11 20:07:16 volumio volumio[1354]: info: Starting Streaming Daemon Feb 11 20:07:16 volumio sudo[2371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 20:07:16 volumio sudo[2371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:16 volumio volumio[1354]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 20:07:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 20:07:16 volumio sudo[2371]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:16 volumio volumio[1354]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Feb 11 20:07:17 volumio volumio[1354]: error: Cannot start Volumio Streaming Daemon Feb 11 20:07:17 volumio volumio[1354]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 11 20:07:17 volumio volumio[1354]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 11 20:07:17 volumio volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 11 20:07:17 volumio volumio[1354]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 11 20:07:17 volumio volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 11 20:07:18 volumio sudo[2375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 11 20:07:18 volumio sudo[2375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2375]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio volumio[1354]: info: MRS: MultiRoom plugin initialized Feb 11 20:07:18 volumio volumio[1354]: info: MRS: STOPPING SNAPCLIENT Feb 11 20:07:18 volumio volumio[1354]: info: MRS: Snap server stop Feb 11 20:07:18 volumio volumio[1354]: info: MRS: STOPPING volumioStreaming Feb 11 20:07:18 volumio sudo[2392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 11 20:07:18 volumio sudo[2392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 11 20:07:18 volumio sudo[2394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 11 20:07:18 volumio sudo[2400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 11 20:07:18 volumio sudo[2400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 11 20:07:18 volumio sudo[2398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2400]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 11 20:07:18 volumio sudo[2392]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio sudo[2398]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio sudo[2394]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 11 20:07:18 volumio volumio[1354]: info: Preparing to generate the ALSA configuration file Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 11 20:07:18 volumio volumio[1354]: info: Loading i18n strings for locale it Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 11 20:07:18 volumio volumio[1354]: info: Setting timezone to Europe/Rome Feb 11 20:07:18 volumio sudo[2405]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 11 20:07:18 volumio sudo[2405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2405]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio sudo[2409]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Feb 11 20:07:18 volumio sudo[2409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2409]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio sudo[2413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 11 20:07:18 volumio sudo[2413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2413]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio sudo[2418]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Feb 11 20:07:18 volumio sudo[2418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2418]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 11 20:07:18 volumio volumio[1354]: info: Loading i18n strings for locale it Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 11 20:07:18 volumio volumio[1354]: info: Setting timezone to Europe/Rome Feb 11 20:07:18 volumio sudo[2422]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 11 20:07:18 volumio sudo[2422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2422]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio sudo[2426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Feb 11 20:07:18 volumio sudo[2426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2426]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio sudo[2430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 11 20:07:18 volumio sudo[2430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2430]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio sudo[2434]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Feb 11 20:07:18 volumio sudo[2434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio sudo[2434]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 11 20:07:18 volumio sudo[2437]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 11 20:07:18 volumio sudo[2437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio volumio[1354]: info: MRS: Removed streaming files Feb 11 20:07:18 volumio volumio[1354]: info: MRS: volumioStreaming STOPPED Feb 11 20:07:18 volumio volumio[1354]: info: MRS: SNAPSERVER STOPPED Feb 11 20:07:18 volumio volumio[1354]: info: MRS: SNAPCLIENT STOPPED Feb 11 20:07:18 volumio volumio[1354]: info: Asound.conf file unchanged, so no further update is needed Feb 11 20:07:18 volumio volumio[1354]: info: Output device has changed, restarting MPD Feb 11 20:07:18 volumio volumio[1354]: info: Output device has changed, restarting Shairport Sync Feb 11 20:07:18 volumio sudo[2440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 20:07:18 volumio sudo[2440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:18 volumio sudo[2440]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:18 volumio sudo[2442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 20:07:18 volumio sudo[2442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:18 volumio volumio[1354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 20:07:18 volumio volumio[1354]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 11 20:07:18 volumio volumio[1354]: ------------------------------------ BT MESSAGE: [FUNC] onStart Feb 11 20:07:18 volumio volumio[1354]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Feb 11 20:07:18 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 11 20:07:18 volumio volumio[1354]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Feb 11 20:07:18 volumio volumio[1354]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Feb 11 20:07:18 volumio volumio[1354]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 11 20:07:18 volumio volumio[1354]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Feb 11 20:07:18 volumio volumio[1354]: info: Adding METAVOLUMIO REST API Endpoints Feb 11 20:07:18 volumio volumio[1354]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 11 20:07:18 volumio volumio[1354]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 11 20:07:18 volumio volumio[1354]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 11 20:07:18 volumio volumio[1354]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Feb 11 20:07:18 volumio volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 11 20:07:18 volumio volumio[1354]: info: Preparing CD Folders Feb 11 20:07:18 volumio volumio[1354]: info: Adding CD REST API Endpoints Feb 11 20:07:18 volumio volumio[1354]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 11 20:07:18 volumio volumio[1354]: info: Starting UDEV Watcher for CD Feb 11 20:07:18 volumio volumio[1354]: info: Detecting CD presence with UDEV Feb 11 20:07:18 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 11 20:07:18 volumio bluetoothd[1002]: Path / reserved for Adv Monitor app :1.44 Feb 11 20:07:18 volumio bluetoothd[1002]: Adv Monitor app :1.44 disconnected from D-Bus Feb 11 20:07:18 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 20:07:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 20:07:18 volumio systemd[1]: mpd.service: Consumed 1.510s CPU time. Feb 11 20:07:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 20:07:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 20:07:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 20:07:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 20:07:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 20:07:18 volumio sudo[2460]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 20:07:18 volumio sudo[2460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 20:07:18 volumio sudo[2460]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:19 volumio mpd[2462]: 2026-02-11T20:07:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 20:07:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 20:07:19 volumio sudo[2442]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:21 volumio sudo[2437]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:23 volumio volumio[1354]: warn: [cd-plugin] cdspeedctl: device or media not ready Feb 11 20:07:23 volumio volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Feb 11 20:07:23 volumio volumio[1354]: info: Adding inputs REST Endpoints Feb 11 20:07:23 volumio volumio[1354]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 11 20:07:23 volumio volumio[1354]: info: Scanning Audio Inputs Feb 11 20:07:23 volumio volumio[1354]: info: Checking against Known Cards name Feb 11 20:07:23 volumio volumio[1354]: info: Adding Server instance for streaming Feb 11 20:07:23 volumio volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Feb 11 20:07:23 volumio volumio[1354]: error: Hi Res Audio Failed Login: Missing Login Data Feb 11 20:07:23 volumio volumio[1354]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 11 20:07:23 volumio volumio[1354]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 11 20:07:23 volumio volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 11 20:07:23 volumio volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 11 20:07:23 volumio volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 11 20:07:23 volumio volumio[1354]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Feb 11 20:07:23 volumio volumio[1354]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Feb 11 20:07:23 volumio sudo[2483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:07:23 volumio sudo[2483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:23 volumio volumio[1354]: info: QobuzConnect: Starting Qobuz Connect socket and service Feb 11 20:07:23 volumio sudo[2490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 20:07:23 volumio sudo[2490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:23 volumio volumio[1354]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Feb 11 20:07:23 volumio volumio[1354]: info: Adding TIDAL REST API Endpoints Feb 11 20:07:23 volumio volumio[1354]: info: Stopping AccessToken refresher cron for QOBUZ Feb 11 20:07:23 volumio sudo[2483]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:23 volumio volumio[1354]: info: AccessToken refresher cron started for QOBUZ Feb 11 20:07:23 volumio volumio[1354]: info: Adding QOBUZ REST API Endpoints Feb 11 20:07:23 volumio volumio[1354]: info: Fetching Streaming Services browse cache Feb 11 20:07:23 volumio volumio[1354]: info: Fetching Streaming Services browse cache Feb 11 20:07:23 volumio sudo[2490]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:23 volumio sudo[2493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 20:07:23 volumio sudo[2493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:23 volumio volumio[1354]: info: MRS: Getting audio outputs on start Feb 11 20:07:23 volumio volumio[1354]: info: MRS: Requesting all other devices output Feb 11 20:07:23 volumio volumio[1354]: info: BOOT COMPLETED Feb 11 20:07:23 volumio volumio[1354]: Updating browse sources language Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 20:07:23 volumio volumio[1354]: Updating browse sources language Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 20:07:23 volumio volumio[1354]: info: Setting Geolocation for MyVolumio to eu2 Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: Setting Geolocation for MyVolumio to eu6 Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:23 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 20:07:23 volumio volumio[1354]: info: Received Get System Info Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 20:07:23 volumio volumio[1354]: info: Discovery: Getting this device information Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:23 volumio volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 20:07:23 volumio sudo[2493]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:23 volumio volumio[1354]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Feb 11 20:07:23 volumio volumio[1354]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Feb 11 20:07:23 volumio volumio[1354]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Feb 11 20:07:23 volumio volumio[1354]: info: MPD Permissions set Feb 11 20:07:23 volumio sudo[2498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 11 20:07:23 volumio sudo[2498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:23 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Feb 11 20:07:23 volumio sudo[2498]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:23 volumio volumiobt[2515]: INFO [BTSTART] Ensuring Bluetooth directory exists... Feb 11 20:07:23 volumio volumio[1354]: error: updateQueue error: null Feb 11 20:07:23 volumio sudo[2518]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Feb 11 20:07:23 volumio sudo[2518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:23 volumio upmpdcli[2513]: writing RSA key Feb 11 20:07:23 volumio sudo[2518]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:23 volumio sudo[2520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Feb 11 20:07:23 volumio sudo[2520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:23 volumio sudo[2520]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:23 volumio volumiobt[2524]: INFO [BTSTART] Powering on Bluetooth if needed... Feb 11 20:07:23 volumio volumio[1354]: info: Executing endpoint qc_getconfig Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 11 20:07:23 volumio bluetoothd[1002]: Adv Monitor app :1.49 disconnected from D-Bus Feb 11 20:07:23 volumio volumiobt[2530]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Feb 11 20:07:23 volumio qobuz-connect[2496]: 20260211 20:07:23.948 [2496.2496] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 11 20:07:23 volumio volumio[1354]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 11 20:07:23 volumio volumio[1354]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 11 20:07:23 volumio volumio[1354]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Feb 11 20:07:23 volumio volumio[1354]: info: CoreCommandRouter::volumioStop Feb 11 20:07:23 volumio volumio[1354]: info: CoreStateMachine::stop Feb 11 20:07:23 volumio volumio[1354]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 20:07:23 volumio volumio[1354]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Feb 11 20:07:23 volumio volumio[1354]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 20:07:23 volumio volumio[1354]: info: Starting Shairport Sync Feb 11 20:07:24 volumio volumio[1354]: Cast browser error: Error: getaddrinfo -3007 Feb 11 20:07:24 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:24 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:24 volumio volumiobt[2531]: [176B blob data] Feb 11 20:07:24 volumio volumiobt[2531]: [157B blob data] Feb 11 20:07:24 volumio volumiobt[2531]: [157B blob data] Feb 11 20:07:24 volumio volumiobt[2531]: [157B blob data] Feb 11 20:07:24 volumio volumiobt[2531]: [113B blob data] Feb 11 20:07:24 volumio volumiobt[2531]: [bluetoothctl]> discoverable on Feb 11 20:07:24 volumio volumiobt[2531]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Feb 11 20:07:24 volumio volumiobt[2531]: [bluetoothctl]> pairable on Feb 11 20:07:24 volumio sudo[2535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 20:07:24 volumio sudo[2535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio volumio[1354]: info: Preparing to generate the ALSA configuration file Feb 11 20:07:24 volumio sudo[2538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 11 20:07:24 volumio sudo[2538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:24 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:24 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:07:24 volumio bluetoothd[1002]: Adv Monitor app :1.50 disconnected from D-Bus Feb 11 20:07:24 volumio volumio[1354]: info: Asound.conf file unchanged, so no further update is needed Feb 11 20:07:24 volumio volumio[1354]: info: Output device has changed, restarting MPD Feb 11 20:07:24 volumio volumiobt[2531]: [bluetoothctl]> Feb 11 20:07:24 volumio volumiobt[2540]: INFO [BTSTART] Registering Bluetooth agent... Feb 11 20:07:24 volumio volumio[1354]: info: Output device has changed, restarting Shairport Sync Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:24 volumio sudo[2542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 20:07:24 volumio sudo[2542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio sudo[2542]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio volumiobt[2544]: [NEW] Media /org/bluez/hci0 Feb 11 20:07:24 volumio volumiobt[2544]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 11 20:07:24 volumio volumiobt[2544]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 11 20:07:24 volumio volumiobt[2544]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Feb 11 20:07:24 volumio sudo[2545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 20:07:24 volumio sudo[2545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio qobuz-connect[2496]: 20260211 20:07:24.057 [2496.2496] INFO VolumeManager: [0x1b1d348]: Setting new playback volume: 75 Feb 11 20:07:24 volumio qobuz-connect[2496]: 20260211 20:07:24.057 [2496.2496] INFO VolumeManager: [0x1b1d348]: Setting new mute state: 0 Feb 11 20:07:24 volumio qobuz-connect[2496]: 20260211 20:07:24.057 [2496.2496] INFO QobuzConnect: [0x1b1dd18]: Client initialized! Feb 11 20:07:24 volumio qobuz-connect[2496]: 20260211 20:07:24.057 [2496.2496] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Feb 11 20:07:24 volumio bluetoothd[1002]: Adv Monitor app :1.51 disconnected from D-Bus Feb 11 20:07:24 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. Feb 11 20:07:24 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. Feb 11 20:07:24 volumio volumio[1354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 20:07:24 volumio volumio[1354]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:24 volumio volumio[1354]: info: Preparing to generate the ALSA configuration file Feb 11 20:07:24 volumio qobuz-connect[2496]: 20260211 20:07:24.076 [2496.2496] INFO LocalConfigManager: [0x1b1cc28]: Starting Local Configuration server Feb 11 20:07:24 volumio qobuz-connect[2496]: 20260211 20:07:24.076 [2496.2496] INFO SampleApp: Starting Local configuration server Feb 11 20:07:24 volumio qobuz-connect[2496]: 20260211 20:07:24.076 [2496.2496] INFO SampleApp: Connected to UNIX socket client 0x1b07818 Feb 11 20:07:24 volumio volumiobt[2553]: No agent is registered Feb 11 20:07:24 volumio volumiobt[2553]: [NEW] Media /org/bluez/hci0 Feb 11 20:07:24 volumio volumiobt[2553]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 11 20:07:24 volumio volumiobt[2553]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 11 20:07:24 volumio volumiobt[2553]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Feb 11 20:07:24 volumio bluetoothd[1002]: Adv Monitor app :1.53 disconnected from D-Bus Feb 11 20:07:24 volumio volumiobt[2560]: INFO [BTSTART] Agent registered successfully. Feb 11 20:07:24 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 11 20:07:24 volumio volumiobt[2563]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Feb 11 20:07:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 20:07:24 volumio volumio[1354]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 11 20:07:24 volumio volumio[1354]: info: Reading ALSA contributions from plugins. Feb 11 20:07:24 volumio volumio[1354]: info: MPD Permissions set Feb 11 20:07:24 volumio qobuz-connect[2496]: 20260211 20:07:24.099 [2496.2496] INFO SampleApp: Playback volume changed: 75 Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio sudo[2567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 20:07:24 volumio sudo[2567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 11 20:07:24 volumio sudo[2538]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:07:24 volumio systemd[1]: shairport-sync.service: Consumed 1.508s CPU time. Feb 11 20:07:24 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 20:07:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 20:07:24 volumio autossh[2574]: port set to 0, monitoring disabled Feb 11 20:07:24 volumio volumio[1354]: info: Remote SSH Started Feb 11 20:07:24 volumio autossh[2574]: starting ssh (count 1) Feb 11 20:07:24 volumio autossh[2574]: ssh child pid is 2577 Feb 11 20:07:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 20:07:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 20:07:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 20:07:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 20:07:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 20:07:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:07:24 volumio sudo[2535]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio sudo[2567]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio sudo[2581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 20:07:24 volumio sudo[2581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 11 20:07:24 volumio qobuz-connect[2496]: 20260211 20:07:24.195 [2496.2496] INFO SampleApp: Stopping Local configuration server Feb 11 20:07:24 volumio volumio[1354]: info: Shairport-Sync Started Feb 11 20:07:24 volumio sudo[2579]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 20:07:24 volumio sudo[2579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 20:07:24 volumio sudo[2579]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio volumio[1354]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 20:07:24 volumio volumio[1354]: info: Starting Shairport Sync Feb 11 20:07:24 volumio sudo[2600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 20:07:24 volumio sudo[2600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 20:07:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 20:07:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:07:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:07:24 volumio sudo[2600]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio volumio[1354]: info: Shairport-Sync Started Feb 11 20:07:24 volumio volumio[1354]: info: Asound.conf file written Feb 11 20:07:24 volumio sudo[2605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 11 20:07:24 volumio sudo[2605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio sudo[2605]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: No state is present for card vc4hdmi0 Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: Found hardware: "vc4-hdmi" "" "" "" "" Feb 11 20:07:24 volumio volumio[1354]: Hardware is initialized using a generic method Feb 11 20:07:24 volumio volumio[1354]: No state is present for card vc4hdmi0 Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: No state is present for card vc4hdmi1 Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: Found hardware: "vc4-hdmi" "" "" "" "" Feb 11 20:07:24 volumio volumio[1354]: Hardware is initialized using a generic method Feb 11 20:07:24 volumio volumio[1354]: No state is present for card vc4hdmi1 Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: No state is present for card sndrpihifiberry Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: Found hardware: "RPi-simple" "" "" "" "" Feb 11 20:07:24 volumio volumio[1354]: Hardware is initialized using a generic method Feb 11 20:07:24 volumio volumio[1354]: No state is present for card sndrpihifiberry Feb 11 20:07:24 volumio volumio[1354]: info: Output device has changed, restarting MPD Feb 11 20:07:24 volumio volumio[1354]: info: Output device has changed, restarting Shairport Sync Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:24 volumio sudo[2625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 20:07:24 volumio sudo[2625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio sudo[2625]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio volumio[1354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 20:07:24 volumio volumio[1354]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio sudo[2627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 20:07:24 volumio sudo[2627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:24 volumio volumio[1354]: info: Preparing to generate the ALSA configuration file Feb 11 20:07:24 volumio volumiossh-tunnel[2577]: Warning: Permanently added '[eu1.myvolumio.org]:2222' (RSA) to the list of known hosts. Feb 11 20:07:24 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 20:07:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 20:07:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 20:07:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 20:07:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 20:07:24 volumio volumio[1354]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 11 20:07:24 volumio volumio[1354]: info: Reading ALSA contributions from plugins. Feb 11 20:07:24 volumio volumio[1354]: info: Successfully Added MyVolumio device Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:24 volumio volumio[1354]: info: MPD Permissions set Feb 11 20:07:24 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 20:07:24 volumio volumio[1354]: info: Successfully Added MyVolumio device Feb 11 20:07:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 20:07:24 volumio volumio[1354]: info: Starting Shairport Sync Feb 11 20:07:24 volumio sudo[2641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 20:07:24 volumio sudo[2641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio volumio[1354]: info: Asound.conf file written Feb 11 20:07:24 volumio sudo[2651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 20:07:24 volumio sudo[2651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio sudo[2654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 11 20:07:24 volumio sudo[2654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio sudo[2641]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio sudo[2654]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 20:07:24 volumio sudo[2657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 20:07:24 volumio sudo[2657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 20:07:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:07:24 volumio sudo[2648]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 20:07:24 volumio sudo[2648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 20:07:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:07:24 volumio sudo[2648]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio sudo[2651]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: No state is present for card vc4hdmi0 Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: Found hardware: "vc4-hdmi" "" "" "" "" Feb 11 20:07:24 volumio volumio[1354]: Hardware is initialized using a generic method Feb 11 20:07:24 volumio volumio[1354]: No state is present for card vc4hdmi0 Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: No state is present for card vc4hdmi1 Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: Found hardware: "vc4-hdmi" "" "" "" "" Feb 11 20:07:24 volumio volumio[1354]: Hardware is initialized using a generic method Feb 11 20:07:24 volumio volumio[1354]: No state is present for card vc4hdmi1 Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: No state is present for card sndrpihifiberry Feb 11 20:07:24 volumio volumio[1354]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 11 20:07:24 volumio volumio[1354]: Found hardware: "RPi-simple" "" "" "" "" Feb 11 20:07:24 volumio volumio[1354]: Hardware is initialized using a generic method Feb 11 20:07:24 volumio volumio[1354]: No state is present for card sndrpihifiberry Feb 11 20:07:24 volumio volumio[1354]: info: Output device has changed, restarting MPD Feb 11 20:07:24 volumio sudo[2678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 20:07:24 volumio sudo[2678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio volumio[1354]: info: Output device has changed, restarting Shairport Sync Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:24 volumio sudo[2678]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio volumio[1354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 20:07:24 volumio volumio[1354]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio sudo[2681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 20:07:24 volumio sudo[2681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:24 volumio volumiobt[2564]: INFO:a2dp-agent:Connecting to system bus. Feb 11 20:07:24 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 20:07:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 20:07:24 volumio sudo[2696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 20:07:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 20:07:24 volumio sudo[2696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 20:07:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 20:07:24 volumio volumio[1354]: info: MPD Permissions set Feb 11 20:07:24 volumio volumio[1354]: info: Shairport-Sync Started Feb 11 20:07:24 volumio volumiobt[2564]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Feb 11 20:07:24 volumio volumiobt[2564]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: Starting Shairport Sync Feb 11 20:07:24 volumio sudo[2706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 20:07:24 volumio sudo[2706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 20:07:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 20:07:24 volumio sudo[2696]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 20:07:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 20:07:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:07:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:07:24 volumio sudo[2709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 20:07:24 volumio volumiobt[2564]: INFO:a2dp-agent:Registering agent. Feb 11 20:07:24 volumio sudo[2709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:24 volumio sudo[2706]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio volumiobt[2564]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Feb 11 20:07:24 volumio volumiobt[2564]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Feb 11 20:07:24 volumio volumio[1354]: info: Shairport-Sync Started Feb 11 20:07:24 volumio sudo[2708]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 20:07:24 volumio sudo[2708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 20:07:24 volumio sudo[2708]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:24 volumio volumio[1354]: info: Updating MyVolumio device info Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: Updating MyVolumio device info Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:24 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:25 volumio mpd[2727]: 2026-02-11T20:07:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 20:07:25 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 20:07:25 volumio sudo[2545]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:25 volumio sudo[2681]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:25 volumio sudo[2627]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:25 volumio volumio[1354]: error: MPD error: The expression evaluated to a falsy value: Feb 11 20:07:25 volumio volumio[1354]: assert.ok(self.idling) Feb 11 20:07:25 volumio volumio[1354]: error: The expression evaluated to a falsy value: Feb 11 20:07:25 volumio volumio[1354]: assert.ok(self.idling) Feb 11 20:07:25 volumio volumio[1354]: error: MPD error: The expression evaluated to a falsy value: Feb 11 20:07:25 volumio volumio[1354]: assert.ok(self.idling) Feb 11 20:07:25 volumio volumio[1354]: error: The expression evaluated to a falsy value: Feb 11 20:07:25 volumio volumio[1354]: assert.ok(self.idling) Feb 11 20:07:25 volumio volumio[1354]: error: updateQueue error: null Feb 11 20:07:25 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 11 20:07:25 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 11 20:07:25 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 11 20:07:25 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:25 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 20:07:25 volumio volumio[1354]: info: Successfully Updated MyVolumio device Feb 11 20:07:25 volumio volumio[1354]: info: Successfully Updated MyVolumio device Feb 11 20:07:26 volumio qobuz-connect[2496]: 20260211 20:07:26.079 [2496.2496] INFO SampleApp: shat down connection on UNIX socket Feb 11 20:07:26 volumio volumio[1354]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 11 20:07:26 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:26 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 11 20:07:26 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 20:07:26 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 20:07:26 volumio sudo[2657]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:26 volumio sudo[2581]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:26 volumio sudo[2709]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:26 volumio volumio[1354]: info: Executing endpoint qc_getconfig Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 11 20:07:26 volumio qobuz-connect[2733]: 20260211 20:07:26.128 [2733.2733] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 11 20:07:26 volumio qobuz-connect[2733]: 20260211 20:07:26.130 [2733.2733] INFO VolumeManager: [0x16a3348]: Setting new playback volume: 75 Feb 11 20:07:26 volumio qobuz-connect[2733]: 20260211 20:07:26.130 [2733.2733] INFO VolumeManager: [0x16a3348]: Setting new mute state: 0 Feb 11 20:07:26 volumio qobuz-connect[2733]: 20260211 20:07:26.130 [2733.2733] INFO QobuzConnect: [0x16a3d18]: Client initialized! Feb 11 20:07:26 volumio qobuz-connect[2733]: 20260211 20:07:26.130 [2733.2733] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Feb 11 20:07:26 volumio volumio[1354]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 11 20:07:26 volumio volumio[1354]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 11 20:07:26 volumio qobuz-connect[2733]: 20260211 20:07:26.135 [2733.2733] INFO LocalConfigManager: [0x16a2c28]: Starting Local Configuration server Feb 11 20:07:26 volumio qobuz-connect[2733]: 20260211 20:07:26.135 [2733.2733] INFO SampleApp: Starting Local configuration server Feb 11 20:07:26 volumio qobuz-connect[2733]: 20260211 20:07:26.136 [2733.2733] INFO SampleApp: Connected to UNIX socket client 0x168d818 Feb 11 20:07:26 volumio qobuz-connect[2733]: 20260211 20:07:26.278 [2733.2733] INFO SampleApp: Playback volume changed: 75 Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:26 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:07:26 volumio volumio[1354]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:07:26 volumio volumio[1354]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:07:26 volumio sudo[2748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 11 20:07:26 volumio sudo[2748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:26 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 11 20:07:26 volumio sudo[2748]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:26 volumio volumio[1354]: info: Executing endpoint tc_getconfig Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 11 20:07:26 volumio vtcs[2751]: STARTING TidalConnect services, version: 1.6.1 Feb 11 20:07:26 volumio vtcs[2751]: STARTED TidalConnect services. Feb 11 20:07:26 volumio volumio[1354]: info: Executing endpoint tc_connect Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 11 20:07:26 volumio volumio[1354]: info: Connecting to TidalConnect Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::servicePushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:26 volumio volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 11 20:07:26 volumio volumio[1354]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 11 20:07:26 volumio volumio[1354]: verbose: CURRENT POSITION 0 Feb 11 20:07:26 volumio volumio[1354]: info: CoreStateMachine::syncState stateService stop Feb 11 20:07:26 volumio volumio[1354]: info: CoreStateMachine::syncState currentStatus stop Feb 11 20:07:26 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:26 volumio volumio[1354]: info: No code Feb 11 20:07:26 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::servicePushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:26 volumio volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 11 20:07:26 volumio volumio[1354]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 11 20:07:26 volumio volumio[1354]: verbose: CURRENT POSITION 0 Feb 11 20:07:26 volumio volumio[1354]: info: CoreStateMachine::syncState stateService stop Feb 11 20:07:26 volumio volumio[1354]: info: CoreStateMachine::syncState currentStatus stop Feb 11 20:07:26 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:26 volumio volumio[1354]: info: No code Feb 11 20:07:26 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:26 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:26 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:26 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:26 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:26 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:27 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 11 20:07:27 volumio volumio[1354]: info: Preparing to save Alsa Options, stopping services first Feb 11 20:07:27 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:27 volumio volumio[1354]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"HDMI 0 Out"},"disallowPush":true} Feb 11 20:07:27 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 20:07:27 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 20:07:27 volumio volumio[1354]: info: Found match in Cards Database: setting mixer for card HDMI 0 Out Feb 11 20:07:27 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:07:27 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:27 volumio vtcs[2751]: [2026-02-11 20:07:27.984] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 11 20:07:27 volumio vtcs[2751]: [2026-02-11 20:07:27.984] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 11 20:07:27 volumio sudo[2774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:07:27 volumio sudo[2774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:27 volumio volumio[1354]: info: Device 0 does not have any Mixer Control Available Feb 11 20:07:27 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:07:27 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:28 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 11 20:07:28 volumio systemd[1]: vtcs.service: Deactivated successfully. Feb 11 20:07:28 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 11 20:07:28 volumio sudo[2780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:07:28 volumio sudo[2780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 11 20:07:28 volumio sudo[2774]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio volumio[1354]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 11 20:07:28 volumio volumio[1354]: info: Disabling external Volume Control Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:28 volumio volumio[1354]: info: Retrieving Cloud Streaming UI Feb 11 20:07:28 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:28 volumio volumio[1354]: info: Preparing to generate the ALSA configuration file Feb 11 20:07:28 volumio sudo[2780]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio sudo[2790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:07:28 volumio sudo[2790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio sudo[2789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:07:28 volumio sudo[2789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio sudo[2799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 20:07:28 volumio sudo[2799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:28 volumio volumio[1354]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 11 20:07:28 volumio volumio[1354]: info: Reading ALSA contributions from plugins. Feb 11 20:07:28 volumio sudo[2790]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio sudo[2806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 20:07:28 volumio sudo[2806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio volumio[1354]: info: Getting Tidal Cloud Configuration Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:28 volumio volumio[1354]: info: Getting Qobuz Cloud Configuration Feb 11 20:07:28 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:28 volumio sudo[2789]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio volumio[1354]: info: Getting Spotify Cloud Configuration Feb 11 20:07:28 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:28 volumio volumio[1354]: info: Saving Spotify Acccount Feb 11 20:07:28 volumio volumio[1354]: info: Got it Feb 11 20:07:28 volumio volumio[1354]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 11 20:07:28 volumio sudo[2806]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 11 20:07:28 volumio sudo[2799]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 20:07:28 volumio sudo[2814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 20:07:28 volumio sudo[2814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio sudo[2815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 20:07:28 volumio sudo[2815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 11 20:07:28 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 11 20:07:28 volumio qobuz-connect[2733]: 20260211 20:07:28.115 [2733.2733] INFO SampleApp: Stopping Local configuration server Feb 11 20:07:28 volumio volumio[1354]: info: Got Tidal Cloud Configuration Feb 11 20:07:28 volumio volumio[1354]: info: Got it Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:28 volumio volumio[1354]: info: Asound.conf file unchanged, so no further update is needed Feb 11 20:07:28 volumio volumio[1354]: info: Output device has changed, restarting MPD Feb 11 20:07:28 volumio volumio[1354]: info: Output device has changed, restarting Shairport Sync Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:28 volumio sudo[2822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 20:07:28 volumio sudo[2822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio sudo[2822]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio sudo[2824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 20:07:28 volumio sudo[2824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio volumio[1354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 20:07:28 volumio volumio[1354]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:28 volumio qobuz-connect[2733]: 20260211 20:07:28.146 [2733.2733] INFO SampleApp: shat down connection on UNIX socket Feb 11 20:07:28 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 11 20:07:28 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:28 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 11 20:07:28 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 20:07:28 volumio sudo[2834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:07:28 volumio sudo[2834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio sudo[2838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:07:28 volumio sudo[2838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio volumio[1354]: info: MPD Permissions set Feb 11 20:07:28 volumio volumio[1354]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 11 20:07:28 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:28 volumio sudo[2845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 20:07:28 volumio sudo[2845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:28 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:28 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 20:07:28 volumio sudo[2814]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:07:28 volumio volumio[1354]: info: Starting Shairport Sync Feb 11 20:07:28 volumio sudo[2815]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio volumio[1354]: info: Executing endpoint qc_getconfig Feb 11 20:07:28 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 11 20:07:28 volumio sudo[2853]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 20:07:28 volumio sudo[2853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 20:07:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 20:07:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 20:07:28 volumio qobuz-connect[2835]: 20260211 20:07:28.218 [2835.2835] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 11 20:07:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 20:07:28 volumio qobuz-connect[2835]: 20260211 20:07:28.220 [2835.2835] INFO VolumeManager: [0x2ee348]: Setting new playback volume: 75 Feb 11 20:07:28 volumio qobuz-connect[2835]: 20260211 20:07:28.220 [2835.2835] INFO VolumeManager: [0x2ee348]: Setting new mute state: 0 Feb 11 20:07:28 volumio qobuz-connect[2835]: 20260211 20:07:28.220 [2835.2835] INFO QobuzConnect: [0x2eed18]: Client initialized! Feb 11 20:07:28 volumio qobuz-connect[2835]: 20260211 20:07:28.220 [2835.2835] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Feb 11 20:07:28 volumio volumio[1354]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 11 20:07:28 volumio volumio[1354]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 11 20:07:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 20:07:28 volumio qobuz-connect[2835]: 20260211 20:07:28.227 [2835.2835] INFO LocalConfigManager: [0x2edc28]: Starting Local Configuration server Feb 11 20:07:28 volumio qobuz-connect[2835]: 20260211 20:07:28.227 [2835.2835] INFO SampleApp: Starting Local configuration server Feb 11 20:07:28 volumio qobuz-connect[2835]: 20260211 20:07:28.227 [2835.2835] INFO SampleApp: Connected to UNIX socket client 0x2d8818 Feb 11 20:07:28 volumio sudo[2845]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio sudo[2834]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio sudo[2862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 20:07:28 volumio sudo[2862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:28 volumio sudo[2838]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 20:07:28 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 20:07:28 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:07:28 volumio systemd[1]: shairport-sync.service: Consumed 1.523s CPU time. Feb 11 20:07:28 volumio sudo[2859]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 20:07:28 volumio sudo[2859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 20:07:28 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:07:28 volumio sudo[2853]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio sudo[2859]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio volumio[1354]: info: Shairport-Sync Started Feb 11 20:07:28 volumio qobuz-connect[2835]: 20260211 20:07:28.276 [2835.2835] INFO SampleApp: Stopping Local configuration server Feb 11 20:07:28 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 11 20:07:28 volumio mpd[2868]: 2026-02-11T20:07:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 20:07:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 20:07:28 volumio sudo[2824]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:28 volumio volumio[1354]: error: updateQueue error: null Feb 11 20:07:29 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:29 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:29 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:29 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:29 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:07:29 volumio volumio[1354]: info: TidalConnect service started! Feb 11 20:07:30 volumio qobuz-connect[2835]: 20260211 20:07:30.237 [2835.2835] INFO SampleApp: shat down connection on UNIX socket Feb 11 20:07:30 volumio volumio[1354]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 11 20:07:30 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:07:30 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:30 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 11 20:07:30 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 20:07:30 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 20:07:30 volumio sudo[2862]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:30 volumio volumio[1354]: info: Executing endpoint qc_getconfig Feb 11 20:07:30 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 11 20:07:30 volumio qobuz-connect[2889]: 20260211 20:07:30.283 [2889.2889] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 11 20:07:30 volumio volumio[1354]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 11 20:07:30 volumio volumio[1354]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 11 20:07:30 volumio qobuz-connect[2889]: 20260211 20:07:30.285 [2889.2889] INFO VolumeManager: [0x12f8348]: Setting new playback volume: 75 Feb 11 20:07:30 volumio qobuz-connect[2889]: 20260211 20:07:30.285 [2889.2889] INFO VolumeManager: [0x12f8348]: Setting new mute state: 0 Feb 11 20:07:30 volumio qobuz-connect[2889]: 20260211 20:07:30.285 [2889.2889] INFO QobuzConnect: [0x12f8d18]: Client initialized! Feb 11 20:07:30 volumio qobuz-connect[2889]: 20260211 20:07:30.285 [2889.2889] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Feb 11 20:07:30 volumio qobuz-connect[2889]: 20260211 20:07:30.290 [2889.2889] INFO LocalConfigManager: [0x12f7c28]: Starting Local Configuration server Feb 11 20:07:30 volumio qobuz-connect[2889]: 20260211 20:07:30.290 [2889.2889] INFO SampleApp: Starting Local configuration server Feb 11 20:07:30 volumio qobuz-connect[2889]: 20260211 20:07:30.290 [2889.2889] INFO SampleApp: Connected to UNIX socket client 0x12e2818 Feb 11 20:07:30 volumio qobuz-connect[2889]: 20260211 20:07:30.433 [2889.2889] INFO SampleApp: Playback volume changed: 75 Feb 11 20:07:30 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:31 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:07:31 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:07:31 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:07:31 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:07:31 volumio volumio[1354]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:07:31 volumio volumio[1354]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:07:31 volumio volumio[1354]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:07:31 volumio volumio[1354]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:07:31 volumio sudo[2912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 11 20:07:31 volumio sudo[2912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:31 volumio sudo[2916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 11 20:07:31 volumio sudo[2916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:31 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 11 20:07:31 volumio sudo[2912]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:31 volumio sudo[2916]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:31 volumio volumio[1354]: info: Executing endpoint tc_getconfig Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 11 20:07:31 volumio vtcs[2932]: STARTING TidalConnect services, version: 1.6.1 Feb 11 20:07:31 volumio vtcs[2932]: STARTED TidalConnect services. Feb 11 20:07:31 volumio volumio[1354]: info: Executing endpoint tc_connect Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 11 20:07:31 volumio volumio[1354]: info: Connecting to TidalConnect Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::servicePushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:31 volumio volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 11 20:07:31 volumio volumio[1354]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 11 20:07:31 volumio volumio[1354]: verbose: CURRENT POSITION 0 Feb 11 20:07:31 volumio volumio[1354]: info: CoreStateMachine::syncState stateService stop Feb 11 20:07:31 volumio volumio[1354]: info: CoreStateMachine::syncState currentStatus stop Feb 11 20:07:31 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:31 volumio volumio[1354]: info: No code Feb 11 20:07:31 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::servicePushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:31 volumio volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 11 20:07:31 volumio volumio[1354]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 11 20:07:31 volumio volumio[1354]: verbose: CURRENT POSITION 0 Feb 11 20:07:31 volumio volumio[1354]: info: CoreStateMachine::syncState stateService stop Feb 11 20:07:31 volumio volumio[1354]: info: CoreStateMachine::syncState currentStatus stop Feb 11 20:07:31 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:31 volumio volumio[1354]: info: No code Feb 11 20:07:31 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:31 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:31 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:31 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:31 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:31 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:31 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:31 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:31 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:07:31 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:07:31 volumio volumio[1354]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:07:31 volumio volumio[1354]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:07:31 volumio sudo[2958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 11 20:07:31 volumio sudo[2958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:31 volumio sudo[2958]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:32 volumio volumio[1354]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 11 20:07:32 volumio volumio[1354]: info: CoreStateMachine::getcurrentVolume Feb 11 20:07:32 volumio volumio[1354]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 20:07:32 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:07:32 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:07:32 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:32 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:07:34 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:34 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:34 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:34 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:34 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:07:34 volumio volumio[1354]: info: TidalConnect service started! Feb 11 20:07:34 volumio volumio[1354]: info: TidalConnect service started! Feb 11 20:07:34 volumio volumio[1354]: info: TidalConnect service started! Feb 11 20:07:39 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:39 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:39 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:39 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:39 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:07:44 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:44 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:44 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:44 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:44 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:07:44 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 11 20:07:44 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 20:07:44 volumio volumio-remote-updater[1015]: Test mode disabled Feb 11 20:07:44 volumio volumio-remote-updater[1015]: Alpha mode disabled Feb 11 20:07:44 volumio volumio-remote-updater[1015]: Alpha legacy test mode disabled Feb 11 20:07:44 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 11 20:07:44 volumio volumio[1354]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 20:07:44 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 20:07:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 11 20:07:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 20:07:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 11 20:07:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 20:07:48 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Feb 11 20:07:48 volumio volumio-remote-updater[1015]: Test mode disabled Feb 11 20:07:48 volumio volumio-remote-updater[1015]: Alpha mode disabled Feb 11 20:07:48 volumio volumio-remote-updater[1015]: Alpha legacy test mode disabled Feb 11 20:07:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 11 20:07:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 11 20:07:48 volumio volumio-remote-updater[1015]: Test mode disabled Feb 11 20:07:48 volumio volumio-remote-updater[1015]: Alpha mode disabled Feb 11 20:07:48 volumio volumio-remote-updater[1015]: Alpha legacy test mode disabled Feb 11 20:07:48 volumio volumio[1354]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 20:07:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 20:07:48 volumio volumio[1354]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 20:07:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 20:07:49 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:49 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:49 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:49 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:49 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:07:53 volumio volumio[1354]: info: Received OAUTH Data Feb 11 20:07:53 volumio volumio[1354]: info: Refreshing TIDAL token Feb 11 20:07:54 volumio sudo[3010]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 20:07:54 volumio sudo[3010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:54 volumio sudo[3012]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 20:07:54 volumio sudo[3010]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:54 volumio sudo[3012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:54 volumio sudo[3012]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:54 volumio volumio[1354]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.5 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Feb 11 20:07:54 volumio volumio[1354]: info: Received Get System Info Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 20:07:54 volumio volumio[1354]: info: Discovery: Getting this device information Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 20:07:54 volumio volumio-remote-updater[1015]: Test mode disabled Feb 11 20:07:54 volumio volumio-remote-updater[1015]: Alpha mode disabled Feb 11 20:07:54 volumio volumio-remote-updater[1015]: Alpha legacy test mode disabled Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:54 volumio volumio[1354]: info: Retrieving Cloud Streaming UI Feb 11 20:07:54 volumio volumio[1354]: info: Getting Tidal Cloud Configuration Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:54 volumio volumio[1354]: info: Getting Qobuz Cloud Configuration Feb 11 20:07:54 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:54 volumio volumio[1354]: info: Getting Spotify Cloud Configuration Feb 11 20:07:54 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:54 volumio volumio[1354]: info: Saving Spotify Acccount Feb 11 20:07:54 volumio volumio[1354]: info: Got it Feb 11 20:07:54 volumio volumio[1354]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 11 20:07:54 volumio volumio[1354]: info: Got Tidal Cloud Configuration Feb 11 20:07:54 volumio volumio[1354]: info: Got it Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:54 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:54 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:54 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:54 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:54 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:07:54 volumio volumio[1354]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 20:07:54 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 20:07:55 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 11 20:07:55 volumio volumio[1354]: info: Loading i18n strings for locale it Feb 11 20:07:55 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 11 20:07:55 volumio volumio[1354]: info: Setting timezone to Europe/Rome Feb 11 20:07:55 volumio sudo[3025]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 11 20:07:55 volumio sudo[3025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:55 volumio sudo[3025]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:55 volumio sudo[3029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Feb 11 20:07:55 volumio sudo[3029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:55 volumio sudo[3029]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:55 volumio sudo[3033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 11 20:07:55 volumio sudo[3033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:55 volumio sudo[3033]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:55 volumio sudo[3037]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Feb 11 20:07:55 volumio sudo[3037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:55 volumio dbus-daemon[1003]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.89' (uid=0 pid=3038 comm="/usr/bin/timedatectl set-timezone Europe/Rome") Feb 11 20:07:55 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Feb 11 20:07:55 volumio dbus-daemon[1003]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 11 20:07:55 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Feb 11 20:07:55 volumio sudo[3037]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:55 volumio volumio[1354]: Updating browse sources language Feb 11 20:07:55 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 20:07:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 20:07:56 volumio volumio[1354]: info: Fetching Streaming Services browse cache Feb 11 20:07:56 volumio volumio[1354]: info: TIDAL Access Token successfully retrieved Feb 11 20:07:56 volumio volumio[1354]: info: Successfully retrieved User Session From TIDAL Feb 11 20:07:56 volumio volumio[1354]: info: Adding TIDAL to Browse Sources Feb 11 20:07:56 volumio volumio[1354]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 20:07:56 volumio volumio[1354]: info: [1770836876422] CoreMusicLibrary::Adding element TIDAL Feb 11 20:07:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 20:07:56 volumio volumio[1354]: Cannot find translation for source TIDAL Feb 11 20:07:56 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:56 volumio volumio[1354]: info: Retrieving Cloud Streaming UI Feb 11 20:07:56 volumio volumio[1354]: info: Getting Tidal Cloud Configuration Feb 11 20:07:56 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:56 volumio volumio[1354]: info: Getting Qobuz Cloud Configuration Feb 11 20:07:56 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:56 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:56 volumio volumio[1354]: info: Getting Spotify Cloud Configuration Feb 11 20:07:56 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:07:56 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:56 volumio volumio[1354]: info: Saving Spotify Acccount Feb 11 20:07:56 volumio volumio[1354]: info: Got it Feb 11 20:07:56 volumio volumio[1354]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 11 20:07:56 volumio volumio[1354]: info: Got Tidal Cloud Configuration Feb 11 20:07:56 volumio volumio[1354]: info: Got it Feb 11 20:07:56 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:07:56 volumio volumio[1354]: info: Successfully retrieved User Subscription From TIDAL Feb 11 20:07:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 20:07:57 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Feb 11 20:07:57 volumio volumio[1354]: info: Loading i18n strings for locale it Feb 11 20:07:57 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Feb 11 20:07:57 volumio volumio[1354]: info: Setting timezone to Europe/Rome Feb 11 20:07:57 volumio sudo[3044]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Feb 11 20:07:57 volumio sudo[3044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:57 volumio sudo[3044]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:57 volumio sudo[3048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Rome /etc/localtime Feb 11 20:07:57 volumio sudo[3048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:57 volumio sudo[3048]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:57 volumio sudo[3052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Feb 11 20:07:57 volumio sudo[3052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:57 volumio sudo[3052]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:57 volumio sudo[3056]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Rome Feb 11 20:07:57 volumio sudo[3056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:57 volumio sudo[3056]: pam_unix(sudo:session): session closed for user root Feb 11 20:07:57 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Feb 11 20:07:57 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 11 20:07:57 volumio volumio[1354]: Updating browse sources language Feb 11 20:07:57 volumio volumio[1354]: Cannot find translation for source TIDAL Feb 11 20:07:57 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 20:07:57 volumio volumio[1354]: Cannot find translation for source TIDAL Feb 11 20:07:57 volumio sudo[3059]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 11 20:07:57 volumio sudo[3059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:07:58 volumio volumio[1354]: info: Fetching Streaming Services browse cache Feb 11 20:07:59 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:59 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:59 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:07:59 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:07:59 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:08:00 volumio sudo[3059]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:00 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Feb 11 20:08:00 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Feb 11 20:08:00 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 11 20:08:00 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:08:00 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 20:08:00 volumio volumio[1354]: info: Received Get System Info Feb 11 20:08:00 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 20:08:00 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 20:08:00 volumio volumio[1354]: info: Discovery: Getting this device information Feb 11 20:08:00 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:00 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 20:08:00 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 20:08:00 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 11 20:08:01 volumio volumio[1354]: info: Preparing to save Alsa Options, stopping services first Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:01 volumio volumio[1354]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"HDMI 0 Out"},"disallowPush":true} Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 20:08:01 volumio volumio[1354]: info: Found match in Cards Database: setting mixer for card HDMI 0 Out Feb 11 20:08:01 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:01 volumio vtcs[2932]: [2026-02-11 20:08:01.729] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 11 20:08:01 volumio vtcs[2932]: [2026-02-11 20:08:01.729] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 11 20:08:01 volumio sudo[3088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:08:01 volumio sudo[3088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio volumio[1354]: info: Device 0 does not have any Mixer Control Available Feb 11 20:08:01 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:01 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 11 20:08:01 volumio sudo[3094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:08:01 volumio sudo[3094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 11 20:08:01 volumio volumio[1354]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 11 20:08:01 volumio volumio[1354]: info: Disabling external Volume Control Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:01 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:08:01 volumio systemd[1]: vtcs.service: Deactivated successfully. Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:01 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 11 20:08:01 volumio volumio[1354]: info: Preparing to generate the ALSA configuration file Feb 11 20:08:01 volumio sudo[3088]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:01 volumio sudo[3094]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:01 volumio sudo[3101]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:08:01 volumio sudo[3101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio sudo[3103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:08:01 volumio sudo[3103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio sudo[3112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 20:08:01 volumio sudo[3112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio sudo[3103]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:01 volumio volumio[1354]: info: Retrieving Cloud Streaming UI Feb 11 20:08:01 volumio sudo[3101]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:01 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:08:01 volumio volumio[1354]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 11 20:08:01 volumio volumio[1354]: info: Reading ALSA contributions from plugins. Feb 11 20:08:01 volumio sudo[3119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 20:08:01 volumio sudo[3119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio sudo[3112]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:01 volumio sudo[3123]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 20:08:01 volumio sudo[3123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio sudo[3119]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 20:08:01 volumio sudo[3128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 20:08:01 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 11 20:08:01 volumio sudo[3128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio qobuz-connect[2889]: 20260211 20:08:01.868 [2889.2889] INFO SampleApp: Stopping Local configuration server Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 11 20:08:01 volumio volumio[1354]: info: Getting Tidal Cloud Configuration Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:01 volumio volumio[1354]: info: Getting Qobuz Cloud Configuration Feb 11 20:08:01 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:01 volumio volumio[1354]: info: Getting Spotify Cloud Configuration Feb 11 20:08:01 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:01 volumio volumio[1354]: info: Saving Spotify Acccount Feb 11 20:08:01 volumio volumio[1354]: info: Got it Feb 11 20:08:01 volumio volumio[1354]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 11 20:08:01 volumio volumio[1354]: info: Got Tidal Cloud Configuration Feb 11 20:08:01 volumio volumio[1354]: info: Got it Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:01 volumio volumio[1354]: info: Asound.conf file unchanged, so no further update is needed Feb 11 20:08:01 volumio volumio[1354]: info: Output device has changed, restarting MPD Feb 11 20:08:01 volumio volumio[1354]: info: Output device has changed, restarting Shairport Sync Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:08:01 volumio sudo[3135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 20:08:01 volumio sudo[3135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio sudo[3135]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:01 volumio volumio[1354]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 20:08:01 volumio volumio[1354]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:01 volumio sudo[3137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 20:08:01 volumio sudo[3137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:01 volumio sudo[3147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:08:01 volumio sudo[3147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 11 20:08:01 volumio sudo[3149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 11 20:08:01 volumio sudo[3149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio volumio[1354]: info: MPD Permissions set Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:01 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 20:08:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 20:08:01 volumio sudo[3157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 11 20:08:01 volumio sudo[3157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 20:08:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 20:08:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 20:08:01 volumio sudo[3147]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:01 volumio volumio[1354]: info: Starting Shairport Sync Feb 11 20:08:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 20:08:01 volumio sudo[3167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 20:08:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 20:08:01 volumio sudo[3167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:02 volumio sudo[3157]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:02 volumio sudo[3149]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:02 volumio sudo[3171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 11 20:08:02 volumio sudo[3171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 20:08:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 20:08:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:08:02 volumio systemd[1]: shairport-sync.service: Consumed 1.548s CPU time. Feb 11 20:08:02 volumio sudo[3168]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 20:08:02 volumio sudo[3168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 20:08:02 volumio sudo[3168]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 20:08:02 volumio sudo[3167]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:02 volumio volumio[1354]: info: Shairport-Sync Started Feb 11 20:08:02 volumio qobuz-connect[2889]: 20260211 20:08:02.322 [2889.2889] INFO SampleApp: shat down connection on UNIX socket Feb 11 20:08:02 volumio volumio[1354]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 11 20:08:02 volumio volumio[1354]: info: QobuzConnect: setDeactiveState invoked Feb 11 20:08:02 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:02 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 11 20:08:02 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 20:08:02 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 11 20:08:02 volumio sudo[3128]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:02 volumio sudo[3123]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:02 volumio sudo[3171]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:02 volumio volumio[1354]: info: Executing endpoint qc_getconfig Feb 11 20:08:02 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 11 20:08:02 volumio qobuz-connect[3190]: 20260211 20:08:02.388 [3190.3190] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 11 20:08:02 volumio qobuz-connect[3190]: 20260211 20:08:02.390 [3190.3190] INFO VolumeManager: [0x53f348]: Setting new playback volume: 75 Feb 11 20:08:02 volumio qobuz-connect[3190]: 20260211 20:08:02.390 [3190.3190] INFO VolumeManager: [0x53f348]: Setting new mute state: 0 Feb 11 20:08:02 volumio qobuz-connect[3190]: 20260211 20:08:02.390 [3190.3190] INFO QobuzConnect: [0x53fd18]: Client initialized! Feb 11 20:08:02 volumio qobuz-connect[3190]: 20260211 20:08:02.390 [3190.3190] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Feb 11 20:08:02 volumio volumio[1354]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 11 20:08:02 volumio volumio[1354]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 11 20:08:02 volumio qobuz-connect[3190]: 20260211 20:08:02.404 [3190.3190] INFO LocalConfigManager: [0x53ec28]: Starting Local Configuration server Feb 11 20:08:02 volumio qobuz-connect[3190]: 20260211 20:08:02.404 [3190.3190] INFO SampleApp: Starting Local configuration server Feb 11 20:08:02 volumio qobuz-connect[3190]: 20260211 20:08:02.404 [3190.3190] INFO SampleApp: Connected to UNIX socket client 0x529818 Feb 11 20:08:02 volumio qobuz-connect[3190]: 20260211 20:08:02.537 [3190.3190] INFO SampleApp: Playback volume changed: 75 Feb 11 20:08:02 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:02 volumio mpd[3175]: 2026-02-11T20:08:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 20:08:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 20:08:02 volumio sudo[3137]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:02 volumio volumio[1354]: error: updateQueue error: null Feb 11 20:08:04 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:04 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:04 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:04 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:04 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:08:04 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Feb 11 20:08:04 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Feb 11 20:08:04 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Feb 11 20:08:04 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Feb 11 20:08:04 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Feb 11 20:08:04 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:08:04 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:08:04 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:08:04 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:08:04 volumio volumio[1354]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:08:04 volumio volumio[1354]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:08:04 volumio volumio[1354]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:08:04 volumio volumio[1354]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:08:04 volumio sudo[3226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 11 20:08:04 volumio sudo[3226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:04 volumio sudo[3228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 11 20:08:04 volumio sudo[3228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:04 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 11 20:08:04 volumio sudo[3228]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:04 volumio sudo[3226]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:04 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:08:05 volumio volumio[1354]: info: Executing endpoint tc_getconfig Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 11 20:08:05 volumio vtcs[3232]: STARTING TidalConnect services, version: 1.6.1 Feb 11 20:08:05 volumio vtcs[3232]: STARTED TidalConnect services. Feb 11 20:08:05 volumio volumio[1354]: info: Executing endpoint tc_connect Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 11 20:08:05 volumio volumio[1354]: info: Connecting to TidalConnect Feb 11 20:08:05 volumio volumio[1354]: info: TidalConnect service stoped! Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::servicePushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:05 volumio volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 11 20:08:05 volumio volumio[1354]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 11 20:08:05 volumio volumio[1354]: verbose: CURRENT POSITION 0 Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::syncState stateService stop Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::syncState currentStatus stop Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:05 volumio volumio[1354]: info: No code Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::servicePushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:05 volumio volumio[1354]: info: CorePlayQueue::getTrack 0 Feb 11 20:08:05 volumio volumio[1354]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Feb 11 20:08:05 volumio volumio[1354]: verbose: CURRENT POSITION 0 Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::syncState stateService stop Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::syncState currentStatus stop Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:05 volumio volumio[1354]: info: No code Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:05 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:08:05 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:08:05 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:08:05 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:08:05 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:08:05 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:08:05 volumio volumio[1354]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:08:05 volumio volumio[1354]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 11 20:08:05 volumio sudo[3258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 11 20:08:05 volumio sudo[3258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:05 volumio sudo[3258]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::getcurrentVolume Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 20:08:05 volumio volumio[1354]: info: CoreStateMachine::pushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioPushState Feb 11 20:08:05 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:05 volumio volumio[1354]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gEX2MDi1kBMfzLbFmPQFhVYucFm1.cdb0bc9dde83f68cbbc5e8c42e3bb29d.state.status' Feb 11 20:08:07 volumio volumio[1354]: info: TidalConnect service started! Feb 11 20:08:07 volumio volumio[1354]: info: TidalConnect service started! Feb 11 20:08:08 volumio volumio[1354]: info: TidalConnect service started! Feb 11 20:08:09 volumio volumio[1354]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Feb 11 20:08:09 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Feb 11 20:08:09 volumio volumio[1354]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'value') Feb 11 20:08:14 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:14 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:14 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:14 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:14 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 20:08:14 volumio volumio[1354]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 11 20:08:14 volumio volumio[1354]: info: Received Get System Version Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 20:08:14 volumio volumio[1354]: info: Received Get System Info Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 20:08:14 volumio volumio[1354]: info: Discovery: Getting this device information Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::Reload Ui Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Feb 11 20:08:14 volumio volumio[1354]: info: Wizard terminated Successfully Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::volumiosetStartupVolume Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 20:08:14 volumio volumio[1354]: info: Received Get System Info Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 20:08:14 volumio volumio[1354]: info: Discovery: Getting this device information Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 20:08:14 volumio volumio5-onboarding[1807]: time=2026-02-11T19:08:14.672Z level=INFO msg="disabling BLE discovery" Feb 11 20:08:14 volumio volumio5-onboarding[1807]: time=2026-02-11T19:08:14.675Z level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=D8:3A:DD:ED:1A:14%00 Feb 11 20:08:14 volumio sudo[3281]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 20:08:14 volumio sudo[3281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:14 volumio sudo[3281]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:14 volumio sudo[3283]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 20:08:14 volumio sudo[3283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:14 volumio sudo[3283]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:14 volumio volumio[1354]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.5 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 11 20:08:14 volumio sudo[3287]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 20:08:14 volumio sudo[3287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:14 volumio sudo[3287]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:14 volumio sudo[3289]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 20:08:14 volumio sudo[3289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 20:08:14 volumio sudo[3289]: pam_unix(sudo:session): session closed for user root Feb 11 20:08:14 volumio volumio[1354]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.5 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 20:08:14 volumio volumio[1354]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 11 20:08:14 volumio volumio[1354]: info: Received Get System Info Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 20:08:14 volumio volumio[1354]: info: Discovery: Getting this device information Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:14 volumio volumio[1354]: info: Listing playlists Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 11 20:08:14 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 20:08:15 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 20:08:15 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 11 20:08:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 20:08:16 volumio volumio[1354]: info: Received Get System Info Feb 11 20:08:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 20:08:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 20:08:16 volumio volumio[1354]: info: Discovery: Getting this device information Feb 11 20:08:16 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:16 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 20:08:17 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 20:08:17 volumio volumio[1354]: info: Received Get System Info Feb 11 20:08:17 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 20:08:17 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 20:08:17 volumio volumio[1354]: info: Discovery: Getting this device information Feb 11 20:08:17 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:17 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 20:08:17 volumio volumio[1354]: info: Executing endpoint metavolumio Feb 11 20:08:17 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 20:08:19 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:19 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:19 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:19 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:19 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:08:24 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:24 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:24 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:24 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:24 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:08:25 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 11 20:08:25 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 20:08:25 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 20:08:25 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 11 20:08:25 volumio volumio-remote-updater[1015]: Test mode disabled Feb 11 20:08:25 volumio volumio-remote-updater[1015]: Alpha mode disabled Feb 11 20:08:25 volumio volumio-remote-updater[1015]: Alpha legacy test mode disabled Feb 11 20:08:25 volumio volumio[1354]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 20:08:25 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 20:08:27 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 11 20:08:27 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 20:08:27 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Feb 11 20:08:27 volumio volumio-remote-updater[1015]: Test mode disabled Feb 11 20:08:27 volumio volumio-remote-updater[1015]: Alpha mode disabled Feb 11 20:08:27 volumio volumio-remote-updater[1015]: Alpha legacy test mode disabled Feb 11 20:08:27 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 11 20:08:27 volumio volumio[1354]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 20:08:27 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 20:08:29 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:29 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:29 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:29 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:29 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:08:32 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 20:08:32 volumio volumio[1354]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 11 20:08:32 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 11 20:08:32 volumio volumio[1354]: info: Received Get System Version Feb 11 20:08:32 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 20:08:32 volumio volumio[1354]: info: Received Get System Info Feb 11 20:08:32 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 20:08:32 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 20:08:32 volumio volumio[1354]: info: Discovery: Getting this device information Feb 11 20:08:32 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:32 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 20:08:34 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:34 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:34 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:34 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:34 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:08:39 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:39 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:39 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:39 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:39 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:08:44 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Feb 11 20:08:44 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:44 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:44 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:44 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:44 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:08:48 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 20:08:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 20:08:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 20:08:48 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 11 20:08:49 volumio volumio[1354]: info: Received Get System Version Feb 11 20:08:49 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 20:08:49 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:49 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:49 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:49 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:49 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:08:53 volumio volumio[1354]: info: CoreCommandRouter::volumioGetState Feb 11 20:08:54 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:54 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:54 volumio volumio[1354]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 11 20:08:54 volumio volumio[1354]: info: Discovery: Restarting Browsing Feb 11 20:08:54 volumio volumio[1354]: info: Discovery: Restart already pending, ignoring duplicate call Feb 11 20:08:55 volumio volumio[1354]: info: Executing endpoint metavolumio Feb 11 20:08:55 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:56 volumio volumio[1354]: info: Retrieving Cloud Streaming UI Feb 11 20:08:56 volumio volumio[1354]: info: Getting Tidal Cloud Configuration Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:56 volumio volumio[1354]: info: Getting Qobuz Cloud Configuration Feb 11 20:08:56 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:56 volumio volumio[1354]: info: Getting Spotify Cloud Configuration Feb 11 20:08:56 volumio volumio[1354]: info: Asking plugin for UI Config Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:56 volumio volumio[1354]: info: Saving Spotify Acccount Feb 11 20:08:56 volumio volumio[1354]: info: Got it Feb 11 20:08:56 volumio volumio[1354]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 11 20:08:56 volumio volumio[1354]: info: Got Tidal Cloud Configuration Feb 11 20:08:56 volumio volumio[1354]: info: Got it Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 20:08:56 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 11 20:08:58 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 11 20:08:58 volumio volumio[1354]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 11 20:08:58 volumio volumio[1354]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 20:08:58 volumio volumio[1354]: Error: getaddrinfo -3007 Feb 11 20:08:58 volumio volumio[1354]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Feb 11 20:08:58 volumio volumio[1354]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Feb 11 20:08:58 volumio volumio[1354]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Feb 11 20:08:58 volumio volumio[1354]: code: -3007, Feb 11 20:08:58 volumio volumio[1354]: errno: -3007, Feb 11 20:08:58 volumio volumio[1354]: syscall: 'getaddrinfo' Feb 11 20:08:58 volumio volumio[1354]: } Feb 11 20:08:58 volumio volumio[1354]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 20:08:59 volumio sudo[3423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 20:07' Feb 11 20:08:59 volumio sudo[3423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"