May 14 16:23:00 volumio volumio[1176]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 14 16:23:00 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 14 16:23:00 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:23:00 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:23:00 volumio volumio[1176]: info: Starting MyVolumio Remote Streaming Endpoints May 14 16:23:00 volumio volumio[1176]: info: MyVolumio not started May 14 16:23:00 volumio volumio[1176]: info: Initializing device activation check May 14 16:23:00 volumio volumio[1176]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 14 16:23:00 volumio volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 14 16:23:09 volumio dbus-daemon[844]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.18' (uid=0 pid=1995 comm="/usr/lib/chromium/chromium --show-component-extens") May 14 16:23:09 volumio volumio[1176]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 14 16:23:09 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 14 16:23:09 volumio volumio[1176]: info: Streaming services startup May 14 16:23:09 volumio volumio[1176]: info: Starting Streaming Daemon May 14 16:23:09 volumio systemd[1]: Starting bluetooth.service - Bluetooth service... May 14 16:23:09 volumio sudo[2063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 14 16:23:09 volumio sudo[2063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:09 volumio volumio[1176]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 14 16:23:09 volumio sudo[2063]: pam_unix(sudo:session): session closed for user root May 14 16:23:09 volumio sudo[2080]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 16:23:09 volumio sudo[2080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:09 volumio sudo[2082]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 16:23:09 volumio sudo[2082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:09 volumio sudo[2080]: pam_unix(sudo:session): session closed for user root May 14 16:23:09 volumio volumio[1176]: error: Cannot start Volumio Streaming Daemon May 14 16:23:09 volumio bluetoothd[2061]: Bluetooth daemon 5.72 May 14 16:23:09 volumio volumio[1176]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 14 16:23:09 volumio volumio[1176]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 14 16:23:09 volumio dbus-daemon[844]: [system] Successfully activated service 'org.bluez' May 14 16:23:09 volumio systemd[1]: Started bluetooth.service - Bluetooth service. May 14 16:23:09 volumio bluetoothd[2061]: Starting SDP server May 14 16:23:09 volumio bluetoothd[2061]: Excluding (cli) wiimote May 14 16:23:09 volumio bluetoothd[2061]: Excluding (cli) sap May 14 16:23:09 volumio bluetoothd[2061]: Excluding (cli) midi May 14 16:23:09 volumio bluetoothd[2061]: Excluding (cli) battery May 14 16:23:09 volumio sudo[2082]: pam_unix(sudo:session): session closed for user root May 14 16:23:09 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 14 16:23:09 volumio kernel: Bluetooth: BNEP filters: protocol multicast May 14 16:23:09 volumio kernel: Bluetooth: BNEP socket layer initialized May 14 16:23:09 volumio bluetoothd[2061]: Bluetooth management interface 1.23 initialized May 14 16:23:09 volumio dbus-daemon[844]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.19' (uid=0 pid=2061 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") May 14 16:23:09 volumio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... May 14 16:23:09 volumio sudo[2099]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 14 16:23:09 volumio sudo[2099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:09 volumio sudo[2099]: pam_unix(sudo:session): session closed for user root May 14 16:23:09 volumio volumio[1176]: info: Upmpdcli Daemon Started May 14 16:23:09 volumio dbus-daemon[844]: [system] Successfully activated service 'org.freedesktop.hostname1' May 14 16:23:09 volumio systemd[1]: Started systemd-hostnamed.service - Hostname Service. May 14 16:23:13 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 16:23:13 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:23:13 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 14 16:23:13 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 14 16:23:14 volumio volumio-remote-updater[852]: No test mode May 14 16:23:14 volumio volumio-remote-updater[852]: No alpha test mode May 14 16:23:14 volumio volumio[1176]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 14 16:23:14 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 14 16:23:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 16:23:15 volumio volumio[1176]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 14 16:23:15 volumio volumio[1176]: info: CoreCommandRouter::volumioGetState May 14 16:23:15 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 May 14 16:23:20 volumio volumio[1176]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 14 16:23:20 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:23:20 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:23:20 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 14 16:23:20 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 14 16:23:20 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 14 16:23:20 volumio volumio[1176]: info: CoreCommandRouter::volumioGetBrowseSources May 14 16:23:20 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::volumioGetVisibleSources May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 14 16:23:21 volumio volumio[1176]: info: Received Get System Info May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 16:23:21 volumio volumio[1176]: info: Discovery: Getting this device information May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::volumioGetState May 14 16:23:21 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 16:23:21 volumio volumio-remote-updater[852]: No test mode May 14 16:23:21 volumio volumio-remote-updater[852]: No alpha test mode May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:21 volumio volumio[1176]: info: Retrieving Cloud Streaming UI May 14 16:23:21 volumio volumio[1176]: info: Getting Tidal Cloud Configuration May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:21 volumio volumio[1176]: info: Getting Qobuz Cloud Configuration May 14 16:23:21 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:21 volumio volumio[1176]: info: Getting Spotify Cloud Configuration May 14 16:23:21 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:21 volumio volumio[1176]: info: Saving Spotify Acccount May 14 16:23:21 volumio volumio[1176]: info: Got Tidal Cloud Configuration May 14 16:23:21 volumio volumio[1176]: info: Got it May 14 16:23:21 volumio volumio[1176]: info: Got it May 14 16:23:21 volumio volumio[1176]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 14 16:23:21 volumio volumio[1176]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 14 16:23:21 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:21 volumio volumio[1176]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 14 16:23:22 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 16:23:22 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 14 16:23:22 volumio volumio[1176]: info: Loading i18n strings for locale en May 14 16:23:22 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 14 16:23:22 volumio volumio[1176]: info: Setting timezone to UTC May 14 16:23:22 volumio sudo[2245]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 14 16:23:22 volumio sudo[2245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:22 volumio sudo[2245]: pam_unix(sudo:session): session closed for user root May 14 16:23:22 volumio sudo[2249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime May 14 16:23:22 volumio sudo[2249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:22 volumio sudo[2249]: pam_unix(sudo:session): session closed for user root May 14 16:23:22 volumio sudo[2253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 14 16:23:22 volumio sudo[2253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:23 volumio sudo[2253]: pam_unix(sudo:session): session closed for user root May 14 16:23:23 volumio sudo[2257]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC May 14 16:23:23 volumio sudo[2257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:23 volumio dbus-daemon[844]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.22' (uid=0 pid=2258 comm="/usr/bin/timedatectl set-timezone UTC") May 14 16:23:23 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 14 16:23:23 volumio dbus-daemon[844]: [system] Successfully activated service 'org.freedesktop.timedate1' May 14 16:23:23 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 14 16:23:23 volumio sudo[2257]: pam_unix(sudo:session): session closed for user root May 14 16:23:23 volumio volumio[1176]: info: Fetching Streaming Services browse cache May 14 16:23:23 volumio volumio[1176]: Updating browse sources language May 14 16:23:23 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 16:23:29 volumio volumio[1176]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 14 16:23:29 volumio volumio[1176]: info: Completed starting MyVolumio Plugin May 14 16:23:29 volumio volumio[1176]: [Metrics] CommandRouter: 53s 974.74ms May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::volumiosetStartupVolume May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::Close All Modals sent May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::Close All Modals sent May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:29 volumio volumio[1176]: info: Retrieving Cloud Streaming UI May 14 16:23:29 volumio volumio[1176]: info: Getting Tidal Cloud Configuration May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:29 volumio volumio[1176]: info: Getting Qobuz Cloud Configuration May 14 16:23:29 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:29 volumio volumio[1176]: info: Getting Spotify Cloud Configuration May 14 16:23:29 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:29 volumio volumio[1176]: info: Saving Spotify Acccount May 14 16:23:29 volumio volumio[1176]: info: Got Tidal Cloud Configuration May 14 16:23:29 volumio volumio[1176]: info: Got it May 14 16:23:29 volumio volumio[1176]: info: Got it May 14 16:23:29 volumio volumio[1176]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:29 volumio volumio[1176]: info: Retrieving Cloud Streaming UI May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:29 volumio volumio[1176]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 14 16:23:29 volumio volumio[1176]: info: Getting Tidal Cloud Configuration May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:29 volumio volumio[1176]: info: Getting Qobuz Cloud Configuration May 14 16:23:29 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:29 volumio volumio[1176]: info: Getting Spotify Cloud Configuration May 14 16:23:29 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:29 volumio volumio[1176]: info: Saving Spotify Acccount May 14 16:23:29 volumio volumio[1176]: info: Got Tidal Cloud Configuration May 14 16:23:29 volumio volumio[1176]: info: Got it May 14 16:23:29 volumio volumio[1176]: info: Got it May 14 16:23:29 volumio volumio[1176]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 14 16:23:29 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:29 volumio volumio[1176]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 14 16:23:30 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 14 16:23:30 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 14 16:23:30 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 14 16:23:30 volumio volumio[1176]: info: Version has changed, forcing UI Reload May 14 16:23:30 volumio volumio[1176]: info: CoreCommandRouter::Reload Ui May 14 16:23:31 volumio volumio[1176]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::volumioGetVisibleSources May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 14 16:23:31 volumio volumio[1176]: info: Received Get System Info May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 16:23:31 volumio volumio[1176]: info: Discovery: Getting this device information May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::volumioGetState May 14 16:23:31 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 14 16:23:31 volumio volumio-remote-updater[852]: No test mode May 14 16:23:31 volumio volumio-remote-updater[852]: No alpha test mode May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:31 volumio volumio[1176]: info: Retrieving Cloud Streaming UI May 14 16:23:31 volumio volumio[1176]: info: Getting Tidal Cloud Configuration May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:31 volumio volumio[1176]: info: Getting Qobuz Cloud Configuration May 14 16:23:31 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:31 volumio volumio[1176]: info: Getting Spotify Cloud Configuration May 14 16:23:31 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:31 volumio volumio[1176]: info: Saving Spotify Acccount May 14 16:23:31 volumio volumio[1176]: info: Got Tidal Cloud Configuration May 14 16:23:31 volumio volumio[1176]: info: Got it May 14 16:23:31 volumio volumio[1176]: info: Got it May 14 16:23:31 volumio volumio[1176]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 14 16:23:31 volumio volumio[1176]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 14 16:23:31 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:23:31 volumio volumio[1176]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 14 16:23:32 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 14 16:23:32 volumio volumio[1176]: info: Loading i18n strings for locale en May 14 16:23:32 volumio volumio[1176]: Updating browse sources language May 14 16:23:32 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 16:23:32 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 14 16:23:32 volumio volumio[1176]: info: Setting timezone to UTC May 14 16:23:32 volumio sudo[2287]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 14 16:23:32 volumio sudo[2287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:32 volumio sudo[2287]: pam_unix(sudo:session): session closed for user root May 14 16:23:32 volumio sudo[2291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime May 14 16:23:32 volumio sudo[2291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:32 volumio sudo[2291]: pam_unix(sudo:session): session closed for user root May 14 16:23:32 volumio sudo[2295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 14 16:23:32 volumio sudo[2295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:32 volumio sudo[2295]: pam_unix(sudo:session): session closed for user root May 14 16:23:32 volumio sudo[2299]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC May 14 16:23:32 volumio sudo[2299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:23:32 volumio sudo[2299]: pam_unix(sudo:session): session closed for user root May 14 16:23:32 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 16:23:33 volumio volumio[1176]: info: Fetching Streaming Services browse cache May 14 16:23:36 volumio volumio[1176]: info: BOOT COMPLETED May 14 16:23:40 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 14 16:23:49 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 16:23:49 volumio volumio[1176]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 14 16:23:49 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 14 16:23:49 volumio volumio[1176]: info: Received Get System Version May 14 16:23:49 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 16:23:49 volumio volumio[1176]: info: Received Get System Info May 14 16:23:49 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 16:23:49 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 16:23:49 volumio volumio[1176]: info: Discovery: Getting this device information May 14 16:23:49 volumio volumio[1176]: info: CoreCommandRouter::volumioGetState May 14 16:23:49 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 May 14 16:23:49 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 16:23:53 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 14 16:23:53 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 14 16:23:53 volumio volumio-remote-updater[852]: No test mode May 14 16:23:53 volumio volumio-remote-updater[852]: No alpha test mode May 14 16:23:53 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 14 16:23:54 volumio volumio[1176]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 14 16:23:54 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 14 16:24:02 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 14 16:24:02 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 14 16:24:02 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 14 16:24:02 volumio volumio-remote-updater[852]: No test mode May 14 16:24:02 volumio volumio-remote-updater[852]: No alpha test mode May 14 16:24:02 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 14 16:24:02 volumio volumio[1176]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 14 16:24:02 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 14 16:24:06 volumio sudo[2350]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 16:24:06 volumio sudo[2350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:06 volumio sudo[2352]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 16:24:06 volumio sudo[2352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:06 volumio sudo[2350]: pam_unix(sudo:session): session closed for user root May 14 16:24:06 volumio sudo[2352]: pam_unix(sudo:session): session closed for user root May 14 16:24:06 volumio volumio[1176]: verbose: New Socket.io Connection to 192.168.2.22 from 192.168.2.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::volumioGetVisibleSources May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 14 16:24:07 volumio volumio[1176]: info: Received Get System Info May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 16:24:07 volumio volumio[1176]: info: Discovery: Getting this device information May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::volumioGetState May 14 16:24:07 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 16:24:07 volumio volumio-remote-updater[852]: No test mode May 14 16:24:07 volumio volumio-remote-updater[852]: No alpha test mode May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:24:07 volumio volumio[1176]: info: Retrieving Cloud Streaming UI May 14 16:24:07 volumio volumio[1176]: info: Getting Tidal Cloud Configuration May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:24:07 volumio volumio[1176]: info: Getting Qobuz Cloud Configuration May 14 16:24:07 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:24:07 volumio volumio[1176]: info: Getting Spotify Cloud Configuration May 14 16:24:07 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:24:07 volumio volumio[1176]: info: Saving Spotify Acccount May 14 16:24:07 volumio volumio[1176]: info: Got Tidal Cloud Configuration May 14 16:24:07 volumio volumio[1176]: info: Got it May 14 16:24:07 volumio volumio[1176]: info: Got it May 14 16:24:07 volumio volumio[1176]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:24:07 volumio volumio[1176]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 14 16:24:07 volumio volumio[1176]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 14 16:24:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 14 16:24:08 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 14 16:24:08 volumio volumio[1176]: info: Loading i18n strings for locale en May 14 16:24:08 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 14 16:24:08 volumio volumio[1176]: info: Setting timezone to Europe/Amsterdam May 14 16:24:08 volumio sudo[2364]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 14 16:24:08 volumio sudo[2364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:08 volumio sudo[2364]: pam_unix(sudo:session): session closed for user root May 14 16:24:08 volumio sudo[2368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime May 14 16:24:08 volumio sudo[2368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:08 volumio sudo[2368]: pam_unix(sudo:session): session closed for user root May 14 16:24:08 volumio sudo[2372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 14 16:24:08 volumio sudo[2372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:08 volumio sudo[2372]: pam_unix(sudo:session): session closed for user root May 14 16:24:08 volumio sudo[2376]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam May 14 16:24:08 volumio sudo[2376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:08 volumio dbus-daemon[844]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.25' (uid=0 pid=2377 comm="/usr/bin/timedatectl set-timezone Europe/Amsterdam") May 14 16:24:08 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 14 16:24:08 volumio dbus-daemon[844]: [system] Successfully activated service 'org.freedesktop.timedate1' May 14 16:24:08 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 14 16:24:08 volumio sudo[2376]: pam_unix(sudo:session): session closed for user root May 14 16:24:08 volumio volumio[1176]: info: Fetching Streaming Services browse cache May 14 16:24:08 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 16:24:08 volumio volumio[1176]: Updating browse sources language May 14 16:24:08 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 16:24:09 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 14 16:24:09 volumio volumio[1176]: info: MyVolumio login type: Token May 14 16:24:09 volumio volumio[1176]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 14 16:24:10 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 14 16:24:10 volumio volumio[1176]: info: Loading i18n strings for locale en May 14 16:24:10 volumio volumio[1176]: Updating browse sources language May 14 16:24:10 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 16:24:10 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 14 16:24:10 volumio volumio[1176]: info: Setting timezone to Europe/Amsterdam May 14 16:24:10 volumio sudo[2397]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 14 16:24:10 volumio sudo[2397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:10 volumio sudo[2397]: pam_unix(sudo:session): session closed for user root May 14 16:24:10 volumio sudo[2401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime May 14 16:24:10 volumio sudo[2401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:10 volumio sudo[2401]: pam_unix(sudo:session): session closed for user root May 14 16:24:10 volumio sudo[2405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 14 16:24:10 volumio sudo[2405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:10 volumio sudo[2405]: pam_unix(sudo:session): session closed for user root May 14 16:24:10 volumio sudo[2409]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam May 14 16:24:10 volumio sudo[2409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:10 volumio sudo[2409]: pam_unix(sudo:session): session closed for user root May 14 16:24:10 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 14 16:24:10 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 14 16:24:10 volumio sudo[2412]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 14 16:24:10 volumio sudo[2412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:10 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 16:24:10 volumio sudo[2412]: pam_unix(sudo:session): session closed for user root May 14 16:24:10 volumio volumio[1176]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 14 16:24:10 volumio volumio[1176]: wlan0 Interface doesn't support scanning. May 14 16:24:10 volumio volumio[1176]: info: Cannot use regular scanning, forcing with ap-force May 14 16:24:10 volumio sudo[2419]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 14 16:24:10 volumio sudo[2419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:10 volumio sudo[2419]: pam_unix(sudo:session): session closed for user root May 14 16:24:10 volumio volumio[1176]: command failed: No such device (-19) May 14 16:24:10 volumio volumio[1176]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 14 16:24:10 volumio volumio[1176]: command failed: No such device (-19) May 14 16:24:10 volumio volumio[1176]: info: Fetching Streaming Services browse cache May 14 16:24:11 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 16:24:11 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 16:24:12 volumio volumio[1176]: info: MyVolumio token set successfully May 14 16:24:12 volumio volumio[1176]: info: MYVOLUMIO: Adding device May 14 16:24:12 volumio volumio[1176]: info: MYVOLUMIO: Evaluating Server May 14 16:24:12 volumio volumio[1176]: info: MyVolumio Plan changed: premium May 14 16:24:12 volumio volumio[1176]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 14 16:24:12 volumio volumio[1176]: info: Removing browser output: myVolumio user plan is not superstar May 14 16:24:12 volumio volumio[1176]: info: Removing audio output: May 14 16:24:12 volumio volumio[1176]: info: MYVOLUMIO: Adding device May 14 16:24:12 volumio volumio[1176]: info: MYVOLUMIO: Evaluating Server May 14 16:24:12 volumio volumio[1176]: info: Remote config written successfully May 14 16:24:12 volumio volumio[1176]: info: Starting Tunnel 1 May 14 16:24:12 volumio volumio[1176]: info: Starting Tunnel Connection Checker May 14 16:24:12 volumio volumio[1176]: info: MYVolumio Device enabled May 14 16:24:12 volumio volumio[1176]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 14 16:24:12 volumio volumio[1176]: info: MyVolumio status changed May 14 16:24:12 volumio volumio[1176]: info: Streaming services startup May 14 16:24:12 volumio volumio[1176]: info: Starting Streaming Daemon May 14 16:24:12 volumio sudo[2454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 14 16:24:12 volumio sudo[2454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:12 volumio volumio[1176]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' May 14 16:24:12 volumio sudo[2454]: pam_unix(sudo:session): session closed for user root May 14 16:24:12 volumio volumio[1176]: error: Cannot start Volumio Streaming Daemon May 14 16:24:12 volumio volumio[1176]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 14 16:24:12 volumio volumio[1176]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 14 16:24:12 volumio volumio[1176]: info: Setting Geolocation for MyVolumio to eu5 May 14 16:24:12 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:12 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:12 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:13 volumio volumio[1176]: info: Setting Geolocation for MyVolumio to eu8 May 14 16:24:13 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:13 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:13 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:13 volumio volumio[1176]: info: Successfully Added MyVolumio device May 14 16:24:13 volumio volumio[1176]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! May 14 16:24:13 volumio volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 14 16:24:13 volumio volumio[1176]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart May 14 16:24:13 volumio volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 14 16:24:14 volumio sudo[2458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 14 16:24:14 volumio sudo[2458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:14 volumio sudo[2458]: pam_unix(sudo:session): session closed for user root May 14 16:24:14 volumio volumio[1176]: info: MRS: MultiRoom plugin initialized May 14 16:24:14 volumio volumio[1176]: info: MRS: STOPPING SNAPCLIENT May 14 16:24:14 volumio volumio[1176]: info: MRS: Snap server stop May 14 16:24:14 volumio volumio[1176]: info: MRS: STOPPING volumioStreaming May 14 16:24:14 volumio sudo[2475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 14 16:24:14 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:14 volumio sudo[2477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 14 16:24:14 volumio sudo[2477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:14 volumio volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 14 16:24:14 volumio sudo[2479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 14 16:24:14 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:14 volumio sudo[2482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 14 16:24:14 volumio sudo[2482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:14 volumio sudo[2482]: pam_unix(sudo:session): session closed for user root May 14 16:24:14 volumio volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 14 16:24:14 volumio volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 14 16:24:14 volumio volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 14 16:24:14 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root May 14 16:24:14 volumio volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 14 16:24:14 volumio sudo[2477]: pam_unix(sudo:session): session closed for user root May 14 16:24:14 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root May 14 16:24:14 volumio volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 14 16:24:14 volumio volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 14 16:24:14 volumio volumio[1176]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 14 16:24:14 volumio volumio[1176]: info: Preparing to generate the ALSA configuration file May 14 16:24:14 volumio volumio[1176]: info: Updating MyVolumio device info May 14 16:24:14 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:14 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:14 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:14 volumio volumio[1176]: info: Updating MyVolumio device info May 14 16:24:14 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:14 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:14 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:14 volumio volumio[1176]: info: Successfully Added MyVolumio device May 14 16:24:14 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 14 16:24:14 volumio volumio[1176]: info: MRS: Removed streaming files May 14 16:24:14 volumio volumio[1176]: info: MRS: volumioStreaming STOPPED May 14 16:24:14 volumio volumio[1176]: info: MRS: SNAPSERVER STOPPED May 14 16:24:14 volumio volumio[1176]: info: MRS: SNAPCLIENT STOPPED May 14 16:24:14 volumio volumio[1176]: info: Asound.conf file unchanged, so no further update is needed May 14 16:24:14 volumio volumio[1176]: info: Output device has changed, restarting MPD May 14 16:24:14 volumio volumio[1176]: info: Output device has changed, restarting Shairport Sync May 14 16:24:14 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:14 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:15 volumio sudo[2487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 16:24:15 volumio sudo[2487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio sudo[2489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 16:24:15 volumio sudo[2487]: pam_unix(sudo:session): session closed for user root May 14 16:24:15 volumio sudo[2489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio volumio[1176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 16:24:15 volumio volumio[1176]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 14 16:24:15 volumio volumio[1176]: ------------------------------------ BT MESSAGE: [FUNC] onStart May 14 16:24:15 volumio volumio[1176]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service May 14 16:24:15 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 14 16:24:15 volumio volumio[1176]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp May 14 16:24:15 volumio volumio[1176]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ May 14 16:24:15 volumio volumio[1176]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. May 14 16:24:15 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 14 16:24:15 volumio volumio[1176]: info: MRS: Pushing multiroomSync output for this device May 14 16:24:15 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:15 volumio volumio[1176]: info: Adding audio output: May 14 16:24:15 volumio volumio[1176]: info: Adding audio output: May 14 16:24:15 volumio volumio[1176]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 14 16:24:15 volumio volumio[1176]: info: Adding METAVOLUMIO REST API Endpoints May 14 16:24:15 volumio volumio[1176]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 14 16:24:15 volumio volumio[1176]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 14 16:24:15 volumio volumio[1176]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 14 16:24:15 volumio volumio[1176]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 14 16:24:15 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 14 16:24:15 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 14 16:24:15 volumio volumio[1176]: info: Preparing CD Folders May 14 16:24:15 volumio volumio[1176]: info: Adding CD REST API Endpoints May 14 16:24:15 volumio volumio[1176]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 14 16:24:15 volumio volumio[1176]: info: Starting UDEV Watcher for CD May 14 16:24:15 volumio volumio[1176]: info: Detecting CD presence with UDEV May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 14 16:24:15 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 14 16:24:15 volumio volumio[1176]: info: Adding inputs REST Endpoints May 14 16:24:15 volumio volumio[1176]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 14 16:24:15 volumio volumio[1176]: info: Scanning Audio Inputs May 14 16:24:15 volumio volumio[1176]: info: Checking against Known Cards name May 14 16:24:15 volumio volumio[1176]: info: Adding Server instance for streaming May 14 16:24:15 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 14 16:24:15 volumio volumio[1176]: error: Hi Res Audio Failed Login: Missing Login Data May 14 16:24:15 volumio volumio[1176]: info: Adding HIGHRESAUDIO REST API Endpoints May 14 16:24:15 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 14 16:24:15 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 14 16:24:15 volumio volumio[1176]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 14 16:24:15 volumio systemd[1]: mpd.service: Deactivated successfully. May 14 16:24:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 14 16:24:15 volumio systemd[1]: mpd.service: Consumed 2.353s CPU time. May 14 16:24:15 volumio systemd[1]: mpd.socket: Deactivated successfully. May 14 16:24:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 14 16:24:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 14 16:24:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 14 16:24:15 volumio volumio[1176]: info: Stopping AccessToken refresher cron May 14 16:24:15 volumio sudo[2507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 16:24:15 volumio sudo[2507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 14 16:24:15 volumio volumio[1176]: info: AccessToken refresher cron started May 14 16:24:15 volumio volumio[1176]: info: Stopping AccessToken refresher cron for QOBUZ May 14 16:24:15 volumio volumio[1176]: info: AccessToken refresher cron started for QOBUZ May 14 16:24:15 volumio volumio[1176]: info: Adding TIDAL REST API Endpoints May 14 16:24:15 volumio volumio[1176]: info: Adding QOBUZ REST API Endpoints May 14 16:24:15 volumio sudo[2507]: pam_unix(sudo:session): session closed for user root May 14 16:24:15 volumio volumio[1176]: info: Successfully Updated MyVolumio device May 14 16:24:15 volumio volumio[1176]: info: Successfully Updated MyVolumio device May 14 16:24:15 volumio volumio[1176]: ------------------------------------ BT MESSAGE: Failed to power on adapter: May 14 16:24:15 volumio sudo[2508]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 16:24:15 volumio sudo[2508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 16:24:15 volumio volumio[1176]: info: MPD Permissions set May 14 16:24:15 volumio sudo[2508]: pam_unix(sudo:session): session closed for user root May 14 16:24:15 volumio sudo[2513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 14 16:24:15 volumio sudo[2513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 14 16:24:15 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. May 14 16:24:15 volumio sudo[2513]: pam_unix(sudo:session): session closed for user root May 14 16:24:15 volumio volumiobt[2525]: INFO [BTSTART] Ensuring Bluetooth directory exists... May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio sudo[2527]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth May 14 16:24:15 volumio sudo[2527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio sudo[2527]: pam_unix(sudo:session): session closed for user root May 14 16:24:15 volumio sudo[2529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth May 14 16:24:15 volumio sudo[2529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio sudo[2529]: pam_unix(sudo:session): session closed for user root May 14 16:24:15 volumio volumiobt[2531]: INFO [BTSTART] Powering on Bluetooth if needed... May 14 16:24:15 volumio volumiobt[2535]: No default controller available May 14 16:24:15 volumio volumio[1176]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::volumioStop May 14 16:24:15 volumio volumio[1176]: info: CoreStateMachine::stop May 14 16:24:15 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 16:24:15 volumio volumio[1176]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart May 14 16:24:15 volumio volumio[1176]: info: CoreStateMachine::setConsumeUpdateService undefined May 14 16:24:15 volumio volumio[1176]: info: Starting Shairport Sync May 14 16:24:15 volumio volumio[1176]: info: Preparing to generate the ALSA configuration file May 14 16:24:15 volumio sudo[2539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 16:24:15 volumio sudo[2539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio volumio[1176]: info: MRS: Found cast device: Google-Cast-Group-6f6e94dc5d0f4852aacd5c79f528c0e8 May 14 16:24:15 volumio volumio[1176]: info: Adding audio output: May 14 16:24:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 14 16:24:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 14 16:24:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:15 volumio systemd[1]: shairport-sync.service: Consumed 2.189s CPU time. May 14 16:24:15 volumio volumio[1176]: info: MRS: Found cast device: Google-Nest-Hub-5badd672d3121318f36e98647cebf4e9 May 14 16:24:15 volumio volumio[1176]: info: Adding audio output: May 14 16:24:15 volumio volumio[1176]: info: Asound.conf file unchanged, so no further update is needed May 14 16:24:15 volumio volumio[1176]: info: Output device has changed, restarting MPD May 14 16:24:15 volumio volumio[1176]: info: Output device has changed, restarting Shairport Sync May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:15 volumio sudo[2539]: pam_unix(sudo:session): session closed for user root May 14 16:24:15 volumio sudo[2544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 16:24:15 volumio sudo[2544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio sudo[2544]: pam_unix(sudo:session): session closed for user root May 14 16:24:15 volumio sudo[2547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 16:24:15 volumio sudo[2547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio volumio[1176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 16:24:15 volumio volumio[1176]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: Preparing to generate the ALSA configuration file May 14 16:24:15 volumio systemd[1]: mpd.service: Deactivated successfully. May 14 16:24:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 14 16:24:15 volumio systemd[1]: mpd.socket: Deactivated successfully. May 14 16:24:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 14 16:24:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 14 16:24:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 14 16:24:15 volumio volumio[1176]: info: MRS: Found cast device: Google-Nest-Hub-a2b652bdc437c0983a3cf41b5f4181d0 May 14 16:24:15 volumio volumio[1176]: info: Adding audio output: May 14 16:24:15 volumio volumio[1176]: info: MPD Permissions set May 14 16:24:15 volumio volumio[1176]: info: Shairport-Sync Started May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 14 16:24:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: Asound.conf file unchanged, so no further update is needed May 14 16:24:15 volumio volumio[1176]: info: Output device has changed, restarting MPD May 14 16:24:15 volumio volumio[1176]: info: Output device has changed, restarting Shairport Sync May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:15 volumio sudo[2579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 16:24:15 volumio sudo[2579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio sudo[2581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 16:24:15 volumio sudo[2579]: pam_unix(sudo:session): session closed for user root May 14 16:24:15 volumio sudo[2581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio volumio[1176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 16:24:15 volumio volumio[1176]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: MPD Permissions set May 14 16:24:15 volumio volumio[1176]: info: Starting Shairport Sync May 14 16:24:15 volumio systemd[1]: mpd.service: Deactivated successfully. May 14 16:24:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 14 16:24:15 volumio systemd[1]: mpd.socket: Deactivated successfully. May 14 16:24:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 14 16:24:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 14 16:24:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio sudo[2591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 16:24:15 volumio sudo[2591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 14 16:24:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 14 16:24:15 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 14 16:24:16 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 14 16:24:16 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:16 volumio sudo[2591]: pam_unix(sudo:session): session closed for user root May 14 16:24:16 volumio volumio[1176]: info: Starting Shairport Sync May 14 16:24:16 volumio volumio[1176]: info: Shairport-Sync Started May 14 16:24:16 volumio sudo[2604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 16:24:16 volumio sudo[2604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:16 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 14 16:24:16 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 14 16:24:16 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:16 volumio sudo[2592]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 16:24:16 volumio sudo[2592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 16:24:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:16 volumio sudo[2592]: pam_unix(sudo:session): session closed for user root May 14 16:24:16 volumio sudo[2604]: pam_unix(sudo:session): session closed for user root May 14 16:24:16 volumio volumio[1176]: info: Shairport-Sync Started May 14 16:24:16 volumio volumiobt[2636]: INFO [BTSTART] Making Bluetooth discoverable and pairable... May 14 16:24:16 volumio volumiobt[2637]: [75B blob data] May 14 16:24:16 volumio volumiobt[2637]: No default controller available May 14 16:24:16 volumio volumiobt[2637]: [bluetooth]# pairable on May 14 16:24:16 volumio volumiobt[2637]: No default controller available May 14 16:24:16 volumio volumiobt[2637]: [bluetooth]# May 14 16:24:16 volumio volumiobt[2638]: INFO [BTSTART] Registering Bluetooth agent... May 14 16:24:16 volumio volumiobt[2640]: No agent is registered May 14 16:24:16 volumio volumiobt[2641]: INFO [BTSTART] Agent registered successfully. May 14 16:24:16 volumio volumiobt[2642]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... May 14 16:24:17 volumio mpd[2621]: 2025-05-14T16:24:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 14 16:24:17 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 14 16:24:17 volumio sudo[2489]: pam_unix(sudo:session): session closed for user root May 14 16:24:17 volumio sudo[2547]: pam_unix(sudo:session): session closed for user root May 14 16:24:17 volumio sudo[2581]: pam_unix(sudo:session): session closed for user root May 14 16:24:17 volumio volumio[1176]: error: MPD error: The expression evaluated to a falsy value: May 14 16:24:17 volumio volumio[1176]: assert.ok(self.idling) May 14 16:24:17 volumio volumio[1176]: error: The expression evaluated to a falsy value: May 14 16:24:17 volumio volumio[1176]: assert.ok(self.idling) May 14 16:24:17 volumio volumio[1176]: error: MPD error: The expression evaluated to a falsy value: May 14 16:24:17 volumio volumio[1176]: assert.ok(self.idling) May 14 16:24:17 volumio volumio[1176]: error: The expression evaluated to a falsy value: May 14 16:24:17 volumio volumio[1176]: assert.ok(self.idling) May 14 16:24:17 volumio volumio[1176]: error: updateQueue error: null May 14 16:24:17 volumio volumiobt[2643]: INFO:a2dp-agent:Connecting to system bus. May 14 16:24:17 volumio volumiobt[2643]: ERROR:a2dp-agent:No Bluetooth adapter found: Bluetooth adapter not found May 14 16:24:17 volumio volumiobt[2647]: INFO [BTSTART] Disconnecting all Bluetooth devices... May 14 16:24:17 volumio volumiobt[2652]: INFO [BTSTART] Unregistering Bluetooth agent... May 14 16:24:17 volumio volumiobt[2653]: No agent is registered May 14 16:24:17 volumio volumiobt[2654]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... May 14 16:24:17 volumio volumiobt[2656]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... May 14 16:24:17 volumio systemd[1]: volumiobt.service: Deactivated successfully. May 14 16:24:18 volumio volumio[1176]: info: TidalConnect service stoped! May 14 16:24:18 volumio volumio[1176]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 14 16:24:18 volumio volumio[1176]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 14 16:24:18 volumio sudo[2672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 14 16:24:18 volumio sudo[2672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:18 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 14 16:24:18 volumio sudo[2672]: pam_unix(sudo:session): session closed for user root May 14 16:24:18 volumio sudo[2678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 14 16:24:18 volumio sudo[2678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 14 16:24:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 14 16:24:18 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. May 14 16:24:18 volumio sudo[2678]: pam_unix(sudo:session): session closed for user root May 14 16:24:18 volumio volumio[1176]: info: Remote SSH Started May 14 16:24:18 volumio autossh[2681]: port set to 0, monitoring disabled May 14 16:24:18 volumio autossh[2681]: starting ssh (count 1) May 14 16:24:18 volumio autossh[2681]: ssh child pid is 2684 May 14 16:24:18 volumio volumiossh-tunnel[2684]: Warning: Permanently added '[eu8.myvolumio.org]:2222' (RSA) to the list of known hosts. May 14 16:24:18 volumio volumio[1176]: info: Executing endpoint tc_getconfig May 14 16:24:18 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 14 16:24:18 volumio vtcs[2675]: STARTING TidalConnect services, version: 1.5.0.45 May 14 16:24:18 volumio volumio[1176]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 14 16:24:18 volumio vtcs[2675]: STARTED TidalConnect services. May 14 16:24:19 volumio volumio[1176]: info: Executing endpoint tc_connect May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 14 16:24:19 volumio volumio[1176]: info: Connecting to TidalConnect May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::volumioGetState May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::servicePushState May 14 16:24:19 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:19 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 May 14 16:24:19 volumio volumio[1176]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 14 16:24:19 volumio volumio[1176]: verbose: CURRENT POSITION 0 May 14 16:24:19 volumio volumio[1176]: info: CoreStateMachine::syncState stateService stop May 14 16:24:19 volumio volumio[1176]: info: CoreStateMachine::syncState currentStatus stop May 14 16:24:19 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:19 volumio volumio[1176]: info: No code May 14 16:24:19 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::servicePushState May 14 16:24:19 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:19 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 May 14 16:24:19 volumio volumio[1176]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 14 16:24:19 volumio volumio[1176]: verbose: CURRENT POSITION 0 May 14 16:24:19 volumio volumio[1176]: info: CoreStateMachine::syncState stateService stop May 14 16:24:19 volumio volumio[1176]: info: CoreStateMachine::syncState currentStatus stop May 14 16:24:19 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:19 volumio volumio[1176]: info: No code May 14 16:24:19 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:19 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:19 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:19 volumio volumio[1176]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.rDRBmnRicNe9IFSqzqbHi9PA03s2.00d479372362e622324f723a6f9709e0.state.status' May 14 16:24:19 volumio volumio[1176]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.rDRBmnRicNe9IFSqzqbHi9PA03s2.00d479372362e622324f723a6f9709e0.state.status' May 14 16:24:19 volumio volumio[1176]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.rDRBmnRicNe9IFSqzqbHi9PA03s2.00d479372362e622324f723a6f9709e0.state.status' May 14 16:24:19 volumio volumio[1176]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.rDRBmnRicNe9IFSqzqbHi9PA03s2.00d479372362e622324f723a6f9709e0.state.status' May 14 16:24:19 volumio volumio[1176]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.rDRBmnRicNe9IFSqzqbHi9PA03s2.00d479372362e622324f723a6f9709e0.state.status' May 14 16:24:20 volumio volumio[1176]: info: MRS: Getting audio outputs on start May 14 16:24:20 volumio volumio[1176]: info: MRS: Requesting all other devices output May 14 16:24:21 volumio volumio[1176]: info: TidalConnect service started! May 14 16:24:22 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 14 16:24:22 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices May 14 16:24:22 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 14 16:24:22 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:22 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 14 16:24:25 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 16:24:25 volumio volumio[1176]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 14 16:24:25 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 14 16:24:25 volumio volumio[1176]: info: Received Get System Version May 14 16:24:25 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 16:24:25 volumio volumio[1176]: info: Received Get System Info May 14 16:24:25 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 16:24:25 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 16:24:25 volumio volumio[1176]: info: Discovery: Getting this device information May 14 16:24:25 volumio volumio[1176]: info: CoreCommandRouter::volumioGetState May 14 16:24:25 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 14 16:24:27 volumio volumio[1176]: info: Preparing to save Alsa Options, stopping services first May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::volumioGetState May 14 16:24:27 volumio volumio[1176]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"SMSL USB AUDIO"},"disallowPush":true} May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 14 16:24:27 volumio volumio[1176]: info: Device 5 does not have any Mixer Control Available May 14 16:24:27 volumio vtcs[2675]: [2025-05-14 16:24:27.613] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 14 16:24:27 volumio vtcs[2675]: [2025-05-14 16:24:27.613] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected May 14 16:24:27 volumio sudo[2777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 16:24:27 volumio sudo[2777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 14 16:24:27 volumio volumio[1176]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 14 16:24:27 volumio volumio[1176]: info: Disabling external Volume Control May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:24:27 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 14 16:24:27 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:27 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:27 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:27 volumio systemd[1]: vtcs.service: Deactivated successfully. May 14 16:24:27 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 14 16:24:27 volumio volumio[1176]: info: Preparing to generate the ALSA configuration file May 14 16:24:27 volumio sudo[2777]: pam_unix(sudo:session): session closed for user root May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:24:27 volumio volumio[1176]: info: Retrieving Cloud Streaming UI May 14 16:24:27 volumio sudo[2784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 16:24:27 volumio sudo[2784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:27 volumio volumio[1176]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.rDRBmnRicNe9IFSqzqbHi9PA03s2.00d479372362e622324f723a6f9709e0.state.status' May 14 16:24:27 volumio volumio[1176]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 14 16:24:27 volumio volumio[1176]: info: Reading ALSA contributions from plugins. May 14 16:24:27 volumio sudo[2784]: pam_unix(sudo:session): session closed for user root May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 14 16:24:27 volumio volumio[1176]: info: Getting Tidal Cloud Configuration May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:24:27 volumio volumio[1176]: info: Getting Qobuz Cloud Configuration May 14 16:24:27 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:24:27 volumio volumio[1176]: info: Getting Spotify Cloud Configuration May 14 16:24:27 volumio volumio[1176]: info: Asking plugin for UI Config May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:24:27 volumio volumio[1176]: info: Saving Spotify Acccount May 14 16:24:27 volumio volumio[1176]: info: Got it May 14 16:24:27 volumio volumio[1176]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 14 16:24:27 volumio volumio[1176]: info: Got Tidal Cloud Configuration May 14 16:24:27 volumio volumio[1176]: info: Got it May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 16:24:27 volumio volumio[1176]: info: Asound.conf file written May 14 16:24:27 volumio sudo[2796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 14 16:24:27 volumio sudo[2796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:27 volumio sudo[2796]: pam_unix(sudo:session): session closed for user root May 14 16:24:27 volumio volumio[1176]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 14 16:24:27 volumio volumio[1176]: No state is present for card Audio May 14 16:24:27 volumio volumio[1176]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 14 16:24:27 volumio volumio[1176]: Found hardware: "HdmiLpeAudio" "" "" "" "" May 14 16:24:27 volumio volumio[1176]: Hardware is initialized using a generic method May 14 16:24:27 volumio volumio[1176]: No state is present for card Audio May 14 16:24:27 volumio volumio[1176]: No state is present for card AUDIO May 14 16:24:27 volumio volumio[1176]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:85dd" "" "" May 14 16:24:27 volumio volumio[1176]: Hardware is initialized using a generic method May 14 16:24:27 volumio volumio[1176]: No state is present for card AUDIO May 14 16:24:27 volumio volumio[1176]: info: Output device has changed, restarting MPD May 14 16:24:27 volumio volumio[1176]: info: Output device has changed, restarting Shairport Sync May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:27 volumio sudo[2802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 16:24:27 volumio sudo[2802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:27 volumio sudo[2802]: pam_unix(sudo:session): session closed for user root May 14 16:24:27 volumio sudo[2804]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 16:24:27 volumio sudo[2804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:27 volumio volumio[1176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 16:24:27 volumio volumio[1176]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:27 volumio volumio[1176]: info: Output device has changed, restarting MPD May 14 16:24:27 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 14 16:24:27 volumio sudo[2814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 16:24:27 volumio sudo[2814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:27 volumio volumio[1176]: info: Output device has changed, restarting Shairport Sync May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:27 volumio systemd[1]: mpd.service: Deactivated successfully. May 14 16:24:27 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 14 16:24:27 volumio systemd[1]: mpd.service: Consumed 1.218s CPU time. May 14 16:24:27 volumio systemd[1]: mpd.socket: Deactivated successfully. May 14 16:24:27 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 14 16:24:27 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 14 16:24:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 14 16:24:27 volumio sudo[2816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 16:24:27 volumio sudo[2816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:27 volumio sudo[2816]: pam_unix(sudo:session): session closed for user root May 14 16:24:27 volumio volumio[1176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 16:24:27 volumio volumio[1176]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 16:24:27 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:27 volumio sudo[2819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 16:24:27 volumio sudo[2819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 14 16:24:28 volumio systemd[1]: mpd.service: Deactivated successfully. May 14 16:24:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 14 16:24:28 volumio volumio[1176]: info: MPD Permissions set May 14 16:24:28 volumio volumio[1176]: info: MPD Permissions set May 14 16:24:28 volumio sudo[2814]: pam_unix(sudo:session): session closed for user root May 14 16:24:28 volumio sudo[2830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 16:24:28 volumio systemd[1]: mpd.socket: Deactivated successfully. May 14 16:24:28 volumio sudo[2834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 16:24:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 14 16:24:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 14 16:24:28 volumio sudo[2834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio sudo[2830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio sudo[2833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 16:24:28 volumio sudo[2833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio sudo[2830]: pam_unix(sudo:session): session closed for user root May 14 16:24:28 volumio sudo[2834]: pam_unix(sudo:session): session closed for user root May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 14 16:24:28 volumio sudo[2833]: pam_unix(sudo:session): session closed for user root May 14 16:24:28 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:28 volumio volumio[1176]: info: Starting Shairport Sync May 14 16:24:28 volumio volumio[1176]: info: Starting Shairport Sync May 14 16:24:28 volumio sudo[2862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 16:24:28 volumio sudo[2862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:28 volumio sudo[2838]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 16:24:28 volumio sudo[2838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 16:24:28 volumio sudo[2864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 16:24:28 volumio sudo[2864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:28 volumio sudo[2838]: pam_unix(sudo:session): session closed for user root May 14 16:24:28 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 14 16:24:28 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 14 16:24:28 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:28 volumio systemd[1]: shairport-sync.service: Consumed 1.620s CPU time. May 14 16:24:28 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:28 volumio sudo[2862]: pam_unix(sudo:session): session closed for user root May 14 16:24:28 volumio volumio[1176]: info: Shairport-Sync Started May 14 16:24:28 volumio sudo[2864]: pam_unix(sudo:session): session closed for user root May 14 16:24:28 volumio volumio[1176]: info: Shairport-Sync Started May 14 16:24:29 volumio volumio[1176]: info: Preparing to generate the ALSA configuration file May 14 16:24:29 volumio volumio[1176]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 14 16:24:29 volumio volumio[1176]: info: Reading ALSA contributions from plugins. May 14 16:24:29 volumio volumio[1176]: info: Asound.conf file written May 14 16:24:29 volumio sudo[2895]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 14 16:24:29 volumio sudo[2895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:29 volumio mpd[2868]: 2025-05-14T16:24:29 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 14 16:24:29 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 14 16:24:29 volumio sudo[2804]: pam_unix(sudo:session): session closed for user root May 14 16:24:29 volumio sudo[2819]: pam_unix(sudo:session): session closed for user root May 14 16:24:29 volumio sudo[2895]: pam_unix(sudo:session): session closed for user root May 14 16:24:29 volumio volumio[1176]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 14 16:24:29 volumio volumio[1176]: No state is present for card Audio May 14 16:24:29 volumio volumio[1176]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 14 16:24:29 volumio volumio[1176]: Found hardware: "HdmiLpeAudio" "" "" "" "" May 14 16:24:29 volumio volumio[1176]: Hardware is initialized using a generic method May 14 16:24:29 volumio volumio[1176]: No state is present for card Audio May 14 16:24:29 volumio volumio[1176]: No state is present for card AUDIO May 14 16:24:29 volumio volumio[1176]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:85dd" "" "" May 14 16:24:29 volumio volumio[1176]: Hardware is initialized using a generic method May 14 16:24:29 volumio volumio[1176]: No state is present for card AUDIO May 14 16:24:29 volumio volumio[1176]: info: Output device has changed, restarting MPD May 14 16:24:29 volumio volumio[1176]: info: Output device has changed, restarting Shairport Sync May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:29 volumio sudo[2903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 16:24:29 volumio sudo[2903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:29 volumio sudo[2903]: pam_unix(sudo:session): session closed for user root May 14 16:24:29 volumio sudo[2905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 16:24:29 volumio sudo[2905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:29 volumio volumio[1176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 16:24:29 volumio volumio[1176]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 14 16:24:29 volumio volumio[1176]: info: Preparing to generate the ALSA configuration file May 14 16:24:29 volumio systemd[1]: mpd.service: Deactivated successfully. May 14 16:24:29 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 14 16:24:29 volumio systemd[1]: mpd.service: Consumed 1.194s CPU time. May 14 16:24:29 volumio systemd[1]: mpd.socket: Deactivated successfully. May 14 16:24:29 volumio sudo[2915]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 16:24:29 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 14 16:24:29 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 14 16:24:29 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 14 16:24:29 volumio sudo[2915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:29 volumio sudo[2917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 16:24:29 volumio volumio[1176]: info: MPD Permissions set May 14 16:24:29 volumio sudo[2917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:29 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 14 16:24:29 volumio sudo[2915]: pam_unix(sudo:session): session closed for user root May 14 16:24:29 volumio volumio[1176]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 14 16:24:29 volumio volumio[1176]: info: Reading ALSA contributions from plugins. May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 14 16:24:29 volumio sudo[2917]: pam_unix(sudo:session): session closed for user root May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: Starting Shairport Sync May 14 16:24:29 volumio volumio[1176]: info: Asound.conf file unchanged, so no further update is needed May 14 16:24:29 volumio volumio[1176]: info: Output device has changed, restarting MPD May 14 16:24:29 volumio sudo[2918]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 16:24:29 volumio sudo[2918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 16:24:29 volumio sudo[2918]: pam_unix(sudo:session): session closed for user root May 14 16:24:29 volumio sudo[2932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 16:24:29 volumio sudo[2932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:29 volumio volumio[1176]: info: Output device has changed, restarting Shairport Sync May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:29 volumio sudo[2935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 16:24:29 volumio sudo[2935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:29 volumio sudo[2935]: pam_unix(sudo:session): session closed for user root May 14 16:24:29 volumio sudo[2940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 16:24:29 volumio volumio[1176]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 16:24:29 volumio volumio[1176]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio sudo[2940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:29 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 14 16:24:29 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 14 16:24:29 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:29 volumio systemd[1]: shairport-sync.service: Consumed 1.548s CPU time. May 14 16:24:29 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:29 volumio sudo[2932]: pam_unix(sudo:session): session closed for user root May 14 16:24:29 volumio systemd[1]: mpd.service: Deactivated successfully. May 14 16:24:29 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 14 16:24:29 volumio systemd[1]: mpd.socket: Deactivated successfully. May 14 16:24:29 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 14 16:24:29 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 14 16:24:29 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 14 16:24:29 volumio volumio[1176]: info: MPD Permissions set May 14 16:24:29 volumio volumio[1176]: info: Shairport-Sync Started May 14 16:24:29 volumio volumio[1176]: error: MPD error: Error: read ECONNRESET May 14 16:24:29 volumio volumio[1176]: error: MPD error: Error: read ECONNRESET May 14 16:24:29 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:29 volumio sudo[2950]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 16:24:29 volumio sudo[2950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:29 volumio sudo[2952]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 14 16:24:29 volumio sudo[2952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 16:24:29 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 14 16:24:30 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 16:24:30 volumio sudo[2950]: pam_unix(sudo:session): session closed for user root May 14 16:24:30 volumio volumio[1176]: info: Starting Shairport Sync May 14 16:24:30 volumio sudo[2952]: pam_unix(sudo:session): session closed for user root May 14 16:24:30 volumio sudo[2980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 16:24:30 volumio sudo[2980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:30 volumio sudo[2961]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 16:24:30 volumio sudo[2961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 16:24:30 volumio sudo[2961]: pam_unix(sudo:session): session closed for user root May 14 16:24:30 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 14 16:24:30 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 14 16:24:30 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:30 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 16:24:30 volumio sudo[2980]: pam_unix(sudo:session): session closed for user root May 14 16:24:30 volumio volumio[1176]: info: Shairport-Sync Started May 14 16:24:30 volumio volumio[1176]: info: TidalConnect service stoped! May 14 16:24:30 volumio volumio[1176]: info: TidalConnect service stoped! May 14 16:24:30 volumio volumio[1176]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 14 16:24:30 volumio volumio[1176]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 14 16:24:30 volumio sudo[3023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 14 16:24:30 volumio sudo[3023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 16:24:30 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 14 16:24:30 volumio sudo[3023]: pam_unix(sudo:session): session closed for user root May 14 16:24:31 volumio volumio[1176]: info: TidalConnect service stoped! May 14 16:24:31 volumio volumio[1176]: info: Executing endpoint tc_getconfig May 14 16:24:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 14 16:24:31 volumio vtcs[3026]: STARTING TidalConnect services, version: 1.5.0.45 May 14 16:24:31 volumio volumio[1176]: info: TidalConnect service stoped! May 14 16:24:31 volumio volumio[1176]: info: TidalConnect service stoped! May 14 16:24:31 volumio vtcs[3026]: STARTED TidalConnect services. May 14 16:24:31 volumio volumio[1176]: info: TidalConnect service stoped! May 14 16:24:31 volumio volumio[1176]: info: Executing endpoint tc_connect May 14 16:24:31 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 14 16:24:31 volumio volumio[1176]: info: Connecting to TidalConnect May 14 16:24:31 volumio volumio[1176]: info: CoreCommandRouter::servicePushState May 14 16:24:31 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:31 volumio mpd[2984]: 2025-05-14T16:24:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 14 16:24:31 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:31 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 14 16:24:31 volumio sudo[2940]: pam_unix(sudo:session): session closed for user root May 14 16:24:31 volumio sudo[2905]: pam_unix(sudo:session): session closed for user root May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:31 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 May 14 16:24:31 volumio volumio[1176]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 14 16:24:31 volumio volumio[1176]: verbose: CURRENT POSITION 0 May 14 16:24:31 volumio volumio[1176]: info: CoreStateMachine::syncState stateService stop May 14 16:24:31 volumio volumio[1176]: info: CoreStateMachine::syncState currentStatus stop May 14 16:24:31 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:31 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:31 volumio volumio[1176]: info: No code May 14 16:24:31 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:31 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:31 volumio volumio[1176]: info: CoreCommandRouter::servicePushState May 14 16:24:31 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:31 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:31 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 May 14 16:24:31 volumio volumio[1176]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 14 16:24:31 volumio volumio[1176]: verbose: CURRENT POSITION 0 May 14 16:24:31 volumio volumio[1176]: info: CoreStateMachine::syncState stateService stop May 14 16:24:31 volumio volumio[1176]: info: CoreStateMachine::syncState currentStatus stop May 14 16:24:31 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:31 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:31 volumio volumio[1176]: info: No code May 14 16:24:31 volumio volumio[1176]: info: CoreStateMachine::pushState May 14 16:24:31 volumio volumio[1176]: info: CoreCommandRouter::volumioPushState May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output update for this device May 14 16:24:31 volumio volumio[1176]: info: MRS: Pushing multiroomSync output May 14 16:24:31 volumio volumio[1176]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 14 16:24:31 volumio volumio[1176]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 14 16:24:31 volumio volumio[1176]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 16:24:31 volumio volumio[1176]: Error: ENOENT: no such file or directory, unlink '/tmp/7NwYqeLPt5bLEqk2w5Dj1sTlktdEHLaC2Dk0y1fQ3RjoaWktckIZLG2ypeDRHzZGuUuIWmhEbIHXh9YR63SnK0F2ev8YOMtpJY5q' May 14 16:24:31 volumio volumio[1176]: at Object.unlinkSync (node:fs:1899:3) May 14 16:24:31 volumio volumio[1176]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11238) May 14 16:24:31 volumio volumio[1176]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) May 14 16:24:31 volumio volumio[1176]: at TidalConnectClient.emit (node:events:514:28) May 14 16:24:31 volumio volumio[1176]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44661) May 14 16:24:31 volumio volumio[1176]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41631 May 14 16:24:31 volumio volumio[1176]: at Array.forEach () May 14 16:24:31 volumio volumio[1176]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41509) May 14 16:24:31 volumio volumio[1176]: at Socket.emit (node:events:514:28) May 14 16:24:31 volumio volumio[1176]: at addChunk (node:internal/streams/readable:343:12) May 14 16:24:31 volumio volumio[1176]: at readableAddChunk (node:internal/streams/readable:316:9) May 14 16:24:31 volumio volumio[1176]: at Readable.push (node:internal/streams/readable:253:10) May 14 16:24:31 volumio volumio[1176]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { May 14 16:24:31 volumio volumio[1176]: errno: -2, May 14 16:24:31 volumio volumio[1176]: syscall: 'unlink', May 14 16:24:31 volumio volumio[1176]: code: 'ENOENT', May 14 16:24:31 volumio volumio[1176]: path: '/tmp/7NwYqeLPt5bLEqk2w5Dj1sTlktdEHLaC2Dk0y1fQ3RjoaWktckIZLG2ypeDRHzZGuUuIWmhEbIHXh9YR63SnK0F2ev8YOMtpJY5q' May 14 16:24:31 volumio volumio[1176]: } May 14 16:24:31 volumio volumio[1176]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 16:24:31 volumio sudo[3080]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-14 16:23' May 14 16:24:31 volumio sudo[3080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="10fe97f56e977d23bd5c31bb51c11a06f700d721" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 10 21:23:26 UTC 2025" VOLUMIO_VERSION="0.065" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="f1350b3a3ecd8b15adbff5bebce2414c"