Dec 14 11:20:00 volumio volumio[1461]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 14 11:20:00 volumio volumio[1461]: info: CoreStateMachine::getcurrentVolume Dec 14 11:20:00 volumio volumio[1461]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 11:20:00 volumio volumio[1461]: info: VolumeController:: Volume=41 Mute =false Dec 14 11:20:00 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:00 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:00 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:00 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:00 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:00 volumio volumio[1461]: info: CoreStateMachine::updateTrackBlock Dec 14 11:20:00 volumio volumio[1461]: info: CorePlayQueue::getTrackBlock Dec 14 11:20:00 volumio volumio[1461]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 11:20:00 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:00 volumio volumio[1461]: info: VolumeController:: Volume=41 Mute =false Dec 14 11:20:00 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:00 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:00 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:00 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:00 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:00 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:02 volumio volumio[1461]: info: TidalConnect service started! Dec 14 11:20:02 volumio volumio[1461]: info: TidalConnect service started! Dec 14 11:20:02 volumio volumio[1461]: info: TidalConnect service started! Dec 14 11:20:19 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 14 11:20:19 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 11:20:19 volumio volumio-remote-updater[1013]: Test mode disabled Dec 14 11:20:19 volumio volumio-remote-updater[1013]: Alpha mode disabled Dec 14 11:20:19 volumio volumio-remote-updater[1013]: Alpha legacy test mode disabled Dec 14 11:20:19 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 14 11:20:20 volumio volumio[1461]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 14 11:20:20 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 14 11:20:21 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 14 11:20:21 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 11:20:21 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 14 11:20:21 volumio volumio-remote-updater[1013]: Test mode disabled Dec 14 11:20:21 volumio volumio-remote-updater[1013]: Alpha mode disabled Dec 14 11:20:21 volumio volumio-remote-updater[1013]: Alpha legacy test mode disabled Dec 14 11:20:21 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 14 11:20:21 volumio volumio[1461]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 14 11:20:21 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 14 11:20:21 volumio kernel: usb 3-7: new full-speed USB device number 5 using xhci_hcd Dec 14 11:20:21 volumio kernel: usb 3-7: New USB device found, idVendor=1b1c, idProduct=0a41, bcdDevice= 0.00 Dec 14 11:20:21 volumio kernel: usb 3-7: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 11:20:21 volumio kernel: usb 3-7: Product: CORSAIR VIRTUOSO USB Gaming Headset Dec 14 11:20:21 volumio kernel: usb 3-7: Manufacturer: Corsair Dec 14 11:20:21 volumio kernel: usb 3-7: SerialNumber: 162a9602000400fc Dec 14 11:20:21 volumio kernel: input: Corsair CORSAIR VIRTUOSO USB Gaming Headset as /devices/pci0000:00/0000:00:14.0/usb3/3-7/3-7:1.3/0003:1B1C:0A41.0003/input/input15 Dec 14 11:20:21 volumio kernel: input: Corsair CORSAIR VIRTUOSO USB Gaming Headset Consumer Control as /devices/pci0000:00/0000:00:14.0/usb3/3-7/3-7:1.3/0003:1B1C:0A41.0003/input/input16 Dec 14 11:20:21 volumio kernel: input: Corsair CORSAIR VIRTUOSO USB Gaming Headset as /devices/pci0000:00/0000:00:14.0/usb3/3-7/3-7:1.3/0003:1B1C:0A41.0003/input/input17 Dec 14 11:20:21 volumio kernel: hid-generic 0003:1B1C:0A41.0003: input,hiddev1,hidraw2: USB HID v1.11 Device [Corsair CORSAIR VIRTUOSO USB Gaming Headset] on usb-0000:00:14.0-7/input3 Dec 14 11:20:21 volumio kernel: hid-generic 0003:1B1C:0A41.0004: hiddev2,hidraw3: USB HID v1.11 Device [Corsair CORSAIR VIRTUOSO USB Gaming Headset] on usb-0000:00:14.0-7/input4 Dec 14 11:20:22 volumio kernel: usbcore: registered new interface driver snd-usb-audio Dec 14 11:20:22 volumio (udev-worker)[4386]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 14 11:20:22 volumio volumio[1461]: info: Dec 14 11:20:22 volumio volumio[1461]: ---------------------------- USB Audio Device Attached Dec 14 11:20:22 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 14 11:20:22 volumio volumio[1461]: info: Executing endpoint scanAudioInputs Dec 14 11:20:22 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: smart_inputs , scanAudioInputs Dec 14 11:20:22 volumio volumio[1461]: info: Scanning Audio Inputs Dec 14 11:20:22 volumio volumio[1461]: info: Checking against Known Cards name Dec 14 11:20:22 volumio volumio[1461]: info: Checking against Known Cards name Dec 14 11:20:22 volumio volumio[1461]: info: Checking against Known Cards name Dec 14 11:20:22 volumio volumio[1461]: info: Checking against Known Cards name Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 14 11:20:27 volumio volumio[1461]: info: Preparing to save Alsa Options, stopping services first Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:27 volumio volumio[1461]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0,0","label":"Analog Out"},"disallowPush":true} Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:20:27 volumio volumio[1461]: info: Found match in Cards Database: setting mixer for card Analog Out Dec 14 11:20:27 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:27 volumio vtcs[4290]: [2025-12-14 11:20:27.843] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 14 11:20:27 volumio vtcs[4290]: [2025-12-14 11:20:27.843] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 14 11:20:27 volumio sudo[4445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:27 volumio sudo[4445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio volumio[1461]: info: Setting mixer Master for card Analog Out Dec 14 11:20:27 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:27 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 14 11:20:27 volumio systemd[1]: vtcs.service: Killing process 4298 (vtcs) with signal SIGKILL. Dec 14 11:20:27 volumio sudo[4451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:27 volumio sudo[4451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 14 11:20:27 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 14 11:20:27 volumio sudo[4445]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 14 11:20:27 volumio volumio[1461]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 14 11:20:27 volumio volumio[1461]: info: Disabling external Volume Control Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:20:27 volumio volumio[1461]: info: Preparing to generate the ALSA configuration file Dec 14 11:20:27 volumio sudo[4451]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:27 volumio sudo[4460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:27 volumio sudo[4460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio sudo[4462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:27 volumio sudo[4462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:27 volumio sudo[4460]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:27 volumio sudo[4470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:20:27 volumio sudo[4470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio sudo[4462]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:27 volumio volumio[1461]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 11:20:27 volumio volumio[1461]: info: Reading ALSA contributions from plugins. Dec 14 11:20:27 volumio sudo[4479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:20:27 volumio sudo[4479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio sudo[4470]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:27 volumio sudo[4479]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:27 volumio sudo[4482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:20:27 volumio sudo[4482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:20:27 volumio sudo[4486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:20:27 volumio sudo[4486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 11:20:27 volumio qobuz-connect[4241]: 20251214 11:20:27.891 [4241.4241] INFO SampleApp: Stopping Local configuration server Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 14 11:20:27 volumio volumio[1461]: info: VolumeController:: Volume=41 Mute =false Dec 14 11:20:27 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:27 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:27 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:27 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:27 volumio volumio[1461]: info: Asound.conf file unchanged, so no further update is needed Dec 14 11:20:27 volumio volumio[1461]: info: Output device has changed, restarting MPD Dec 14 11:20:27 volumio volumio[1461]: info: Output device has changed, restarting Shairport Sync Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:27 volumio sudo[4493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 11:20:27 volumio sudo[4493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio sudo[4493]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:27 volumio sudo[4495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 11:20:27 volumio sudo[4495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio volumio[1461]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 11:20:27 volumio volumio[1461]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:27 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 11:20:27 volumio sudo[4505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:27 volumio sudo[4505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio sudo[4507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:27 volumio sudo[4507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:27 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 11:20:27 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 14 11:20:27 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 11:20:27 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 11:20:27 volumio volumio[1461]: info: MPD Permissions set Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:27 volumio sudo[4516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:20:27 volumio sudo[4516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 11:20:27 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:27 volumio volumio[1461]: info: Starting Shairport Sync Dec 14 11:20:27 volumio sudo[4528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 11:20:27 volumio sudo[4528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 11:20:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 11:20:27 volumio sudo[4516]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:27 volumio sudo[4507]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:27 volumio sudo[4505]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:27 volumio sudo[4531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:20:27 volumio sudo[4531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:27 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 11:20:27 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 11:20:27 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:20:27 volumio systemd[1]: shairport-sync.service: Consumed 1.702s CPU time. Dec 14 11:20:27 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:20:27 volumio sudo[4528]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:27 volumio volumio[1461]: info: Shairport-Sync Started Dec 14 11:20:27 volumio sudo[4530]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 11:20:27 volumio sudo[4530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 11:20:27 volumio sudo[4530]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:28 volumio mpd[4551]: 2025-12-14T11:20:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 11:20:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 11:20:28 volumio sudo[4495]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:28 volumio volumio[1461]: error: updateQueue error: null Dec 14 11:20:28 volumio qobuz-connect[4241]: 20251214 11:20:28.876 [4241.4241] INFO SampleApp: shat down connection on UNIX socket Dec 14 11:20:28 volumio volumio[1461]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 11:20:28 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:20:28 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:28 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 11:20:28 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 11:20:28 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 11:20:28 volumio sudo[4531]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:28 volumio sudo[4486]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:28 volumio sudo[4482]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:28 volumio volumio[1461]: info: Executing endpoint qc_getconfig Dec 14 11:20:28 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 11:20:28 volumio qobuz-connect[4555]: 20251214 11:20:28.923 [4555.4555] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 11:20:28 volumio volumio[1461]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 11:20:28 volumio volumio[1461]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 11:20:28 volumio qobuz-connect[4555]: 20251214 11:20:28.925 [4555.4555] INFO VolumeManager: [0x55bbeaed7e70]: Setting new playback volume: 75 Dec 14 11:20:28 volumio qobuz-connect[4555]: 20251214 11:20:28.925 [4555.4555] INFO VolumeManager: [0x55bbeaed7e70]: Setting new mute state: 0 Dec 14 11:20:28 volumio qobuz-connect[4555]: 20251214 11:20:28.925 [4555.4555] INFO QobuzConnect: [0x55bbeaed9090]: Client initialized! Dec 14 11:20:28 volumio qobuz-connect[4555]: 20251214 11:20:28.925 [4555.4555] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 14 11:20:28 volumio qobuz-connect[4555]: 20251214 11:20:28.928 [4555.4555] INFO LocalConfigManager: [0x55bbeaed7160]: Starting Local Configuration server Dec 14 11:20:28 volumio qobuz-connect[4555]: 20251214 11:20:28.928 [4555.4555] INFO SampleApp: Starting Local configuration server Dec 14 11:20:28 volumio qobuz-connect[4555]: 20251214 11:20:28.928 [4555.4555] INFO SampleApp: Connected to UNIX socket client 0x55bbeaeadbb0 Dec 14 11:20:29 volumio qobuz-connect[4555]: 20251214 11:20:29.073 [4555.4555] INFO SampleApp: Playback volume changed: 75 Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:29 volumio volumio[1461]: verbose: New Socket.io Connection to 192.168.3.19 from 192.168.3.12 UA: Mozilla/5.0 (Linux; Android 15; 24095PCADG Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:20:29 volumio volumio[1461]: info: Retrieving Cloud Streaming UI Dec 14 11:20:29 volumio volumio[1461]: info: Getting Tidal Cloud Configuration Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:20:29 volumio volumio[1461]: info: Getting Qobuz Cloud Configuration Dec 14 11:20:29 volumio volumio[1461]: info: Asking plugin for UI Config Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:20:29 volumio volumio[1461]: info: Getting Spotify Cloud Configuration Dec 14 11:20:29 volumio volumio[1461]: info: Asking plugin for UI Config Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:20:29 volumio volumio[1461]: info: Saving Spotify Acccount Dec 14 11:20:29 volumio volumio[1461]: info: Got it Dec 14 11:20:29 volumio volumio[1461]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 14 11:20:29 volumio volumio[1461]: info: Got Tidal Cloud Configuration Dec 14 11:20:29 volumio volumio[1461]: info: Got it Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:29 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:20:30 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:30 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:30 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:30 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:30 volumio volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:30 volumio volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:30 volumio volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:30 volumio volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:30 volumio sudo[4584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:20:30 volumio sudo[4584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:30 volumio sudo[4586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:20:30 volumio sudo[4586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:30 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 11:20:30 volumio sudo[4584]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:30 volumio sudo[4586]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:30 volumio volumio[1461]: info: Executing endpoint tc_getconfig Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 14 11:20:30 volumio vtcs[4590]: STARTING TidalConnect services, version: 1.5.2.56 Dec 14 11:20:30 volumio vtcs[4590]: STARTED TidalConnect services. Dec 14 11:20:30 volumio volumio[1461]: info: Executing endpoint tc_connect Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 14 11:20:30 volumio volumio[1461]: info: Connecting to TidalConnect Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::servicePushState Dec 14 11:20:30 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:30 volumio volumio[1461]: info: CorePlayQueue::getTrack 0 Dec 14 11:20:30 volumio volumio[1461]: 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 14 11:20:30 volumio volumio[1461]: verbose: CURRENT POSITION 0 Dec 14 11:20:30 volumio volumio[1461]: info: CoreStateMachine::syncState stateService stop Dec 14 11:20:30 volumio volumio[1461]: info: CoreStateMachine::syncState currentStatus stop Dec 14 11:20:30 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:30 volumio volumio[1461]: info: No code Dec 14 11:20:30 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::servicePushState Dec 14 11:20:30 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:30 volumio volumio[1461]: info: CorePlayQueue::getTrack 0 Dec 14 11:20:30 volumio volumio[1461]: 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 14 11:20:30 volumio volumio[1461]: verbose: CURRENT POSITION 0 Dec 14 11:20:30 volumio volumio[1461]: info: CoreStateMachine::syncState stateService stop Dec 14 11:20:30 volumio volumio[1461]: info: CoreStateMachine::syncState currentStatus stop Dec 14 11:20:30 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:30 volumio volumio[1461]: info: No code Dec 14 11:20:30 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:30 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:30 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:30 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:30 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:30 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:30 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:30 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:30 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:30 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:30 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:30 volumio volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:30 volumio volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:30 volumio sudo[4616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:20:30 volumio sudo[4616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:30 volumio sudo[4616]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:31 volumio sudo[4620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 11:20:31 volumio sudo[4620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:31 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 11:20:31 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 11:20:31 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 11:20:31 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 11:20:31 volumio sudo[4620]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:31 volumio mpd_monitor.sh[4622]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 11:20:31 volumio volumio[1461]: info: Successfully started MPD Monitor Dec 14 11:20:31 volumio volumio[1461]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 14 11:20:31 volumio volumio[1461]: info: CoreStateMachine::getcurrentVolume Dec 14 11:20:31 volumio volumio[1461]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 11:20:31 volumio volumio[1461]: info: VolumeController:: Volume=41 Mute =false Dec 14 11:20:31 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:31 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:31 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:31 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:31 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:31 volumio volumio[1461]: info: CoreStateMachine::updateTrackBlock Dec 14 11:20:31 volumio volumio[1461]: info: CorePlayQueue::getTrackBlock Dec 14 11:20:31 volumio volumio[1461]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 11:20:31 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:31 volumio volumio[1461]: info: VolumeController:: Volume=41 Mute =false Dec 14 11:20:31 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:31 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:31 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:31 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:31 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:31 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:33 volumio volumio[1461]: info: TidalConnect service started! Dec 14 11:20:33 volumio volumio[1461]: info: TidalConnect service started! Dec 14 11:20:34 volumio volumio[1461]: info: TidalConnect service started! Dec 14 11:20:35 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 14 11:20:35 volumio volumio[1461]: info: Preparing to save Alsa Options, stopping services first Dec 14 11:20:35 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:35 volumio volumio[1461]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"CORSAIR VIRTUOSO USB Gaming Hea"},"disallowPush":true} Dec 14 11:20:35 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:20:36 volumio volumio[1461]: info: Setting mixer Sidetone for card CORSAIR VIRTUOSO USB Gaming Hea Dec 14 11:20:36 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:36 volumio vtcs[4590]: [2025-12-14 11:20:36.016] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 14 11:20:36 volumio vtcs[4590]: [2025-12-14 11:20:36.016] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 14 11:20:36 volumio sudo[4648]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:36 volumio sudo[4648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 14 11:20:36 volumio volumio[1461]: info: Updating Volume Controller Parameters: Device: 5 Name: CORSAIR VIRTUOSO USB Gaming Hea Mixer: Sidetone Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 14 11:20:36 volumio volumio[1461]: info: Disabling external Volume Control Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:20:36 volumio volumio[1461]: info: Preparing to generate the ALSA configuration file Dec 14 11:20:36 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 14 11:20:36 volumio systemd[1]: vtcs.service: Killing process 4598 (vtcs) with signal SIGKILL. Dec 14 11:20:36 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 14 11:20:36 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 14 11:20:36 volumio sudo[4648]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio sudo[4656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:36 volumio sudo[4656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 11:20:36 volumio volumio[1461]: info: Reading ALSA contributions from plugins. Dec 14 11:20:36 volumio sudo[4663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:20:36 volumio sudo[4663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4656]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4663]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:20:36 volumio sudo[4672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:20:36 volumio sudo[4672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 14 11:20:36 volumio volumio[1461]: info: VolumeController:: Volume=5 Mute =true Dec 14 11:20:36 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:36 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:36 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:36 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 11:20:36 volumio qobuz-connect[4555]: 20251214 11:20:36.068 [4555.4555] INFO SampleApp: Stopping Local configuration server Dec 14 11:20:36 volumio volumio[1461]: info: Asound.conf file written Dec 14 11:20:36 volumio sudo[4679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 14 11:20:36 volumio sudo[4679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4679]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: No state is present for card PCH Dec 14 11:20:36 volumio volumio[1461]: Found hardware: "HDA-Intel" "Realtek Generic" "HDA:10ec0256,1019b89b,00100002 HDA:8086281f,80860101,00100000" "0x1019" "0xb89b" Dec 14 11:20:36 volumio volumio[1461]: Hardware is initialized using a generic method Dec 14 11:20:36 volumio volumio[1461]: No state is present for card PCH Dec 14 11:20:36 volumio volumio[1461]: No state is present for card Hea Dec 14 11:20:36 volumio volumio[1461]: Found hardware: "USB-Audio" "USB Mixer" "USB1b1c:0a41" "" "" Dec 14 11:20:36 volumio volumio[1461]: Hardware is initialized using a generic method Dec 14 11:20:36 volumio volumio[1461]: No state is present for card Hea Dec 14 11:20:36 volumio volumio[1461]: info: Output device has changed, restarting MPD Dec 14 11:20:36 volumio volumio[1461]: info: Output device has changed, restarting Shairport Sync Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio sudo[4685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 11:20:36 volumio sudo[4685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4685]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 11:20:36 volumio sudo[4687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 11:20:36 volumio volumio[1461]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:36 volumio volumio[1461]: info: Output device has changed, restarting MPD Dec 14 11:20:36 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 11:20:36 volumio volumio[1461]: info: Output device has changed, restarting Shairport Sync Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio sudo[4697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:36 volumio sudo[4697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 11:20:36 volumio sudo[4699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 11:20:36 volumio volumio[1461]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 11:20:36 volumio sudo[4701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 11:20:36 volumio sudo[4699]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio sudo[4701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 14 11:20:36 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 11:20:36 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:36 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 14 11:20:36 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 11:20:36 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 11:20:36 volumio sudo[4712]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:36 volumio sudo[4712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:36 volumio sudo[4718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:36 volumio sudo[4715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio sudo[4726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:20:36 volumio sudo[4726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: MPD Permissions set Dec 14 11:20:36 volumio volumio[1461]: info: MPD Permissions set Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio sudo[4733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:20:36 volumio sudo[4733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 11:20:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: Starting Shairport Sync Dec 14 11:20:36 volumio volumio[1461]: info: Starting Shairport Sync Dec 14 11:20:36 volumio sudo[4753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 11:20:36 volumio sudo[4753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: Preparing to generate the ALSA configuration file Dec 14 11:20:36 volumio sudo[4726]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4733]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 11:20:36 volumio volumio[1461]: info: Reading ALSA contributions from plugins. Dec 14 11:20:36 volumio sudo[4755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 11:20:36 volumio sudo[4755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: Asound.conf file written Dec 14 11:20:36 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 14 11:20:36 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 11:20:36 volumio sudo[4757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:20:36 volumio sudo[4757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:20:36 volumio sudo[4758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 14 11:20:36 volumio sudo[4763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4763]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4712]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4715]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: No state is present for card PCH Dec 14 11:20:36 volumio volumio[1461]: Found hardware: "HDA-Intel" "Realtek Generic" "HDA:10ec0256,1019b89b,00100002 HDA:8086281f,80860101,00100000" "0x1019" "0xb89b" Dec 14 11:20:36 volumio volumio[1461]: Hardware is initialized using a generic method Dec 14 11:20:36 volumio volumio[1461]: No state is present for card PCH Dec 14 11:20:36 volumio volumio[1461]: No state is present for card Hea Dec 14 11:20:36 volumio volumio[1461]: Found hardware: "USB-Audio" "USB Mixer" "USB1b1c:0a41" "" "" Dec 14 11:20:36 volumio volumio[1461]: Hardware is initialized using a generic method Dec 14 11:20:36 volumio volumio[1461]: No state is present for card Hea Dec 14 11:20:36 volumio volumio[1461]: info: Output device has changed, restarting MPD Dec 14 11:20:36 volumio volumio[1461]: info: Output device has changed, restarting Shairport Sync Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio sudo[4718]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 11:20:36 volumio sudo[4697]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 11:20:36 volumio sudo[4772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4772]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 11:20:36 volumio volumio[1461]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:36 volumio volumio[1461]: info: Preparing to generate the ALSA configuration file Dec 14 11:20:36 volumio sudo[4786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:36 volumio sudo[4786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 14 11:20:36 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 11:20:36 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 11:20:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 11:20:36 volumio sudo[4789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:36 volumio sudo[4789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 11:20:36 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 11:20:36 volumio volumio[1461]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 11:20:36 volumio volumio[1461]: info: Reading ALSA contributions from plugins. Dec 14 11:20:36 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 11:20:36 volumio volumio[1461]: info: MPD Permissions set Dec 14 11:20:36 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:20:36 volumio systemd[1]: shairport-sync.service: Consumed 1.562s CPU time. Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio sudo[4798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:20:36 volumio sudo[4798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 11:20:36 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:20:36 volumio sudo[4753]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: Shairport-Sync Started Dec 14 11:20:36 volumio volumio[1461]: info: Starting Shairport Sync Dec 14 11:20:36 volumio sudo[4755]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: Shairport-Sync Started Dec 14 11:20:36 volumio volumio[1461]: info: Asound.conf file unchanged, so no further update is needed Dec 14 11:20:36 volumio volumio[1461]: info: Output device has changed, restarting MPD Dec 14 11:20:36 volumio sudo[4798]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 11:20:36 volumio volumio[1461]: info: Output device has changed, restarting Shairport Sync Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio sudo[4810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 11:20:36 volumio sudo[4812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:20:36 volumio sudo[4815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 11:20:36 volumio sudo[4786]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4812]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4795]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 11:20:36 volumio sudo[4795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 11:20:36 volumio volumio[1461]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 11:20:36 volumio volumio[1461]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio sudo[4795]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:36 volumio sudo[4789]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:36 volumio sudo[4844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:20:36 volumio sudo[4846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: MPD Permissions set Dec 14 11:20:36 volumio sudo[4855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:20:36 volumio sudo[4855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 11:20:36 volumio sudo[4855]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:20:36 volumio volumio[1461]: info: Starting Shairport Sync Dec 14 11:20:36 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 11:20:36 volumio sudo[4865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:20:36 volumio sudo[4865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 11:20:36 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:20:36 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 14 11:20:36 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 11:20:36 volumio sudo[4867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 11:20:36 volumio sudo[4867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:36 volumio sudo[4846]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4844]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 14 11:20:36 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 11:20:36 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 11:20:36 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 11:20:36 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 11:20:36 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:20:36 volumio sudo[4810]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: Shairport-Sync Started Dec 14 11:20:36 volumio volumio[1461]: (node:1461) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 11:20:36 volumio volumio[1461]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 14 11:20:36 volumio volumio[1461]: (node:1461) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 11:20:36 volumio volumio[1461]: (node:1461) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 11:20:36 volumio volumio[1461]: (node:1461) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 11:20:36 volumio volumio[1461]: (node:1461) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 14 11:20:36 volumio sudo[4867]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: Shairport-Sync Started Dec 14 11:20:36 volumio sudo[4873]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 11:20:36 volumio sudo[4873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 11:20:36 volumio sudo[4873]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio mpd[4890]: 2025-12-14T11:20:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 11:20:36 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 11:20:36 volumio sudo[4701]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4775]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4815]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4687]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: error: MPD error: The expression evaluated to a falsy value: Dec 14 11:20:36 volumio volumio[1461]: assert.ok(self.idling) Dec 14 11:20:36 volumio volumio[1461]: error: The expression evaluated to a falsy value: Dec 14 11:20:36 volumio volumio[1461]: assert.ok(self.idling) Dec 14 11:20:36 volumio volumio[1461]: error: MPD error: The expression evaluated to a falsy value: Dec 14 11:20:36 volumio volumio[1461]: assert.ok(self.idling) Dec 14 11:20:36 volumio volumio[1461]: error: The expression evaluated to a falsy value: Dec 14 11:20:36 volumio volumio[1461]: assert.ok(self.idling) Dec 14 11:20:36 volumio volumio[1461]: error: MPD error: The expression evaluated to a falsy value: Dec 14 11:20:36 volumio volumio[1461]: assert.ok(self.idling) Dec 14 11:20:36 volumio volumio[1461]: error: The expression evaluated to a falsy value: Dec 14 11:20:36 volumio volumio[1461]: assert.ok(self.idling) Dec 14 11:20:36 volumio volumio[1461]: error: updateQueue error: null Dec 14 11:20:36 volumio qobuz-connect[4555]: 20251214 11:20:36.941 [4555.4555] INFO SampleApp: shat down connection on UNIX socket Dec 14 11:20:36 volumio volumio[1461]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 11:20:36 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:36 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 11:20:36 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 11:20:36 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 11:20:36 volumio sudo[4757]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4672]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4865]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4758]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio sudo[4814]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:36 volumio volumio[1461]: info: Executing endpoint qc_getconfig Dec 14 11:20:36 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 11:20:36 volumio qobuz-connect[4894]: 20251214 11:20:36.987 [4894.4894] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 11:20:36 volumio volumio[1461]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 11:20:36 volumio volumio[1461]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 11:20:36 volumio qobuz-connect[4894]: 20251214 11:20:36.989 [4894.4894] INFO VolumeManager: [0x55a55eb68e70]: Setting new playback volume: 75 Dec 14 11:20:36 volumio qobuz-connect[4894]: 20251214 11:20:36.989 [4894.4894] INFO VolumeManager: [0x55a55eb68e70]: Setting new mute state: 0 Dec 14 11:20:36 volumio qobuz-connect[4894]: 20251214 11:20:36.989 [4894.4894] INFO QobuzConnect: [0x55a55eb6a090]: Client initialized! Dec 14 11:20:36 volumio qobuz-connect[4894]: 20251214 11:20:36.989 [4894.4894] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 14 11:20:36 volumio qobuz-connect[4894]: 20251214 11:20:36.991 [4894.4894] INFO LocalConfigManager: [0x55a55eb68160]: Starting Local Configuration server Dec 14 11:20:36 volumio qobuz-connect[4894]: 20251214 11:20:36.991 [4894.4894] INFO SampleApp: Starting Local configuration server Dec 14 11:20:36 volumio qobuz-connect[4894]: 20251214 11:20:36.991 [4894.4894] INFO SampleApp: Connected to UNIX socket client 0x55a55eb3ebb0 Dec 14 11:20:37 volumio qobuz-connect[4894]: 20251214 11:20:37.137 [4894.4894] INFO SampleApp: Playback volume changed: 75 Dec 14 11:20:37 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:39 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:39 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:39 volumio volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:39 volumio volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:39 volumio sudo[4935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:20:39 volumio sudo[4935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:39 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 11:20:39 volumio sudo[4935]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:39 volumio volumio[1461]: info: Executing endpoint tc_getconfig Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 14 11:20:39 volumio vtcs[4938]: STARTING TidalConnect services, version: 1.5.2.56 Dec 14 11:20:39 volumio vtcs[4938]: STARTED TidalConnect services. Dec 14 11:20:39 volumio volumio[1461]: info: Executing endpoint tc_connect Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 14 11:20:39 volumio volumio[1461]: info: Connecting to TidalConnect Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::servicePushState Dec 14 11:20:39 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:39 volumio volumio[1461]: info: CorePlayQueue::getTrack 0 Dec 14 11:20:39 volumio volumio[1461]: 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 14 11:20:39 volumio volumio[1461]: verbose: CURRENT POSITION 0 Dec 14 11:20:39 volumio volumio[1461]: info: CoreStateMachine::syncState stateService stop Dec 14 11:20:39 volumio volumio[1461]: info: CoreStateMachine::syncState currentStatus stop Dec 14 11:20:39 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:39 volumio volumio[1461]: info: No code Dec 14 11:20:39 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::servicePushState Dec 14 11:20:39 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:39 volumio volumio[1461]: info: CorePlayQueue::getTrack 0 Dec 14 11:20:39 volumio volumio[1461]: 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 14 11:20:39 volumio volumio[1461]: verbose: CURRENT POSITION 0 Dec 14 11:20:39 volumio volumio[1461]: info: CoreStateMachine::syncState stateService stop Dec 14 11:20:39 volumio volumio[1461]: info: CoreStateMachine::syncState currentStatus stop Dec 14 11:20:39 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:39 volumio volumio[1461]: info: No code Dec 14 11:20:39 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:39 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:39 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:39 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:39 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:39 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:39 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:39 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:39 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:39 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:39 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:39 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:39 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:39 volumio volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:39 volumio volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:39 volumio volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:39 volumio volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:39 volumio sudo[4967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:20:39 volumio sudo[4967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:39 volumio sudo[4974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:20:39 volumio sudo[4974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:39 volumio sudo[4967]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:39 volumio sudo[4974]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:39 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:39 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:39 volumio volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:39 volumio volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:39 volumio sudo[4987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:20:39 volumio sudo[4987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:39 volumio sudo[4987]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:39 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:39 volumio volumio[1461]: info: TidalConnect service stoped! Dec 14 11:20:39 volumio volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:39 volumio volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:20:39 volumio sudo[4999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:20:39 volumio sudo[4999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:39 volumio sudo[4999]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:39 volumio sudo[5004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 11:20:39 volumio sudo[5004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:39 volumio sudo[5006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 11:20:39 volumio sudo[5006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:39 volumio sudo[5008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 11:20:39 volumio sudo[5008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:39 volumio sudo[5010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 11:20:39 volumio sudo[5010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:20:39 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 11:20:39 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 11:20:39 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 11:20:39 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 11:20:39 volumio sudo[5006]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:39 volumio sudo[5008]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:39 volumio sudo[5010]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:39 volumio sudo[5004]: pam_unix(sudo:session): session closed for user root Dec 14 11:20:39 volumio mpd_monitor.sh[5015]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 11:20:39 volumio volumio[1461]: info: Successfully started MPD Monitor Dec 14 11:20:39 volumio volumio[1461]: info: Successfully started MPD Monitor Dec 14 11:20:39 volumio volumio[1461]: info: Successfully started MPD Monitor Dec 14 11:20:39 volumio volumio[1461]: info: Successfully started MPD Monitor Dec 14 11:20:40 volumio volumio[1461]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 14 11:20:40 volumio volumio[1461]: info: CoreStateMachine::getcurrentVolume Dec 14 11:20:40 volumio volumio[1461]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 11:20:40 volumio volumio[1461]: info: VolumeController:: Volume=5 Mute =true Dec 14 11:20:40 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:40 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:40 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:40 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:40 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:40 volumio volumio[1461]: info: CoreStateMachine::updateTrackBlock Dec 14 11:20:40 volumio volumio[1461]: info: CorePlayQueue::getTrackBlock Dec 14 11:20:40 volumio volumio[1461]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 11:20:40 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:40 volumio volumio[1461]: info: VolumeController:: Volume=5 Mute =true Dec 14 11:20:40 volumio volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:20:40 volumio volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:20:40 volumio volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:20:40 volumio volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:20:40 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:20:40 volumio volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:20:42 volumio volumio[1461]: info: TidalConnect service started! Dec 14 11:20:42 volumio volumio[1461]: info: TidalConnect service started! Dec 14 11:20:42 volumio volumio[1461]: info: TidalConnect service started! Dec 14 11:20:42 volumio volumio[1461]: info: TidalConnect service started! Dec 14 11:20:42 volumio volumio[1461]: info: TidalConnect service started! Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 14 11:21:12 volumio volumio[1461]: info: System name has changed, restarting Shairport Sync Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:12 volumio volumio[1461]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 11:21:12 volumio volumio[1461]: info: Updating MyVolumio device info Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:12 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:12 volumio vtcs[4938]: [2025-12-14 11:21:12.936] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 14 11:21:12 volumio vtcs[4938]: [2025-12-14 11:21:12.936] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 14 11:21:12 volumio volumio[1461]: info: Discovery: Restarting Advertising due to device name change Dec 14 11:21:12 volumio sudo[5109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:21:12 volumio sudo[5109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:12 volumio sudo[5111]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 14 11:21:12 volumio sudo[5111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:12 volumio sudo[5113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:21:12 volumio sudo[5113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 14 11:21:12 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 14 11:21:12 volumio systemd[1]: vtcs.service: Killing process 4943 (vtcs) with signal SIGKILL. Dec 14 11:21:12 volumio sudo[5111]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 14 11:21:12 volumio sudo[5122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:12 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:21:12 volumio sudo[5122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:12 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 14 11:21:12 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 14 11:21:12 volumio volumio[1461]: info: Starting Shairport Sync Dec 14 11:21:12 volumio sudo[5109]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:12 volumio sudo[5122]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:12 volumio sudo[5128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Dec 14 11:21:12 volumio sudo[5128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:12 volumio sudo[5130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 11:21:12 volumio sudo[5130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:12 volumio sudo[5113]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:12 volumio sudo[5128]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:12 volumio volumio[1461]: info: Permissions for /etc/hosts set Dec 14 11:21:12 volumio sudo[5132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:21:12 volumio sudo[5132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 11:21:12 volumio volumio[1461]: info: Hostname now is room Dec 14 11:21:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 11:21:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:21:12 volumio systemd[1]: shairport-sync.service: Consumed 1.736s CPU time. Dec 14 11:21:13 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:21:13 volumio sudo[5130]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:13 volumio volumio[1461]: info: Shairport-Sync Started Dec 14 11:21:13 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 11:21:13 volumio qobuz-connect[4894]: 20251214 11:21:13.016 [4894.4894] INFO SampleApp: Stopping Local configuration server Dec 14 11:21:13 volumio qobuz-connect[4894]: 20251214 11:21:13.026 [4894.4894] INFO SampleApp: shat down connection on UNIX socket Dec 14 11:21:13 volumio volumio[1461]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 11:21:13 volumio volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:21:13 volumio volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:13 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 11:21:13 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 11:21:13 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 11:21:13 volumio sudo[5132]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:13 volumio volumio[1461]: info: Executing endpoint qc_getconfig Dec 14 11:21:13 volumio volumio[1461]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 11:21:13 volumio qobuz-connect[5157]: 20251214 11:21:13.042 [5157.5157] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 11:21:13 volumio volumio[1461]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 11:21:13 volumio volumio[1461]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 11:21:13 volumio qobuz-connect[5157]: 20251214 11:21:13.043 [5157.5157] INFO VolumeManager: [0x56085ab1fe70]: Setting new playback volume: 75 Dec 14 11:21:13 volumio qobuz-connect[5157]: 20251214 11:21:13.043 [5157.5157] INFO VolumeManager: [0x56085ab1fe70]: Setting new mute state: 0 Dec 14 11:21:13 volumio qobuz-connect[5157]: 20251214 11:21:13.043 [5157.5157] INFO QobuzConnect: [0x56085ab21090]: Client initialized! Dec 14 11:21:13 volumio qobuz-connect[5157]: 20251214 11:21:13.043 [5157.5157] INFO SampleApp: Starting Avahi advertising, name: Room, service name: _qobuz-connect._tcp Dec 14 11:21:13 volumio qobuz-connect[5157]: 20251214 11:21:13.046 [5157.5157] INFO LocalConfigManager: [0x56085ab1f160]: Starting Local Configuration server Dec 14 11:21:13 volumio qobuz-connect[5157]: 20251214 11:21:13.046 [5157.5157] INFO SampleApp: Starting Local configuration server Dec 14 11:21:13 volumio qobuz-connect[5157]: 20251214 11:21:13.047 [5157.5157] INFO SampleApp: Connected to UNIX socket client 0x56085aaf5bb0 Dec 14 11:21:13 volumio sudo[5141]: volumio : unable to resolve host volumio: System error Dec 14 11:21:13 volumio sudo[5138]: volumio : unable to resolve host volumio: System error Dec 14 11:21:13 volumio sudo[5138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname room Dec 14 11:21:13 volumio sudo[5141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Dec 14 11:21:13 volumio sudo[5141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:13 volumio sudo[5138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:13 room sudo[5138]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:13 room volumio[1461]: info: New hostname set Dec 14 11:21:13 room sudo[5141]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:13 room volumio[1461]: info: Permissions for /etc/avahi/services/volumio.service Dec 14 11:21:13 room volumio[1461]: info: Avahi name changed to room Dec 14 11:21:13 room avahi-daemon[1003]: Files changed, reloading. Dec 14 11:21:13 room avahi-daemon[1003]: Loading service file /services/volumio.service. Dec 14 11:21:13 room qobuz-connect[5157]: 20251214 11:21:13.192 [5157.5157] INFO SampleApp: Playback volume changed: 75 Dec 14 11:21:13 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:13 room volumio[1461]: info: Successfully Updated MyVolumio device Dec 14 11:21:13 room volumio[1461]: info: Discovery: A device disappeared from network Dec 14 11:21:13 room volumio[1461]: info: Discovery: Device volumio disappeared from network Dec 14 11:21:13 room volumio[1461]: info: Discovery: A device disappeared from network Dec 14 11:21:13 room avahi-daemon[1003]: Service "Room" (/services/volumio.service) successfully established. Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 14 11:21:15 room volumio[1461]: info: Preparing to save Alsa Options, stopping services first Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:15 room volumio[1461]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"CORSAIR VIRTUOSO USB Gaming Hea"},"disallowPush":true} Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:21:15 room volumio[1461]: info: Setting mixer Sidetone for card CORSAIR VIRTUOSO USB Gaming Hea Dec 14 11:21:15 room volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:15 room sudo[5178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:21:15 room sudo[5178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 14 11:21:15 room volumio[1461]: info: Updating Volume Controller Parameters: Device: 5 Name: CORSAIR VIRTUOSO USB Gaming Hea Mixer: Sidetone Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 14 11:21:15 room volumio[1461]: info: Disabling external Volume Control Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:15 room volumio[1461]: info: Preparing to generate the ALSA configuration file Dec 14 11:21:15 room sudo[5178]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:15 room sudo[5186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:21:15 room sudo[5186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 11:21:15 room volumio[1461]: info: Reading ALSA contributions from plugins. Dec 14 11:21:15 room sudo[5193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:21:15 room sudo[5186]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:15 room sudo[5193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:15 room sudo[5193]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:21:15 room sudo[5201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:21:15 room sudo[5201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 14 11:21:15 room systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 11:21:15 room qobuz-connect[5157]: 20251214 11:21:15.212 [5157.5157] INFO SampleApp: Stopping Local configuration server Dec 14 11:21:15 room volumio[1461]: info: VolumeController:: Volume=5 Mute =true Dec 14 11:21:15 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:15 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:15 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:15 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:15 room volumio[1461]: info: Asound.conf file unchanged, so no further update is needed Dec 14 11:21:15 room volumio[1461]: info: Output device has changed, restarting MPD Dec 14 11:21:15 room volumio[1461]: info: Output device has changed, restarting Shairport Sync Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:15 room sudo[5207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 11:21:15 room sudo[5207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:15 room sudo[5207]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:15 room sudo[5209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 11:21:15 room sudo[5209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:15 room volumio[1461]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 11:21:15 room volumio[1461]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:15 room systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 11:21:15 room sudo[5219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:21:15 room sudo[5219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:15 room systemd[1]: mpd.service: Deactivated successfully. Dec 14 11:21:15 room systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 11:21:15 room systemd[1]: mpd.socket: Deactivated successfully. Dec 14 11:21:15 room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 11:21:15 room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:15 room sudo[5221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:21:15 room sudo[5221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:15 room volumio[1461]: info: MPD Permissions set Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:15 room sudo[5230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:21:15 room sudo[5230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 11:21:15 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:15 room volumio[1461]: info: Starting Shairport Sync Dec 14 11:21:15 room sudo[5241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 11:21:15 room sudo[5241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:15 room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 11:21:15 room systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 11:21:15 room sudo[5230]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:15 room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 11:21:15 room systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 11:21:15 room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:21:15 room systemd[1]: shairport-sync.service: Consumed 1.530s CPU time. Dec 14 11:21:15 room sudo[5243]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 11:21:15 room sudo[5244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:21:15 room sudo[5243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 11:21:15 room sudo[5244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:15 room sudo[5243]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:15 room sudo[5219]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:15 room sudo[5221]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:15 room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:21:15 room sudo[5241]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:15 room volumio[1461]: info: Shairport-Sync Started Dec 14 11:21:15 room mpd[5247]: 2025-12-14T11:21:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 11:21:15 room systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 11:21:15 room sudo[5209]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:15 room volumio[1461]: error: updateQueue error: null Dec 14 11:21:15 room volumio[1461]: info: TidalConnect service stoped! Dec 14 11:21:15 room volumio[1461]: info: TidalConnect service stoped! Dec 14 11:21:15 room volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:21:15 room volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:21:15 room sudo[5278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:21:15 room sudo[5278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:16 room systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 11:21:16 room sudo[5278]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:16 room volumio[1461]: info: Executing endpoint tc_getconfig Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 14 11:21:16 room vtcs[5281]: STARTING TidalConnect services, version: 1.5.2.56 Dec 14 11:21:16 room vtcs[5281]: STARTED TidalConnect services. Dec 14 11:21:16 room volumio[1461]: info: Executing endpoint tc_connect Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 14 11:21:16 room volumio[1461]: info: Connecting to TidalConnect Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::servicePushState Dec 14 11:21:16 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:16 room volumio[1461]: info: CorePlayQueue::getTrack 0 Dec 14 11:21:16 room volumio[1461]: 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 14 11:21:16 room volumio[1461]: verbose: CURRENT POSITION 0 Dec 14 11:21:16 room volumio[1461]: info: CoreStateMachine::syncState stateService stop Dec 14 11:21:16 room volumio[1461]: info: CoreStateMachine::syncState currentStatus stop Dec 14 11:21:16 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:16 room volumio[1461]: info: No code Dec 14 11:21:16 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::servicePushState Dec 14 11:21:16 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:16 room volumio[1461]: info: CorePlayQueue::getTrack 0 Dec 14 11:21:16 room volumio[1461]: 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 14 11:21:16 room volumio[1461]: verbose: CURRENT POSITION 0 Dec 14 11:21:16 room volumio[1461]: info: CoreStateMachine::syncState stateService stop Dec 14 11:21:16 room volumio[1461]: info: CoreStateMachine::syncState currentStatus stop Dec 14 11:21:16 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:16 room volumio[1461]: info: No code Dec 14 11:21:16 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:16 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:16 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:16 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:16 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:16 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:16 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:16 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:16 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:17 room qobuz-connect[5157]: 20251214 11:21:17.059 [5157.5157] INFO SampleApp: shat down connection on UNIX socket Dec 14 11:21:17 room volumio[1461]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 11:21:17 room volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:21:17 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:17 room systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 11:21:17 room systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 11:21:17 room systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 11:21:17 room sudo[5244]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:17 room sudo[5201]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:17 room volumio[1461]: info: Executing endpoint qc_getconfig Dec 14 11:21:17 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 11:21:17 room qobuz-connect[5299]: 20251214 11:21:17.120 [5299.5299] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 11:21:17 room volumio[1461]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 11:21:17 room volumio[1461]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 11:21:17 room qobuz-connect[5299]: 20251214 11:21:17.122 [5299.5299] INFO VolumeManager: [0x55b6eacd4e70]: Setting new playback volume: 75 Dec 14 11:21:17 room qobuz-connect[5299]: 20251214 11:21:17.122 [5299.5299] INFO VolumeManager: [0x55b6eacd4e70]: Setting new mute state: 0 Dec 14 11:21:17 room qobuz-connect[5299]: 20251214 11:21:17.122 [5299.5299] INFO QobuzConnect: [0x55b6eacd6090]: Client initialized! Dec 14 11:21:17 room qobuz-connect[5299]: 20251214 11:21:17.122 [5299.5299] INFO SampleApp: Starting Avahi advertising, name: Room, service name: _qobuz-connect._tcp Dec 14 11:21:17 room qobuz-connect[5299]: 20251214 11:21:17.125 [5299.5299] INFO LocalConfigManager: [0x55b6eacd4160]: Starting Local Configuration server Dec 14 11:21:17 room qobuz-connect[5299]: 20251214 11:21:17.125 [5299.5299] INFO SampleApp: Starting Local configuration server Dec 14 11:21:17 room qobuz-connect[5299]: 20251214 11:21:17.125 [5299.5299] INFO SampleApp: Connected to UNIX socket client 0x55b6eacaabb0 Dec 14 11:21:17 room qobuz-connect[5299]: 20251214 11:21:17.270 [5299.5299] INFO SampleApp: Playback volume changed: 75 Dec 14 11:21:17 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:17 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 14 11:21:17 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 14 11:21:17 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 14 11:21:17 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:17 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:21:17 room volumio[1461]: info: Discovery: Started advertising with name: Room Dec 14 11:21:18 room volumio[1461]: info: TidalConnect service stoped! Dec 14 11:21:18 room volumio[1461]: info: TidalConnect service stoped! Dec 14 11:21:18 room volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:21:18 room volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:21:18 room sudo[5333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:21:18 room sudo[5333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:18 room sudo[5333]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:18 room volumio[1461]: info: TidalConnect service stoped! Dec 14 11:21:18 room volumio[1461]: info: TidalConnect service stoped! Dec 14 11:21:18 room volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:21:18 room volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:21:18 room sudo[5345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:21:18 room sudo[5345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:18 room sudo[5345]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:18 room sudo[5349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 11:21:18 room sudo[5349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:18 room systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 11:21:18 room systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 11:21:18 room systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 11:21:18 room systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 11:21:18 room sudo[5349]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:18 room mpd_monitor.sh[5351]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 11:21:18 room volumio[1461]: info: Successfully started MPD Monitor Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 14 11:21:18 room volumio[1461]: info: Preparing to save Alsa Options, stopping services first Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:18 room volumio[1461]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"5","label":"CORSAIR VIRTUOSO USB Gaming Hea"},"disallowPush":true} Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:21:18 room volumio[1461]: info: Setting mixer Sidetone for card CORSAIR VIRTUOSO USB Gaming Hea Dec 14 11:21:18 room volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:18 room vtcs[5281]: [2025-12-14 11:21:18.697] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 14 11:21:18 room vtcs[5281]: [2025-12-14 11:21:18.697] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 14 11:21:18 room sudo[5366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:21:18 room sudo[5366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 14 11:21:18 room volumio[1461]: info: Updating Volume Controller Parameters: Device: 5 Name: CORSAIR VIRTUOSO USB Gaming Hea Mixer: Sidetone Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 14 11:21:18 room volumio[1461]: info: Disabling external Volume Control Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:18 room volumio[1461]: info: Preparing to generate the ALSA configuration file Dec 14 11:21:18 room systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 14 11:21:18 room systemd[1]: vtcs.service: Killing process 5285 (vtcs) with signal SIGKILL. Dec 14 11:21:18 room systemd[1]: vtcs.service: Deactivated successfully. Dec 14 11:21:18 room systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 14 11:21:18 room sudo[5366]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:18 room sudo[5373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:21:18 room sudo[5373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 14 11:21:18 room sudo[5381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:21:18 room sudo[5381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:18 room sudo[5373]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:18 room volumio[1461]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 11:21:18 room volumio[1461]: info: Reading ALSA contributions from plugins. Dec 14 11:21:18 room sudo[5383]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 14 11:21:18 room sudo[5383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:18 room sudo[5386]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 14 11:21:18 room sudo[5386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:18 room sudo[5381]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:18 room sudo[5383]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: Microcode SW error detected. Restarting 0x0. Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: Start IWL Error Log Dump: Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: Transport status: 0x0000004B, valid: 6 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: Loaded firmware version: 89.7f71c7f4.0 so-a0-gf-a0-89.ucode Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000071 | NMI_INTERRUPT_UMAC_FATAL Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x000002F0 | trm_hw_status0 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | trm_hw_status1 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x004D9CC8 | branchlink2 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x004CF836 | interruptlink1 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x004CF836 | interruptlink2 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x000159DA | data1 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000010 | data2 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | data3 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | beacon time Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x05313205 | tsf low Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | tsf hi Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | time gp1 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x05327BA0 | time gp2 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000001 | uCode revision type Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000059 | uCode version major Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x7F71C7F4 | uCode version minor Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000370 | hw version Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00C80002 | board version Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x806CFF00 | hcmd Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00020000 | isr0 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x20000000 | isr1 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x48F04802 | isr2 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00C3000C | isr3 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | isr4 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00170103 | last cmd Id Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x000159DA | wait_event Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_control Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_duration Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_mhvalid Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | l2p_addr_match Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000018 | lmpm_pmg_sel Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | timestamp Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x000088AC | flow_handler Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: Start IWL Error Log Dump: Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: Transport status: 0x0000004B, valid: 7 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x2010101D | ADVANCED_SYSASSERT Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | umac branchlink1 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x8048829A | umac branchlink2 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x01001000 | umac interruptlink1 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | umac interruptlink2 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x0000003E | umac data1 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0xDEADBEEF | umac data2 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0xDEADBEEF | umac data3 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000059 | umac major Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x7F71C7F4 | umac minor Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x05327B9B | frame pointer Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0xC0886B4C | stack pointer Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x0026010D | last host cmd Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000000 | isr status reg Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: IML/ROM dump: Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000B03 | IML/ROM error/state Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00008291 | IML/ROM data1 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000090 | IML/ROM WFPM_AUTH_KEY_0 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: Fseq Registers: Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x60000000 | FSEQ_ERROR_CODE Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x803E0003 | FSEQ_TOP_INIT_VERSION Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00190004 | FSEQ_CNVIO_INIT_VERSION Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x0000A652 | FSEQ_OTP_VERSION Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000003 | FSEQ_TOP_CONTENT_VERSION Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x4552414E | FSEQ_ALIVE_TOKEN Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00080400 | FSEQ_CNVI_ID Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00400410 | FSEQ_CNVR_ID Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00080400 | CNVI_AUX_MISC_CHIP Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00400410 | CNVR_AUX_MISC_CHIP Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00009061 | CNVR_SCU_SD_REGS_SD_REG_DIG_DCDC_VTRIM Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00000061 | CNVR_SCU_SD_REGS_SD_REG_ACTIVE_VDIG_MIRROR Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x00190004 | FSEQ_PREV_CNVIO_INIT_VERSION Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x003E0003 | FSEQ_WIFI_FSEQ_VERSION Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x003E0003 | FSEQ_BT_FSEQ_VERSION Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: 0x000000E6 | FSEQ_CLASS_TP_VERSION Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: UMAC CURRENT PC: 0x804abf68 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: LMAC1 CURRENT PC: 0xd0 Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: WRT: Collecting data: ini trigger 4 fired (delay=0ms). Dec 14 11:21:18 room kernel: ieee80211 phy0: Hardware restart was requested Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: FW error in SYNC CMD SCAN_REQ_UMAC Dec 14 11:21:18 room kernel: CPU: 11 UID: 0 PID: 5388 Comm: iwlist Tainted: G W 6.12.57-volumio #2 Dec 14 11:21:18 room kernel: Tainted: [W]=WARN Dec 14 11:21:18 room kernel: Hardware name: ECS LIVA Z5 PLUS/RPLU-MINI, BIOS 20240726.1 07/26/2024 Dec 14 11:21:18 room kernel: Call Trace: Dec 14 11:21:18 room kernel: Dec 14 11:21:18 room kernel: dump_stack_lvl+0x5f/0x80 Dec 14 11:21:18 room kernel: dump_stack+0x14/0x20 Dec 14 11:21:18 room kernel: iwl_trans_pcie_send_hcmd+0x359/0x460 [iwlwifi] Dec 14 11:21:18 room kernel: ? __pfx_autoremove_wake_function+0x10/0x10 Dec 14 11:21:18 room kernel: iwl_trans_send_cmd+0x4f/0xd0 [iwlwifi] Dec 14 11:21:18 room kernel: iwl_mvm_send_cmd+0x1a/0x50 [iwlmvm] Dec 14 11:21:18 room kernel: _iwl_mvm_single_scan_start+0x3f5/0x650 [iwlmvm] Dec 14 11:21:18 room kernel: iwl_mvm_reg_scan_start+0x18/0x20 [iwlmvm] Dec 14 11:21:18 room kernel: ? iwl_mvm_reg_scan_start+0x18/0x20 [iwlmvm] Dec 14 11:21:18 room kernel: iwl_mvm_mac_hw_scan+0x53/0x80 [iwlmvm] Dec 14 11:21:18 room kernel: drv_hw_scan+0xa7/0x160 [mac80211] Dec 14 11:21:18 room kernel: __ieee80211_start_scan+0x2de/0x760 [mac80211] Dec 14 11:21:18 room kernel: ? get_page_from_freelist+0x1083/0x1220 Dec 14 11:21:18 room kernel: ieee80211_request_scan+0x12/0x20 [mac80211] Dec 14 11:21:18 room kernel: ieee80211_scan+0x59/0xe0 [mac80211] Dec 14 11:21:18 room kernel: rdev_scan+0x4d/0xf0 [cfg80211] Dec 14 11:21:18 room kernel: cfg80211_wext_siwscan+0x2ab/0x450 [cfg80211] Dec 14 11:21:18 room kernel: ? __kmalloc_noprof+0x149/0x3b0 Dec 14 11:21:18 room kernel: ioctl_standard_iw_point+0x196/0x380 Dec 14 11:21:18 room kernel: ? __pfx_cfg80211_wext_siwscan+0x10/0x10 [cfg80211] Dec 14 11:21:18 room kernel: ? __pfx_ioctl_standard_call+0x10/0x10 Dec 14 11:21:18 room kernel: ioctl_standard_call+0xb2/0xe0 Dec 14 11:21:18 room kernel: ? netdev_name_node_lookup+0x5b/0x70 Dec 14 11:21:18 room kernel: ? __pfx_ioctl_private_call+0x10/0x10 Dec 14 11:21:18 room kernel: wireless_process_ioctl+0x1ab/0x210 Dec 14 11:21:18 room kernel: wext_handle_ioctl+0x154/0x190 Dec 14 11:21:18 room kernel: ? alloc_file_pseudo+0xbd/0x110 Dec 14 11:21:18 room kernel: sock_ioctl+0x121/0x340 Dec 14 11:21:18 room kernel: __x64_sys_ioctl+0x99/0xd0 Dec 14 11:21:18 room kernel: x64_sys_call+0x108d/0x2060 Dec 14 11:21:18 room kernel: do_syscall_64+0x4b/0x110 Dec 14 11:21:18 room kernel: entry_SYSCALL_64_after_hwframe+0x76/0x7e Dec 14 11:21:18 room kernel: RIP: 0033:0x7fc21b3e5d5b Dec 14 11:21:18 room kernel: Code: 00 48 89 44 24 18 31 c0 48 8d 44 24 60 c7 04 24 10 00 00 00 48 89 44 24 08 48 8d 44 24 20 48 89 44 24 10 b8 10 00 00 00 0f 05 <89> c2 3d 00 f0 ff ff 77 1c 48 8b 44 24 18 64 48 2b 04 25 28 00 00 Dec 14 11:21:18 room kernel: RSP: 002b:00007fff4da17a10 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 Dec 14 11:21:18 room kernel: RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fc21b3e5d5b Dec 14 11:21:18 room kernel: RDX: 00007fff4da17ae0 RSI: 0000000000008b18 RDI: 0000000000000003 Dec 14 11:21:18 room kernel: RBP: 00007fff4da17b80 R08: 000000000000000a R09: 0000000000000000 Dec 14 11:21:18 room kernel: R10: 00007fc21b2ff378 R11: 0000000000000246 R12: 0000000000000000 Dec 14 11:21:18 room kernel: R13: 000055e9fedf74e0 R14: 00007fff4da18220 R15: 0000000000000000 Dec 14 11:21:18 room kernel: Dec 14 11:21:18 room kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5 Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 14 11:21:18 room volumio[1461]: info: VolumeController:: Volume=5 Mute =true Dec 14 11:21:18 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:18 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:18 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:18 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:18 room volumio[1461]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 14 11:21:18 room volumio[1461]: wlan0 Interface doesn't support scanning : Input/output error Dec 14 11:21:18 room volumio[1461]: info: Cannot use regular scanning, forcing with ap-force Dec 14 11:21:19 room sudo[5386]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:19 room kernel: iwlwifi 0000:00:14.3: Scan failed! ret -5 Dec 14 11:21:19 room kernel: iwlwifi 0000:00:14.3: WFPM_UMAC_PD_NOTIFICATION: 0x20 Dec 14 11:21:19 room kernel: iwlwifi 0000:00:14.3: WFPM_LMAC2_PD_NOTIFICATION: 0x1f Dec 14 11:21:19 room kernel: iwlwifi 0000:00:14.3: WFPM_AUTH_KEY_0: 0x90 Dec 14 11:21:19 room kernel: iwlwifi 0000:00:14.3: CNVI_SCU_SEQ_DATA_DW9: 0x0 Dec 14 11:21:19 room kernel: iwlwifi 0000:00:14.3: RFIm is deactivated, reason = 4 Dec 14 11:21:19 room sudo[5391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:21:19 room sudo[5391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:19 room sudo[5400]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 14 11:21:19 room sudo[5400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:19 room systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 11:21:19 room qobuz-connect[5299]: 20251214 11:21:19.488 [5299.5299] INFO SampleApp: Stopping Local configuration server Dec 14 11:21:21 room qobuz-connect[5299]: 20251214 11:21:21.137 [5299.5299] INFO SampleApp: shat down connection on UNIX socket Dec 14 11:21:21 room systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 11:21:21 room systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 11:21:21 room systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 11:21:21 room sudo[5391]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:22 room sudo[5400]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:22 room volumio[1461]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 14 11:21:22 room volumio[1461]: info: CoreStateMachine::getcurrentVolume Dec 14 11:21:22 room volumio[1461]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 11:21:22 room volumio[1461]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 14 11:21:22 room volumio[1461]: info: CoreStateMachine::getcurrentVolume Dec 14 11:21:22 room volumio[1461]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 11:21:22 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:22 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:22 room volumio[1461]: info: Retrieving Cloud Streaming UI Dec 14 11:21:22 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:22 room volumio[1461]: info: Retrieving Cloud Streaming UI Dec 14 11:21:22 room volumio[1461]: info: TidalConnect service stoped! Dec 14 11:21:22 room volumio[1461]: info: TidalConnect service stoped! Dec 14 11:21:22 room volumio[1461]: info: TidalConnect service started! Dec 14 11:21:22 room volumio[1461]: info: TidalConnect service started! Dec 14 11:21:22 room volumio[1461]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 14 11:21:22 room volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:21:22 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:22 room volumio[1461]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 14 11:21:22 room volumio[1461]: wlan0 Interface doesn't support scanning : Input/output error Dec 14 11:21:22 room volumio[1461]: info: Cannot use regular scanning, forcing with ap-force Dec 14 11:21:22 room sudo[5417]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 14 11:21:22 room sudo[5417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room sudo[5417]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room volumio[1461]: info: TidalConnect service started! Dec 14 11:21:25 room sudo[5423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Dec 14 11:21:25 room sudo[5423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room sudo[5425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Dec 14 11:21:25 room sudo[5425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room sudo[5425]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room sudo[5427]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 11:21:25 room sudo[5427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 14 11:21:25 room sudo[5423]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room sudo[5430]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 11:21:25 room sudo[5430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room sudo[5427]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room sudo[5430]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:25 room volumio[1461]: info: Retrieving Cloud Streaming UI Dec 14 11:21:25 room volumio[1461]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:21:25 room volumio[1461]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 11:21:25 room volumio[1461]: info: smb.conf Permissions set Dec 14 11:21:25 room volumio[1461]: info: Internal perms successfully set Dec 14 11:21:25 room volumio[1461]: info: Asound.conf file unchanged, so no further update is needed Dec 14 11:21:25 room volumio[1461]: info: Output device has changed, restarting MPD Dec 14 11:21:25 room volumio[1461]: info: Output device has changed, restarting Shairport Sync Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:25 room sudo[5452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 11:21:25 room sudo[5452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room sudo[5452]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room sudo[5454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 11:21:25 room sudo[5454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room volumio[1461]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 11:21:25 room volumio[1461]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:25 room volumio[1461]: info: QobuzConnect: setDeactiveState invoked Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:25 room systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 11:21:25 room sudo[5464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:21:25 room sudo[5464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room sudo[5466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 11:21:25 room sudo[5466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:25 room systemd[1]: mpd.service: Deactivated successfully. Dec 14 11:21:25 room systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 11:21:25 room systemd[1]: mpd.socket: Deactivated successfully. Dec 14 11:21:25 room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 11:21:25 room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 11:21:25 room volumio[1461]: info: VolumeController:: Volume=5 Mute =true Dec 14 11:21:25 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:25 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:25 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:25 room volumio[1461]: info: CoreStateMachine::updateTrackBlock Dec 14 11:21:25 room volumio[1461]: info: CorePlayQueue::getTrackBlock Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 11:21:25 room sudo[5475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 11:21:25 room sudo[5475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room volumio[1461]: info: VolumeController:: Volume=5 Mute =true Dec 14 11:21:25 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:25 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:25 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:25 room volumio[1461]: info: CoreStateMachine::updateTrackBlock Dec 14 11:21:25 room volumio[1461]: info: CorePlayQueue::getTrackBlock Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 11:21:25 room volumio[1461]: info: Getting Tidal Cloud Configuration Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:25 room volumio[1461]: info: Getting Qobuz Cloud Configuration Dec 14 11:21:25 room volumio[1461]: info: Asking plugin for UI Config Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:25 room volumio[1461]: info: Getting Spotify Cloud Configuration Dec 14 11:21:25 room volumio[1461]: info: Asking plugin for UI Config Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:25 room volumio[1461]: info: Saving Spotify Acccount Dec 14 11:21:25 room volumio[1461]: info: Got it Dec 14 11:21:25 room volumio[1461]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 14 11:21:25 room volumio[1461]: info: Getting Tidal Cloud Configuration Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:25 room volumio[1461]: info: Getting Qobuz Cloud Configuration Dec 14 11:21:25 room volumio[1461]: info: Asking plugin for UI Config Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:25 room volumio[1461]: info: Getting Spotify Cloud Configuration Dec 14 11:21:25 room volumio[1461]: info: Asking plugin for UI Config Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:25 room volumio[1461]: info: Saving Spotify Acccount Dec 14 11:21:25 room volumio[1461]: info: Got it Dec 14 11:21:25 room volumio[1461]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 14 11:21:25 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:25 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:25 room volumio[1461]: info: MPD Permissions set Dec 14 11:21:25 room volumio[1461]: info: Getting Tidal Cloud Configuration Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:25 room volumio[1461]: info: Getting Qobuz Cloud Configuration Dec 14 11:21:25 room volumio[1461]: info: Asking plugin for UI Config Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:25 room volumio[1461]: info: Getting Spotify Cloud Configuration Dec 14 11:21:25 room volumio[1461]: info: Asking plugin for UI Config Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 11:21:25 room volumio[1461]: info: Saving Spotify Acccount Dec 14 11:21:25 room volumio[1461]: info: Got it Dec 14 11:21:25 room volumio[1461]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 14 11:21:25 room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 11:21:25 room sudo[5482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 11:21:25 room sudo[5482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 11:21:25 room volumio[1461]: info: Executing endpoint qc_getconfig Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 11:21:25 room qobuz-connect[5406]: 20251214 11:21:25.372 [5406.5406] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 11:21:25 room volumio[1461]: info: VolumeController:: Volume=5 Mute =true Dec 14 11:21:25 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:25 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:25 room qobuz-connect[5406]: 20251214 11:21:25.373 [5406.5406] INFO VolumeManager: [0x55810ae2de70]: Setting new playback volume: 75 Dec 14 11:21:25 room qobuz-connect[5406]: 20251214 11:21:25.373 [5406.5406] INFO VolumeManager: [0x55810ae2de70]: Setting new mute state: 0 Dec 14 11:21:25 room qobuz-connect[5406]: 20251214 11:21:25.373 [5406.5406] INFO QobuzConnect: [0x55810ae2f090]: Client initialized! Dec 14 11:21:25 room qobuz-connect[5406]: 20251214 11:21:25.373 [5406.5406] INFO SampleApp: Starting Avahi advertising, name: Room, service name: _qobuz-connect._tcp Dec 14 11:21:25 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:25 room sudo[5475]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room sudo[5464]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room sudo[5466]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room qobuz-connect[5406]: 20251214 11:21:25.375 [5406.5406] INFO LocalConfigManager: [0x55810ae2d160]: Starting Local Configuration server Dec 14 11:21:25 room qobuz-connect[5406]: 20251214 11:21:25.375 [5406.5406] INFO SampleApp: Starting Local configuration server Dec 14 11:21:25 room qobuz-connect[5406]: 20251214 11:21:25.375 [5406.5406] INFO SampleApp: Connected to UNIX socket client 0x55810ae03bb0 Dec 14 11:21:25 room sudo[5490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 11:21:25 room sudo[5490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 14 11:21:25 room sudo[5482]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:21:25 room systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 11:21:25 room qobuz-connect[5406]: 20251214 11:21:25.382 [5406.5406] INFO SampleApp: Stopping Local configuration server Dec 14 11:21:25 room volumio[1461]: info: Discovery: adding cfc09ca0-557c-4cee-a4a3-78a2f8eabf82 Dec 14 11:21:25 room volumio[1461]: info: Discovery: Found device Room Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:25 room sudo[5505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 14 11:21:25 room sudo[5505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:21:25 room volumio[1461]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 11:21:25 room volumio[1461]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 11:21:25 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:25 room volumio[1461]: info: VolumeController:: Volume=5 Mute =true Dec 14 11:21:25 room volumio[1461]: info: CoreStateMachine::pushState Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioPushState Dec 14 11:21:25 room volumio[1461]: info: MRS: Pushing multiroomSync output update for this device Dec 14 11:21:25 room volumio[1461]: info: MRS: Pushing multiroomSync output Dec 14 11:21:25 room volumio[1461]: info: CoreCommandRouter::volumioGetState Dec 14 11:21:25 room sudo[5484]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 11:21:25 room sudo[5484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 11:21:25 room sudo[5484]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room sudo[5505]: pam_unix(sudo:session): session closed for user root Dec 14 11:21:25 room volumio[1461]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.YO1dMpqIwANry5w3dmQf0LQOaRU2.d801b87e28ee5bf968b84afcd71f0cca.state.status' Dec 14 11:21:25 room volumio[1461]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 14 11:21:25 room volumio[1461]: Job for vtcs.service canceled. Dec 14 11:21:25 room volumio[1461]: {"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 14 11:21:25 room volumio[1461]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 11:21:25 room volumio[1461]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 14 11:21:25 room volumio[1461]: Job for vtcs.service canceled. Dec 14 11:21:25 room volumio[1461]: at ChildProcess.exithandler (node:child_process:421:12) Dec 14 11:21:25 room volumio[1461]: at ChildProcess.emit (node:events:514:28) Dec 14 11:21:25 room volumio[1461]: at maybeClose (node:internal/child_process:1105:16) Dec 14 11:21:25 room volumio[1461]: at Socket. (node:internal/child_process:457:11) Dec 14 11:21:25 room volumio[1461]: at Socket.emit (node:events:514:28) Dec 14 11:21:25 room volumio[1461]: at Pipe. (node:net:337:12) { Dec 14 11:21:25 room volumio[1461]: code: 1, Dec 14 11:21:25 room volumio[1461]: killed: false, Dec 14 11:21:25 room volumio[1461]: signal: null, Dec 14 11:21:25 room volumio[1461]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Dec 14 11:21:25 room volumio[1461]: stdout: '', Dec 14 11:21:25 room volumio[1461]: stderr: 'Job for vtcs.service canceled.\n' Dec 14 11:21:25 room volumio[1461]: } Dec 14 11:21:25 room volumio[1461]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 11:21:25 room sudo[5523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 11:20' Dec 14 11:21:25 room sudo[5523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"