Dec 11 14:27:00 volumio volumio[1198]: info: Executing endpoint qc_getconfig Dec 11 14:27:00 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 11 14:27:01 volumio qobuz-connect[1866]: 20251211 14:27:01.300 [1866.1866] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 11 14:27:01 volumio volumio[1198]: info: Successfully started MPD Monitor Dec 11 14:27:01 volumio volumio[1198]: info: BOOT COMPLETED Dec 11 14:27:01 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:27:01 volumio qobuz-connect[1866]: 20251211 14:27:01.603 [1866.1866] INFO VolumeManager: [0x370368]: Setting new playback volume: 75 Dec 11 14:27:01 volumio qobuz-connect[1866]: 20251211 14:27:01.603 [1866.1866] INFO VolumeManager: [0x370368]: Setting new mute state: 0 Dec 11 14:27:01 volumio qobuz-connect[1866]: 20251211 14:27:01.603 [1866.1866] INFO QobuzConnect: [0x370d38]: Client initialized! Dec 11 14:27:01 volumio qobuz-connect[1866]: 20251211 14:27:01.604 [1866.1866] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 11 14:27:01 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:27:01 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:27:01 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:27:01 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:27:01 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:27:01 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:27:01 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:27:01 volumio qobuz-connect[1866]: 20251211 14:27:01.654 [1866.1866] INFO LocalConfigManager: [0x36fc48]: Starting Local Configuration server Dec 11 14:27:01 volumio qobuz-connect[1866]: 20251211 14:27:01.654 [1866.1866] INFO SampleApp: Starting Local configuration server Dec 11 14:27:01 volumio qobuz-connect[1866]: 20251211 14:27:01.655 [1866.1866] INFO SampleApp: Playback volume changed: 75 Dec 11 14:27:01 volumio qobuz-connect[1866]: 20251211 14:27:01.655 [1866.1866] INFO SampleApp: Connected to UNIX socket client 0x35a818 Dec 11 14:27:01 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:27:01 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:27:01 volumio volumio[1198]: error: updateQueue error: null Dec 11 14:27:01 volumio volumio[1198]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 11 14:27:01 volumio volumio[1198]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 11 14:27:01 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:27:01 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:02 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:27:02 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:27:02 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:02 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:27:02 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:27:02 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:02 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:27:02 volumio volumio[1198]: info: Got it Dec 11 14:27:02 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:27:02 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:27:02 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:02 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:27:02 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:27:02 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:02 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:27:02 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:27:02 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:02 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:27:02 volumio volumio[1198]: info: Got it Dec 11 14:27:02 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:27:02 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:27:02 volumio volumio[1198]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 14:27:02 volumio volumio[1198]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 14:27:02 volumio volumio[1198]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 11 14:27:02 volumio volumio[1198]: info: Starting Shairport Sync Dec 11 14:27:02 volumio volumio[1198]: info: MRS: Found cast device: Chromecast-1c8ec4e59b72bdbf6ce001a005e3595e Dec 11 14:27:02 volumio volumio[1198]: info: Adding audio output: Dec 11 14:27:02 volumio sudo[1960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 14:27:02 volumio sudo[1960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:02 volumio volumio[1198]: info: MRS: Found cast device: WiiM-AMP-cf58224997668891fa83ee1b2d2053f8 Dec 11 14:27:02 volumio volumio[1198]: info: Adding audio output: Dec 11 14:27:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 14:27:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 14:27:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 14:27:02 volumio systemd[1]: shairport-sync.service: Consumed 2.706s CPU time. Dec 11 14:27:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 14:27:03 volumio sudo[1960]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:04 volumio volumio[1198]: info: Shairport-Sync Started Dec 11 14:27:05 volumio sudo[1990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 14:27:05 volumio sudo[1990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:05 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 11 14:27:05 volumio sudo[1990]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:07 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:27:07 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:27:07 volumio volumio[1198]: info: Got it Dec 11 14:27:07 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:07 volumio volumio[1198]: info: Got it Dec 11 14:27:07 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:09 volumio volumio[1198]: info: TidalConnect service started! Dec 11 14:27:09 volumio volumio[1198]: info: Executing endpoint tc_getconfig Dec 11 14:27:09 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 11 14:27:10 volumio vtcs[1999]: STARTING TidalConnect services, version: 1.5.2.56 Dec 11 14:27:10 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:27:10 volumio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xJoMMQ3BVoRlRV3dKPMnKwL5l0M2.301b61f0db3dd52a4075298c26b253d4.state.status' Dec 11 14:27:10 volumio vtcs[1999]: STARTED TidalConnect services. Dec 11 14:27:12 volumio volumio[1198]: info: Executing endpoint tc_connect Dec 11 14:27:12 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 11 14:27:12 volumio volumio[1198]: info: Connecting to TidalConnect Dec 11 14:27:12 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Dec 11 14:27:12 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:27:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:27:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:27:13 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Dec 11 14:27:13 volumio volumio[1198]: 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} Dec 11 14:27:13 volumio volumio[1198]: verbose: CURRENT POSITION 0 Dec 11 14:27:13 volumio volumio[1198]: info: CoreStateMachine::syncState stateService stop Dec 11 14:27:13 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Dec 11 14:27:13 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:27:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:27:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:27:13 volumio volumio[1198]: info: No code Dec 11 14:27:13 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:27:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:27:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Dec 11 14:27:13 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:27:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:27:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:27:13 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Dec 11 14:27:13 volumio volumio[1198]: 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} Dec 11 14:27:13 volumio volumio[1198]: verbose: CURRENT POSITION 0 Dec 11 14:27:13 volumio volumio[1198]: info: CoreStateMachine::syncState stateService stop Dec 11 14:27:13 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Dec 11 14:27:13 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:27:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:27:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:27:13 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:27:13 volumio volumio[1198]: info: No code Dec 11 14:27:13 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:27:14 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:27:14 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:27:14 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:27:14 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:27:15 volumio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xJoMMQ3BVoRlRV3dKPMnKwL5l0M2.301b61f0db3dd52a4075298c26b253d4.state.status' Dec 11 14:27:15 volumio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xJoMMQ3BVoRlRV3dKPMnKwL5l0M2.301b61f0db3dd52a4075298c26b253d4.state.status' Dec 11 14:27:15 volumio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xJoMMQ3BVoRlRV3dKPMnKwL5l0M2.301b61f0db3dd52a4075298c26b253d4.state.status' Dec 11 14:27:15 volumio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xJoMMQ3BVoRlRV3dKPMnKwL5l0M2.301b61f0db3dd52a4075298c26b253d4.state.status' Dec 11 14:27:21 volumio sudo[2043]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 14:27:21 volumio sudo[2045]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 14:27:21 volumio sudo[2043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:21 volumio sudo[2045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:21 volumio sudo[2045]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:21 volumio sudo[2043]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:22 volumio volumio[1198]: verbose: New Socket.io Connection to 192.168.1.46 from 192.168.1.151 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 11 14:27:22 volumio volumio[1198]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 14:27:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 14:27:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 11 14:27:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 11 14:27:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 11 14:27:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 11 14:27:23 volumio volumio[1198]: info: Received Get System Info Dec 11 14:27:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 14:27:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 14:27:23 volumio volumio[1198]: info: Discovery: Getting this device information Dec 11 14:27:23 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:27:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 14:27:23 volumio volumio-remote-updater[695]: Test mode disabled Dec 11 14:27:23 volumio volumio-remote-updater[695]: Alpha mode disabled Dec 11 14:27:23 volumio volumio-remote-updater[695]: Alpha legacy test mode disabled Dec 11 14:27:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 14:27:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:23 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:27:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 11 14:27:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 11 14:27:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 14:27:24 volumio volumio[1198]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 11 14:27:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 11 14:27:25 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:27:25 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:25 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:27:25 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:27:25 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:25 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:27:25 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:27:25 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:25 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:27:25 volumio volumio[1198]: info: Got it Dec 11 14:27:25 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:27:26 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:27:26 volumio volumio[1198]: info: Got it Dec 11 14:27:26 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:27:26 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 11 14:27:27 volumio volumio[1198]: info: Loading i18n strings for locale pl Dec 11 14:27:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 11 14:27:27 volumio volumio[1198]: info: Setting timezone to Europe/Warsaw Dec 11 14:27:28 volumio sudo[2070]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 11 14:27:28 volumio sudo[2070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:28 volumio sudo[2070]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:28 volumio sudo[2074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Warsaw /etc/localtime Dec 11 14:27:28 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:29 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:29 volumio sudo[2078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 11 14:27:29 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:29 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:30 volumio sudo[2083]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Warsaw Dec 11 14:27:30 volumio sudo[2083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:30 volumio dbus-daemon[681]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.34' (uid=0 pid=2084 comm="/usr/bin/timedatectl set-timezone Europe/Warsaw") Dec 11 14:27:31 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 11 14:27:31 volumio dbus-daemon[681]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 11 14:27:31 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 11 14:27:31 volumio sudo[2083]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:32 volumio volumio[1198]: info: Fetching Streaming Services browse cache Dec 11 14:27:32 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 11 14:27:35 volumio volumio[1198]: Updating browse sources language Dec 11 14:27:35 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 14:27:37 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 11 14:27:38 volumio volumio[1198]: info: Loading i18n strings for locale pl Dec 11 14:27:38 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 11 14:27:38 volumio volumio[1198]: info: Setting timezone to Europe/Warsaw Dec 11 14:27:39 volumio sudo[2106]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 11 14:27:39 volumio sudo[2106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:39 volumio sudo[2106]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:40 volumio sudo[2110]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Warsaw /etc/localtime Dec 11 14:27:40 volumio sudo[2110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:40 volumio sudo[2110]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:41 volumio sudo[2114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 11 14:27:41 volumio sudo[2114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:41 volumio sudo[2114]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:42 volumio sudo[2118]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Warsaw Dec 11 14:27:42 volumio sudo[2118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:42 volumio sudo[2118]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 11 14:27:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 11 14:27:42 volumio volumio[1198]: info: Fetching Streaming Services browse cache Dec 11 14:27:42 volumio sudo[2121]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 11 14:27:42 volumio sudo[2121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 11 14:27:42 volumio volumio[1198]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 11 14:27:42 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 11 14:27:43 volumio sudo[2121]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:43 volumio volumio[1198]: info: Received Get System Version Dec 11 14:27:43 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 14:27:43 volumio volumio[1198]: info: Received Get System Info Dec 11 14:27:43 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 14:27:43 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 14:27:43 volumio volumio[1198]: info: Discovery: Getting this device information Dec 11 14:27:43 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:27:43 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 14:27:43 volumio volumio[1198]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 11 14:27:43 volumio volumio[1198]: wlan0 Interface doesn't support scanning : Network is down Dec 11 14:27:43 volumio volumio[1198]: info: Cannot use regular scanning, forcing with ap-force Dec 11 14:27:44 volumio sudo[2125]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 11 14:27:44 volumio sudo[2125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:27:44 volumio sudo[2125]: pam_unix(sudo:session): session closed for user root Dec 11 14:27:44 volumio volumio[1198]: command failed: Network is down (-100) Dec 11 14:27:44 volumio volumio[1198]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 11 14:27:44 volumio volumio[1198]: command failed: Network is down (-100) Dec 11 14:27:45 volumio volumio[1198]: Updating browse sources language Dec 11 14:27:45 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 14:27:47 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 11 14:27:47 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 11 14:27:48 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 14:27:48 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:27:48 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 14:28:02 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 11 14:28:02 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 14:28:02 volumio volumio-remote-updater[695]: Test mode disabled Dec 11 14:28:02 volumio volumio-remote-updater[695]: Alpha mode disabled Dec 11 14:28:02 volumio volumio-remote-updater[695]: Alpha legacy test mode disabled Dec 11 14:28:02 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 11 14:28:02 volumio volumio[1198]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 11 14:28:02 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 11 14:28:04 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 14:28:04 volumio volumio[1198]: info: Preparing to save Alsa Options, stopping services first Dec 11 14:28:04 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:04 volumio volumio[1198]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"allo-digione","label":"Allo DigiOne"},"output_device":{"value":1,"label":"Allo DigiOne"},"disallowPush":true} Dec 11 14:28:04 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 14:28:04 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 11 14:28:04 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:04 volumio volumio[1198]: info: Enabling I2S DAC: Allo DigiOne Dec 11 14:28:04 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Dec 11 14:28:04 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:05 volumio sudo[2165]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Dec 11 14:28:05 volumio sudo[2165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:05 volumio sudo[2165]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:05 volumio volumio[1198]: info: No Overlays Loaded Dec 11 14:28:05 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:05 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Dec 11 14:28:06 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:06 volumio sudo[2168]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay allo-digione Dec 11 14:28:06 volumio sudo[2168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:06 volumio volumio[1198]: warn: Unable to locate the device Allo DigiOne a reboot may be required. Dec 11 14:28:06 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Dec 11 14:28:06 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Dec 11 14:28:06 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Dec 11 14:28:06 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Dec 11 14:28:06 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Dec 11 14:28:06 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock44-gpio Dec 11 14:28:06 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock48-gpio Dec 11 14:28:06 volumio sudo[2168]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:07 volumio kernel: wm8804 1-003b: revision E Dec 11 14:28:07 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 14:28:07 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Dec 11 14:28:07 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:07 volumio (udev-worker)[2178]: controlC2: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 2' failed with exit code 99. Dec 11 14:28:07 volumio volumio[1198]: info: Device 2 does not have any Mixer Control Available Dec 11 14:28:07 volumio volumio[1198]: info: QobuzConnect: setDeactiveState invoked Dec 11 14:28:07 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:07 volumio vtcs[1999]: [2025-12-11 14:28:07.822] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 11 14:28:07 volumio vtcs[1999]: [2025-12-11 14:28:07.873] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 11 14:28:07 volumio vtcs[1999]: [2025-12-11 14:28:07.873] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 11 14:28:08 volumio sudo[2210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 14:28:08 volumio sudo[2210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:08 volumio volumio[1198]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 14:28:08 volumio volumio[1198]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo DigiOne Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 14:28:08 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 11 14:28:08 volumio systemd[1]: vtcs.service: Killing process 2004 (vtcs) with signal SIGKILL. Dec 11 14:28:08 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 11 14:28:08 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 11 14:28:08 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 14:28:08 volumio volumio[1198]: info: Disabling external Volume Control Dec 11 14:28:08 volumio sudo[2210]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:08 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:09 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:09 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:09 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 11 14:28:09 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:28:09 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 14:28:09 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:28:09 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:28:09 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:28:09 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:09 volumio volumio[1198]: info: Preparing to generate the ALSA configuration file Dec 11 14:28:11 volumio sudo[2228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 14:28:11 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:11 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:11 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:11 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:11 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:11 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:11 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:11 volumio volumio[1198]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 14:28:11 volumio volumio[1198]: info: Reading ALSA contributions from plugins. Dec 11 14:28:11 volumio sudo[2233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 14:28:11 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:11 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:12 volumio volumio[1198]: info: Dec 11 14:28:12 volumio volumio[1198]: ---------------------------- MPD announces state update: mixer Dec 11 14:28:12 volumio volumio[1198]: info: ControllerMpd::getState Dec 11 14:28:12 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Dec 11 14:28:12 volumio sudo[2233]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:12 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 11 14:28:12 volumio volumio[1198]: info: I2S Param [object Object] successfully enabled Dec 11 14:28:12 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 11 14:28:12 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 14:28:12 volumio sudo[2238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 14:28:12 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:12 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:13 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:13 volumio volumio[1198]: info: sendMpdCommand status took 843 milliseconds Dec 11 14:28:13 volumio volumio[1198]: verbose: ControllerMpd::parseState Dec 11 14:28:13 volumio volumio[1198]: info: ControllerMpd::pushState Dec 11 14:28:13 volumio volumio[1198]: info: CoreCommandRouter::servicePushState Dec 11 14:28:13 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:28:13 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:28:13 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 14:28:13 volumio qobuz-connect[1866]: 20251211 14:28:13.400 [1866.1866] INFO SampleApp: Stopping Local configuration server Dec 11 14:28:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:28:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:28:13 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:13 volumio volumio[1198]: info: CorePlayQueue::getTrack 0 Dec 11 14:28:13 volumio volumio[1198]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 11 14:28:13 volumio volumio[1198]: verbose: CURRENT POSITION 0 Dec 11 14:28:13 volumio volumio[1198]: info: CoreStateMachine::syncState stateService stop Dec 11 14:28:13 volumio volumio[1198]: info: CoreStateMachine::syncState currentStatus stop Dec 11 14:28:13 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:28:13 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 14:28:13 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:28:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:28:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:28:13 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:13 volumio qobuz-connect[1866]: 20251211 14:28:13.742 [1866.1866] INFO SampleApp: shat down connection on UNIX socket Dec 11 14:28:13 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 14:28:13 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 14:28:13 volumio volumio[1198]: info: No code Dec 11 14:28:13 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:28:13 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:28:13 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 14:28:13 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:28:13 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:28:13 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:14 volumio volumio[1198]: info: ------------------------------ 2658ms Dec 11 14:28:15 volumio volumio-remote-updater[695]: Test mode disabled Dec 11 14:28:15 volumio volumio-remote-updater[695]: Alpha mode disabled Dec 11 14:28:15 volumio volumio-remote-updater[695]: Alpha legacy test mode disabled Dec 11 14:28:15 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 11 14:28:15 volumio volumio[1198]: info: TidalConnect service stoped! Dec 11 14:28:15 volumio volumio[1198]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 11 14:28:15 volumio volumio[1198]: info: CoreStateMachine::getcurrentVolume Dec 11 14:28:15 volumio volumio[1198]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 14:28:15 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:28:15 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:28:15 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:28:15 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:28:15 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:15 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:15 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:17 volumio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xJoMMQ3BVoRlRV3dKPMnKwL5l0M2.301b61f0db3dd52a4075298c26b253d4.state.status' Dec 11 14:28:17 volumio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xJoMMQ3BVoRlRV3dKPMnKwL5l0M2.301b61f0db3dd52a4075298c26b253d4.state.status' Dec 11 14:28:17 volumio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xJoMMQ3BVoRlRV3dKPMnKwL5l0M2.301b61f0db3dd52a4075298c26b253d4.state.status' Dec 11 14:28:18 volumio volumio[1198]: Upnp client error: Error: This socket has been ended by the other party Dec 11 14:28:18 volumio volumio[1198]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 11 14:28:18 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 11 14:28:18 volumio volumio[1198]: info: TidalConnect service stoped! Dec 11 14:28:18 volumio volumio[1198]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 11 14:28:18 volumio volumio[1198]: info: QobuzConnect: setDeactiveState invoked Dec 11 14:28:18 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:18 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:18 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:18 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 11 14:28:19 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 14:28:19 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:19 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 14:28:19 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:19 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 14:28:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:20 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 14:28:20 volumio volumio[1198]: info: Getting Alsa Cards List without I2S DAC Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 14:28:21 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:21 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:21 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:21 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:21 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:21 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:21 volumio volumio[1198]: info: Got it Dec 11 14:28:21 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:21 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:21 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:22 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:22 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:22 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:22 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:22 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:22 volumio volumio[1198]: info: Got it Dec 11 14:28:22 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:22 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:22 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:22 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:22 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:22 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:22 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:22 volumio volumio[1198]: info: Got it Dec 11 14:28:22 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:22 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:22 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:22 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:22 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:22 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:22 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:22 volumio volumio[1198]: info: Got it Dec 11 14:28:22 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 11 14:28:22 volumio volumio[1198]: info: Preparing to save Alsa Options, stopping services first Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:22 volumio volumio[1198]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"allo-digione","label":"Allo DigiOne"},"output_device":{"value":1,"label":"Allo DigiOne"},"disallowPush":true} Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:22 volumio volumio[1198]: info: Device 2 does not have any Mixer Control Available Dec 11 14:28:22 volumio volumio[1198]: info: QobuzConnect: setDeactiveState invoked Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 11 14:28:22 volumio volumio[1198]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo DigiOne Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 11 14:28:22 volumio volumio[1198]: info: Disabling external Volume Control Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:22 volumio sudo[2292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 14:28:22 volumio sudo[2292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:22 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 11 14:28:22 volumio sudo[2292]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Dec 11 14:28:22 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:23 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:28:23 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:28:23 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:23 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio sudo[2308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 14:28:23 volumio sudo[2308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:23 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:23 volumio volumio[1198]: info: Got it Dec 11 14:28:23 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:23 volumio sudo[2313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 14:28:23 volumio volumio[1198]: info: Executing endpoint qc_getconfig Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 11 14:28:23 volumio sudo[2313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:23 volumio sudo[2308]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:23 volumio qobuz-connect[2244]: 20251211 14:28:23.480 [2244.2244] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 11 14:28:23 volumio qobuz-connect[2244]: 20251211 14:28:23.493 [2244.2244] INFO VolumeManager: [0xe46368]: Setting new playback volume: 75 Dec 11 14:28:23 volumio qobuz-connect[2244]: 20251211 14:28:23.494 [2244.2244] INFO VolumeManager: [0xe46368]: Setting new mute state: 0 Dec 11 14:28:23 volumio qobuz-connect[2244]: 20251211 14:28:23.494 [2244.2244] INFO QobuzConnect: [0xe46d38]: Client initialized! Dec 11 14:28:23 volumio qobuz-connect[2244]: 20251211 14:28:23.494 [2244.2244] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 11 14:28:23 volumio sudo[2313]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:23 volumio qobuz-connect[2244]: 20251211 14:28:23.543 [2244.2244] INFO LocalConfigManager: [0xe45c48]: Starting Local Configuration server Dec 11 14:28:23 volumio qobuz-connect[2244]: 20251211 14:28:23.543 [2244.2244] INFO SampleApp: Starting Local configuration server Dec 11 14:28:23 volumio qobuz-connect[2244]: 20251211 14:28:23.544 [2244.2244] INFO SampleApp: Connected to UNIX socket client 0xe30818 Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::Reload Ui Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Dec 11 14:28:23 volumio volumio[1198]: info: Wizard terminated Successfully Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::volumiosetStartupVolume Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:23 volumio volumio[1198]: info: Player Reboot required after I2S DAC has been enabled in wizard Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Retrieving Cloud Streaming UI Dec 11 14:28:23 volumio sudo[2322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 14:28:23 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:23 volumio qobuz-connect[2244]: 20251211 14:28:23.630 [2244.2244] INFO SampleApp: Playback volume changed: 75 Dec 11 14:28:23 volumio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xJoMMQ3BVoRlRV3dKPMnKwL5l0M2.301b61f0db3dd52a4075298c26b253d4.state.status' Dec 11 14:28:23 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 14:28:23 volumio volumio[1198]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 11 14:28:23 volumio volumio[1198]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 11 14:28:23 volumio qobuz-connect[2244]: 20251211 14:28:23.694 [2244.2244] INFO SampleApp: Stopping Local configuration server Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 14:28:23 volumio volumio-remote-updater[695]: PROGRESS: 0, STATUS: "Starting update", ETA: "6m" Dec 11 14:28:23 volumio volumio-remote-updater[695]: terminate called after throwing an instance of 'std::domain_error' Dec 11 14:28:23 volumio volumio-remote-updater[695]: what(): cannot use operator[] with string Dec 11 14:28:23 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Dec 11 14:28:23 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , notifyProgress Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: updater_comm , notifyProgress Dec 11 14:28:23 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:23 volumio volumio[1198]: info: Got it Dec 11 14:28:23 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:23 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Dec 11 14:28:23 volumio systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Dec 11 14:28:23 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:23 volumio volumio[1198]: info: Got it Dec 11 14:28:23 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:23 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:23 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:23 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:23 volumio volumio[1198]: info: Got it Dec 11 14:28:23 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:23 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:23 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Dec 11 14:28:23 volumio volumio[1198]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 14:28:23 volumio volumio[1198]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 14:28:24 volumio volumio-remote-updater[2336]: Volumio OTA Client: 1.7.0 Dec 11 14:28:24 volumio volumio-remote-updater[2336]: Error: No active session Dec 11 14:28:24 volumio volumio-remote-updater[2336]: [2025-12-11 14:28:24] [connect] Successful connection Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 14:28:24 volumio volumio[1198]: info: Getting Alsa Cards List without I2S DAC Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 14:28:24 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:24 volumio volumio[1198]: info: Got it Dec 11 14:28:24 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:24 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:24 volumio volumio[1198]: info: Got it Dec 11 14:28:24 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:24 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:24 volumio volumio[1198]: info: Got it Dec 11 14:28:24 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:24 volumio upmpdcli[2351]: writing RSA key Dec 11 14:28:24 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:24 volumio volumio[1198]: info: Got it Dec 11 14:28:24 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 11 14:28:24 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Getting Tidal Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Getting Qobuz Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Getting Spotify Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Asking plugin for UI Config Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Saving Spotify Acccount Dec 11 14:28:24 volumio volumio[1198]: info: Got it Dec 11 14:28:24 volumio volumio[1198]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 14:28:24 volumio volumio[1198]: info: Got it Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Got it Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Got it Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Got it Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:24 volumio volumio[1198]: info: Got it Dec 11 14:28:24 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:24 volumio volumio[1198]: info: Asound.conf file written Dec 11 14:28:24 volumio sudo[2361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 14:28:24 volumio sudo[2361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:24 volumio sudo[2365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 11 14:28:24 volumio sudo[2365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:24 volumio sudo[2365]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:25 volumio volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 11 14:28:25 volumio volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 11 14:28:25 volumio volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 11 14:28:25 volumio volumio[1198]: No state is present for card sndallodigione Dec 11 14:28:25 volumio volumio[1198]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 11 14:28:25 volumio volumio[1198]: Found hardware: "RPi-WM8804" "" "" "" "" Dec 11 14:28:25 volumio volumio[1198]: Hardware is initialized using a generic method Dec 11 14:28:25 volumio volumio[1198]: No state is present for card sndallodigione Dec 11 14:28:25 volumio volumio[1198]: info: Output device has changed, restarting MPD Dec 11 14:28:25 volumio volumio[1198]: info: Output device has changed, restarting Shairport Sync Dec 11 14:28:25 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:25 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:25 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 11 14:28:25 volumio sudo[2377]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 14:28:25 volumio sudo[2361]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:25 volumio sudo[2377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:25 volumio sudo[2375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 14:28:25 volumio sudo[2375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:25 volumio sudo[2375]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:25 volumio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 14:28:25 volumio volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 14:28:25 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:25 volumio volumio[1198]: info: QobuzConnect: setDeactiveState invoked Dec 11 14:28:25 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:25 volumio volumio[1198]: info: Preparing to generate the ALSA configuration file Dec 11 14:28:25 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 14:28:25 volumio sudo[2391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 14:28:25 volumio sudo[2391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:25 volumio volumio[1198]: info: Output device has changed, restarting MPD Dec 11 14:28:25 volumio volumio[1198]: info: Output device has changed, restarting Shairport Sync Dec 11 14:28:25 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:25 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:25 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 11 14:28:25 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 11 14:28:25 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 11 14:28:25 volumio sudo[2394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 14:28:25 volumio sudo[2394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:25 volumio sudo[2397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 14:28:25 volumio sudo[2397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:25 volumio sudo[2394]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:25 volumio sudo[2391]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:25 volumio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 14:28:25 volumio volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 14:28:25 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:25 volumio volumio[1198]: info: QobuzConnect: setDeactiveState invoked Dec 11 14:28:25 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:25 volumio qobuz-connect[2244]: 20251211 14:28:25.553 [2244.2244] INFO SampleApp: shat down connection on UNIX socket Dec 11 14:28:25 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 14:28:25 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 14:28:25 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 14:28:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 14:28:25 volumio systemd[1]: mpd.service: Consumed 6.040s CPU time. Dec 11 14:28:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 14:28:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 14:28:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 14:28:25 volumio sudo[2407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 14:28:25 volumio sudo[2407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:25 volumio sudo[2409]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 14:28:25 volumio sudo[2409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:25 volumio sudo[2413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 14:28:25 volumio sudo[2413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 14:28:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 14:28:25 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 14:28:25 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:25 volumio sudo[2422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 14:28:25 volumio sudo[2422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:25 volumio sudo[2431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 14:28:25 volumio sudo[2431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:25 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:25 volumio volumio[1198]: info: Got it Dec 11 14:28:25 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:26 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:26 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:26 volumio sudo[2422]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:26 volumio volumio[1198]: info: Got it Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:26 volumio volumio[1198]: info: Got it Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:26 volumio sudo[2433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 14:28:26 volumio sudo[2433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:26 volumio sudo[2407]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:26 volumio sudo[2427]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 14:28:26 volumio sudo[2427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 14:28:26 volumio sudo[2413]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:26 volumio sudo[2427]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:26 volumio sudo[2409]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:26 volumio volumio[1198]: info: Dec 11 14:28:26 volumio volumio[1198]: ---------------------------- MPD announces state update: mixer Dec 11 14:28:26 volumio volumio[1198]: info: ControllerMpd::getState Dec 11 14:28:26 volumio volumio[1198]: verbose: ControllerMpd::sendMpdCommand status Dec 11 14:28:26 volumio volumio[1198]: error: MPD error: Error: write EPIPE Dec 11 14:28:26 volumio volumio[1198]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Dec 11 14:28:26 volumio volumio[1198]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 11 14:28:26 volumio volumio[1198]: info: Reading ALSA contributions from plugins. Dec 11 14:28:26 volumio volumio-remote-updater[2336]: [2025-12-11 14:28:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765459704 101 Dec 11 14:28:26 volumio sudo[2431]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:26 volumio volumio[1198]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Dec 11 14:28:26 volumio volumio[1198]: info: MPD Permissions set Dec 11 14:28:26 volumio volumio[1198]: info: MPD Permissions set Dec 11 14:28:26 volumio volumio[1198]: info: TidalConnect service stoped! Dec 11 14:28:26 volumio volumio[1198]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 11 14:28:26 volumio volumio[1198]: info: QobuzConnect: setDeactiveState invoked Dec 11 14:28:26 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:26 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 14:28:26 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 14:28:26 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:26 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:26 volumio volumio[1198]: info: Got it Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:26 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:26 volumio volumio[1198]: info: Got it Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:26 volumio volumio[1198]: info: Got it Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:26 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 14:28:26 volumio sudo[2440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 14:28:26 volumio sudo[2440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:26 volumio sudo[2433]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:26 volumio volumio[1198]: info: Got it Dec 11 14:28:26 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:26 volumio volumio[1198]: info: Executing endpoint qc_getconfig Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 11 14:28:26 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 14:28:26 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 14:28:26 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 14:28:26 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 11 14:28:26 volumio volumio[1198]: info: CoreStateMachine::getcurrentVolume Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 14:28:26 volumio volumio[1198]: info: CoreStateMachine::pushState Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::volumioPushState Dec 11 14:28:26 volumio sudo[2440]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:26 volumio volumio[1198]: info: MRS: Pushing multiroomSync output update for this device Dec 11 14:28:26 volumio volumio[1198]: info: MRS: Pushing multiroomSync output Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:26 volumio volumio[1198]: info: Got it Dec 11 14:28:26 volumio volumio[1198]: info: Got Tidal Cloud Configuration Dec 11 14:28:26 volumio volumio[1198]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 14:28:27 volumio volumio[1198]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xJoMMQ3BVoRlRV3dKPMnKwL5l0M2.301b61f0db3dd52a4075298c26b253d4.state.status' Dec 11 14:28:27 volumio volumio[1198]: info: TidalConnect service stoped! Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: Executing endpoint qc_getconfig Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 11 14:28:27 volumio qobuz-connect[2447]: 20251211 14:28:27.590 [2447.2447] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 11 14:28:27 volumio qobuz-connect[2447]: 20251211 14:28:27.604 [2447.2447] INFO VolumeManager: [0x1c59368]: Setting new playback volume: 75 Dec 11 14:28:27 volumio qobuz-connect[2447]: 20251211 14:28:27.604 [2447.2447] INFO VolumeManager: [0x1c59368]: Setting new mute state: 0 Dec 11 14:28:27 volumio qobuz-connect[2447]: 20251211 14:28:27.604 [2447.2447] INFO QobuzConnect: [0x1c59d38]: Client initialized! Dec 11 14:28:27 volumio qobuz-connect[2447]: 20251211 14:28:27.604 [2447.2447] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 11 14:28:27 volumio volumio[1198]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 11 14:28:27 volumio volumio[1198]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 11 14:28:27 volumio volumio[1198]: info: Starting Shairport Sync Dec 11 14:28:27 volumio qobuz-connect[2447]: 20251211 14:28:27.654 [2447.2447] INFO LocalConfigManager: [0x1c58c48]: Starting Local Configuration server Dec 11 14:28:27 volumio qobuz-connect[2447]: 20251211 14:28:27.654 [2447.2447] INFO SampleApp: Starting Local configuration server Dec 11 14:28:27 volumio qobuz-connect[2447]: 20251211 14:28:27.655 [2447.2447] INFO SampleApp: Connected to UNIX socket client 0x1c43818 Dec 11 14:28:27 volumio volumio[1198]: info: Starting Shairport Sync Dec 11 14:28:27 volumio volumio[1198]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 14:28:27 volumio volumio[1198]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 14:28:27 volumio qobuz-connect[2447]: 20251211 14:28:27.741 [2447.2447] INFO SampleApp: Playback volume changed: 75 Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:27 volumio volumio[1198]: info: Asound.conf file unchanged, so no further update is needed Dec 11 14:28:27 volumio volumio[1198]: info: Output device has changed, restarting MPD Dec 11 14:28:27 volumio volumio[1198]: info: Output device has changed, restarting Shairport Sync Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:27 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:29 volumio sudo[2473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 14:28:29 volumio sudo[2477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 14:28:29 volumio volumio[1198]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 14:28:29 volumio sudo[2477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:29 volumio sudo[2475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 14:28:29 volumio volumio[1198]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:29 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:29 volumio sudo[2473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:29 volumio sudo[2477]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:29 volumio sudo[2479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 14:28:29 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:29 volumio volumio[1198]: info: QobuzConnect: setDeactiveState invoked Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::volumioGetState Dec 11 14:28:29 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 14:28:29 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 14:28:29 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 14:28:29 volumio systemd[1]: shairport-sync.service: Consumed 2.761s CPU time. Dec 11 14:28:29 volumio sudo[2491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 14:28:29 volumio sudo[2491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:29 volumio sudo[2496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 14:28:29 volumio sudo[2496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:29 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 14:28:29 volumio volumio[1198]: info: MPD Permissions set Dec 11 14:28:29 volumio volumio[1198]: info: TidalConnect service stoped! Dec 11 14:28:29 volumio volumio[1198]: info: TidalConnect service stoped! Dec 11 14:28:29 volumio volumio[1198]: info: TidalConnect service started! Dec 11 14:28:29 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:29 volumio sudo[2509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 11 14:28:29 volumio sudo[2509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:29 volumio volumio[1198]: info: TidalConnect service stoped! Dec 11 14:28:29 volumio sudo[2517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 14:28:29 volumio sudo[2517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:29 volumio volumio[1198]: info: TidalConnect service stoped! Dec 11 14:28:29 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 14:28:29 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 11 14:28:29 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 14:28:29 volumio systemd[1]: mpd.service: Consumed 1.667s CPU time. Dec 11 14:28:29 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 14:28:29 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 14:28:29 volumio volumio[1198]: info: Shairport-Sync Started Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:28:29 volumio volumio[1198]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:28:29 volumio sudo[2496]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:29 volumio sudo[2491]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:29 volumio sudo[2509]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:29 volumio volumio[1198]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 14:28:29 volumio volumio[1198]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 14:28:29 volumio volumio[1198]: info: Starting Shairport Sync Dec 11 14:28:29 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 11 14:28:29 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 14:28:29 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 14:28:29 volumio volumio[1198]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 11 14:28:29 volumio volumio[1198]: Job for vtcs.service canceled. Dec 11 14:28:29 volumio volumio[1198]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Dec 11 14:28:29 volumio volumio[1198]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 14:28:29 volumio volumio[1198]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 14:28:29 volumio volumio[1198]: error: Upnp client error: Error: read ECONNRESET Dec 11 14:28:29 volumio sudo[2551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 11 14:28:29 volumio volumio[1198]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 14:28:29 volumio sudo[2551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:29 volumio volumio[1198]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 11 14:28:29 volumio volumio[1198]: Job for vtcs.service canceled. Dec 11 14:28:29 volumio volumio[1198]: at ChildProcess.exithandler (node:child_process:421:12) Dec 11 14:28:29 volumio volumio[1198]: at ChildProcess.emit (node:events:514:28) Dec 11 14:28:29 volumio volumio[1198]: at maybeClose (node:internal/child_process:1105:16) Dec 11 14:28:29 volumio volumio[1198]: at Socket. (node:internal/child_process:457:11) Dec 11 14:28:29 volumio volumio[1198]: at Socket.emit (node:events:514:28) Dec 11 14:28:29 volumio volumio[1198]: at Pipe. (node:net:337:12) { Dec 11 14:28:29 volumio volumio[1198]: code: 1, Dec 11 14:28:29 volumio volumio[1198]: killed: false, Dec 11 14:28:29 volumio volumio[1198]: signal: null, Dec 11 14:28:29 volumio volumio[1198]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Dec 11 14:28:29 volumio volumio[1198]: stdout: '', Dec 11 14:28:29 volumio volumio[1198]: stderr: 'Job for vtcs.service canceled.\n' Dec 11 14:28:29 volumio volumio[1198]: } Dec 11 14:28:29 volumio volumio[1198]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 14:28:29 volumio sudo[2553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 14:28:29 volumio sudo[2553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 14:28:29 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 14:28:29 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 14:28:29 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 14:28:29 volumio sudo[2473]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:29 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 11 14:28:29 volumio sudo[2517]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:30 volumio qobuz-connect[2447]: 20251211 14:28:30.152 [2447.2447] INFO SampleApp: Stopping Local configuration server Dec 11 14:28:30 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 11 14:28:30 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 14:28:30 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 14:28:30 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 14:28:30 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 14:28:30 volumio sudo[2553]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:30 volumio sudo[2561]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 14:28:30 volumio sudo[2561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 14:28:30 volumio sudo[2561]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:31 volumio qobuz-connect[2447]: 20251211 14:28:31.664 [2447.2447] INFO SampleApp: shat down connection on UNIX socket Dec 11 14:28:31 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 11 14:28:31 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 14:28:31 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 11 14:28:31 volumio sudo[2551]: pam_unix(sudo:session): session closed for user root Dec 11 14:28:32 volumio sudo[2610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 14:27' Dec 11 14:28:32 volumio sudo[2610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"