Oct 15 09:55:01 rivo volumio[3210]: info: CoreCommandRouter::volumioVolatilePlay Oct 15 09:55:01 rivo volumio[3210]: info: CoreStateMachine::volatilePlay Oct 15 09:55:01 rivo volumio[3210]: info: Received play: Oct 15 09:55:01 rivo vtcs[4081]: [start:106] Entering Oct 15 09:55:01 rivo vtcs[4081]: [start:113] Reopening PCM device... Oct 15 09:55:01 rivo volumio[3210]: info: CoreCommandRouter::servicePushState Oct 15 09:55:01 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:55:01 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 09:55:01 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:55:01 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:55:01 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:55:01 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:55:01 rivo vtcs[4081]: [start:115] PCM opened successfully. Oct 15 09:55:01 rivo vtcs[4081]: [start:120] Exiting Oct 15 09:55:01 rivo vtcs[4081]: [feedThread:223] Entering Oct 15 09:55:01 rivo volumio5-onboarding[3790]: time=2026-10-15T09:55:01.703Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_PLAYING positionMs=46528 volume=100 Oct 15 09:55:01 rivo volumio5-onboarding[3790]: time=2026-10-15T09:55:01.704Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id= title="Nature's Ratio" Oct 15 09:55:01 rivo volumio[3210]: info: Signalling Playback active due to playback status change Oct 15 09:55:01 rivo volumio[3210]: info: MCU Signalled Playback Active Oct 15 09:55:01 rivo volumio[3210]: info: CoreCommandRouter::servicePushState Oct 15 09:55:01 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:55:01 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 09:55:01 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:55:01 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:55:01 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:55:01 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:55:01 rivo volumio5-onboarding[3790]: time=2026-10-15T09:55:01.925Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_PLAYING positionMs=47053 volume=100 Oct 15 09:55:01 rivo volumio5-onboarding[3790]: time=2026-10-15T09:55:01.926Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id= title="Nature's Ratio" Oct 15 09:55:23 rivo vtcs[4081]: [2026-10-15 09:55:23] [error] handle_read_frame error: asio.system:125 (Operation aborted.) Oct 15 09:55:49 rivo kernel: usb 1-1.4.2: USB disconnect, device number 5 Oct 15 09:55:49 rivo vtcs[4081]: ALSA lib ./src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Oct 15 09:55:49 rivo vtcs[4081]: ALSA lib pcm.c:8573:(snd_pcm_recover) cannot recovery from underrun, prepare failed: No such device Oct 15 09:55:49 rivo vtcs[4081]: [feedThread:265] ALSA error: No such device Oct 15 09:55:49 rivo vtcs[4081]: [feedThread:276] Exiting Oct 15 09:55:49 rivo volumio[3210]: info: Oct 15 09:55:49 rivo volumio[3210]: ---------------------------- USB Audio Device Detached Oct 15 09:55:49 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 15 09:55:49 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 09:55:49 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 15 09:55:49 rivo volumio[3210]: info: Stopping RAAT Plugin Oct 15 09:55:49 rivo sudo[5330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Oct 15 09:55:49 rivo sudo[5330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:49 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 15 09:55:49 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 15 09:55:49 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 15 09:55:49 rivo sudo[5330]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:49 rivo volumio[3210]: info: Raat Daemon stopped successfully Oct 15 09:55:51 rivo kernel: usb 1-1.4.2: new high-speed USB device number 6 using xhci-hcd Oct 15 09:55:51 rivo kernel: usb 1-1.4.2: New USB device found, idVendor=16d0, idProduct=071a Oct 15 09:55:51 rivo kernel: usb 1-1.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 15 09:55:51 rivo kernel: usb 1-1.4.2: Product: Combo384 Amanero Oct 15 09:55:51 rivo kernel: usb 1-1.4.2: Manufacturer: Amanero Technologies Oct 15 09:55:51 rivo kernel: usb 1-1.4.2: SerialNumber: 413-001 Oct 15 09:55:51 rivo kernel: usb 1-1.4.2: Unsupported device Oct 15 09:55:51 rivo kernel: usb 1-1.4.2: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Oct 15 09:55:51 rivo kernel: usb 1-1.4.2: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Oct 15 09:55:51 rivo kernel: usb 1-1.4.2: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Oct 15 09:55:51 rivo kernel: usb 1-1.4.2: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Oct 15 09:55:51 rivo (udev-worker)[5324]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Oct 15 09:55:51 rivo volumio[3210]: info: Oct 15 09:55:51 rivo volumio[3210]: ---------------------------- USB Audio Device Attached Oct 15 09:55:51 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 15 09:55:51 rivo volumio[3210]: info: CoreCommandRouter::Close All Modals sent Oct 15 09:55:51 rivo volumio[3210]: info: Preparing to save Alsa Options, stopping services first Oct 15 09:55:51 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:55:51 rivo volumio[3210]: info: CoreCommandRouter::volumioPause Oct 15 09:55:51 rivo volumio[3210]: info: CoreStateMachine::pause Oct 15 09:55:51 rivo volumio[3210]: info: CoreStateMachine::stPlaybackTimer Oct 15 09:55:51 rivo volumio[3210]: info: CoreStateMachine::servicePause Oct 15 09:55:51 rivo volumio[3210]: info: CoreCommandRouter::servicePause Oct 15 09:55:51 rivo volumio[3210]: info: Received pause Oct 15 09:55:51 rivo volumio[3210]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Combo384 Amanero","alsacard":"Amanero"},"i2s":false} Oct 15 09:55:51 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 09:55:51 rivo vtcs[4081]: [pause:147] Entering Oct 15 09:55:51 rivo vtcs[4081]: [pause:154] ALSA error: No such device Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 09:55:52 rivo volumio[3210]: info: Setting mixer PCM for card Combo384 Amanero Oct 15 09:55:52 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:55:52 rivo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 15 09:55:52 rivo volumio[3210]: info: Relinquishing Volumio State Oct 15 09:55:52 rivo volumio[3210]: verbose: UNSET VOLATILE: Service: tidalconnect Oct 15 09:55:52 rivo volumio[3210]: info: unSetVolatile called Oct 15 09:55:52 rivo volumio[3210]: info: Relinquishing Volumio State to another service Oct 15 09:55:52 rivo vtcs[4081]: [close:85] Entering Oct 15 09:55:52 rivo vtcs[4079]: Bus error Oct 15 09:55:52 rivo systemd[1]: vtcs.service: Main process exited, code=exited, status=135/n/a Oct 15 09:55:52 rivo systemd[1]: vtcs.service: Failed with result 'exit-code'. Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 15 09:55:52 rivo volumio[3210]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 15 09:55:52 rivo volumio[3210]: info: Disabling external Volume Control Oct 15 09:55:52 rivo volumio[3210]: info: VolumeController:: Starting alsactl monitor Oct 15 09:55:52 rivo sudo[5346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:55:52 rivo sudo[5346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 09:55:52 rivo volumio[3210]: info: Preparing to generate the ALSA configuration file Oct 15 09:55:52 rivo systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. Oct 15 09:55:52 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 15 09:55:52 rivo sudo[5346]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:52 rivo sudo[5353]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:55:52 rivo sudo[5353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:52 rivo volumio[3210]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 15 09:55:52 rivo volumio[3210]: info: Reading ALSA contributions from plugins. Oct 15 09:55:52 rivo sudo[5359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 09:55:52 rivo sudo[5359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:52 rivo sudo[5353]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 09:55:52 rivo sudo[5359]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:52 rivo sudo[5365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 09:55:52 rivo sudo[5365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 15 09:55:52 rivo volumio[3210]: info: VolumeController:: Volume=46 Mute =false Oct 15 09:55:52 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:55:52 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:55:52 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:52 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:55:52 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:55:52 rivo volumio5-onboarding[3790]: time=2026-10-15T09:55:52.534Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_PAUSED positionMs=322 volume=46 Oct 15 09:55:52 rivo volumio5-onboarding[3790]: time=2026-10-15T09:55:52.536Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:55:52 rivo qobuz-connect[3889]: 20261015 09:55:52.537 [3889.3889] INFO SampleApp: Stopping Local configuration server Oct 15 09:55:52 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::volumioGetQueue Oct 15 09:55:52 rivo volumio[3210]: info: CoreStateMachine::getQueue Oct 15 09:55:52 rivo volumio[3210]: info: CorePlayQueue::getQueue Oct 15 09:55:52 rivo volumio[3210]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 15 09:55:52 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:55:52 rivo volumio[3210]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: Asound.conf file unchanged, so no further update is needed Oct 15 09:55:52 rivo volumio[3210]: info: Output device has changed, restarting MPD Oct 15 09:55:52 rivo volumio[3210]: info: Output device has changed, restarting Shairport Sync Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:55:52 rivo sudo[5377]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 09:55:52 rivo sudo[5377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:52 rivo sudo[5379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 09:55:52 rivo sudo[5379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:52 rivo sudo[5377]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:52 rivo volumio[3210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 09:55:52 rivo volumio[3210]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:55:52 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:52 rivo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: Relinquishing Volumio State Oct 15 09:55:52 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 15 09:55:52 rivo sudo[5391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:55:52 rivo sudo[5391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:52 rivo sudo[5393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:55:52 rivo sudo[5393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:52 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 15 09:55:52 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 15 09:55:52 rivo sudo[5399]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 09:55:52 rivo sudo[5399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:52 rivo sudo[5391]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:55:52 rivo volumio[3210]: info: MPD Permissions set Oct 15 09:55:52 rivo volumio[3210]: info: MCU Signalled Playback Inactive Oct 15 09:55:52 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 15 09:55:52 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 09:55:52 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:52 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 15 09:55:53 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 09:55:53 rivo sudo[5399]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 15 09:55:53 rivo sudo[5393]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo sudo[5418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 09:55:53 rivo sudo[5418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 15 09:55:53 rivo sudo[5426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 15 09:55:53 rivo sudo[5426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:53 rivo volumio[3210]: info: Starting Shairport Sync Oct 15 09:55:53 rivo sudo[5426]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo sudo[5431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 15 09:55:53 rivo sudo[5431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:53 rivo sudo[5432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 09:55:53 rivo sudo[5432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:53 rivo sudo[5433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 15 09:55:53 rivo sudo[5433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:55:53 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 15 09:55:53 rivo sudo[5431]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 15 09:55:53 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 09:55:53 rivo sudo[5443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 15 09:55:53 rivo sudo[5443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:53 rivo sudo[5446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 15 09:55:53 rivo sudo[5446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:53 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 09:55:53 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 09:55:53 rivo sudo[5433]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo volumio[3210]: info: Raat Daemon started successfully Oct 15 09:55:53 rivo sudo[5446]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo volumio[3210]: info: Executing endpoint restartRAATSocket Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 15 09:55:53 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 15 09:55:53 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 09:55:53 rivo sudo[5432]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo volumio[3210]: info: Shairport-Sync Started Oct 15 09:55:53 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 15 09:55:53 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 15 09:55:53 rivo sudo[5459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 15 09:55:53 rivo sudo[5459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:53 rivo volumio[3210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 15 09:55:53 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 15 09:55:53 rivo sudo[5443]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo sudo[5416]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 09:55:53 rivo sudo[5416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 09:55:53 rivo volumio[3210]: info: Raat Daemon started successfully Oct 15 09:55:53 rivo sudo[5416]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 15 09:55:53 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 15 09:55:53 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 15 09:55:53 rivo qobuz-connect[3889]: 20261015 09:55:53.775 [3889.3889] INFO SampleApp: shat down connection on UNIX socket Oct 15 09:55:53 rivo volumio[3210]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 15 09:55:53 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:55:53 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:53 rivo volumio[3210]: info: Raat Daemon started successfully Oct 15 09:55:53 rivo sudo[5459]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 15 09:55:53 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 15 09:55:53 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:55:53 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:55:53 rivo sudo[5365]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo sudo[5418]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:53 rivo volumio[3210]: info: Executing endpoint restartRAATSocket Oct 15 09:55:53 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 15 09:55:53 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:53 rivo volumio[3210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 15 09:55:54 rivo volumio[3210]: info: Executing endpoint qc_getconfig Oct 15 09:55:54 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 15 09:55:54 rivo qobuz-connect[5493]: 20261015 09:55:54.039 [5493.5493] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 15 09:55:54 rivo volumio[3210]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 15 09:55:54 rivo volumio[3210]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 15 09:55:54 rivo qobuz-connect[5493]: 20261015 09:55:54.045 [5493.5493] INFO VolumeManager: [0xac90eaa8]: Setting new playback volume: 75 Oct 15 09:55:54 rivo qobuz-connect[5493]: 20261015 09:55:54.045 [5493.5493] INFO VolumeManager: [0xac90eaa8]: Setting new mute state: 0 Oct 15 09:55:54 rivo qobuz-connect[5493]: 20261015 09:55:54.045 [5493.5493] INFO AudioStreamManager: [0xac90e800]: Setting new audio download buffer size: 1048576 Oct 15 09:55:54 rivo qobuz-connect[5493]: 20261015 09:55:54.045 [5493.5493] INFO QobuzConnect: [0xac90f370]: Client initialized! Oct 15 09:55:54 rivo qobuz-connect[5493]: 20261015 09:55:54.046 [5493.5493] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 15 09:55:54 rivo qobuz-connect[5493]: 20261015 09:55:54.060 [5493.5493] INFO LocalConfigManager: [0xac90e528]: Starting Local Configuration server Oct 15 09:55:54 rivo qobuz-connect[5493]: 20261015 09:55:54.060 [5493.5493] INFO SampleApp: Starting Local configuration server Oct 15 09:55:54 rivo qobuz-connect[5493]: 20261015 09:55:54.061 [5493.5493] INFO SampleApp: Connected to UNIX socket client 0xac8f9908 Oct 15 09:55:54 rivo qobuz-connect[5493]: 20261015 09:55:54.188 [5493.5493] INFO SampleApp: Playback volume changed: 75 Oct 15 09:55:54 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:55:54 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:54 rivo volumio[3210]: info: Executing endpoint metavolumio Oct 15 09:55:54 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 15 09:55:54 rivo volumio[3210]: info: Executing endpoint metavolumio Oct 15 09:55:54 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 15 09:55:54 rivo volumio[3210]: info: Executing endpoint metavolumio Oct 15 09:55:54 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 15 09:55:55 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:55:55 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:55:55 rivo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:55:55 rivo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:55:55 rivo sudo[5513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 15 09:55:55 rivo sudo[5513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:55 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 15 09:55:55 rivo sudo[5513]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:55 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:55:56 rivo volumio[3210]: info: Executing endpoint tc_getconfig Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 15 09:55:56 rivo vtcs[5518]: STARTING TidalConnect services, version: 1.6.1 Oct 15 09:55:56 rivo vtcs[5518]: STARTED TidalConnect services. Oct 15 09:55:56 rivo volumio[3210]: info: Executing endpoint tc_connect Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 15 09:55:56 rivo volumio[3210]: info: Connecting to TidalConnect Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::servicePushState Oct 15 09:55:56 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:56 rivo volumio[3210]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_fip Received tidalconnect Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::servicePushState Oct 15 09:55:56 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:56 rivo volumio[3210]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_fip Received tidalconnect Oct 15 09:55:56 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:55:56 rivo volumio[3210]: info: RAAT: Requesting Headphone Status Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 15 09:55:56 rivo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:55:56 rivo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:55:56 rivo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 15 09:55:56 rivo volumio[3210]: info: Reporting Headphone State: false Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:56 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:55:56 rivo volumio[3210]: info: RAAT: Requesting Headphone Status Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 15 09:55:56 rivo sudo[5542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 15 09:55:56 rivo sudo[5542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 15 09:55:56 rivo volumio[3210]: info: CoreStateMachine::getcurrentVolume Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 09:55:56 rivo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 15 09:55:56 rivo volumio[3210]: info: Reporting Headphone State: false Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:56 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:55:56 rivo sudo[5542]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:56 rivo volumio[3210]: info: VolumeController:: Volume=46 Mute =false Oct 15 09:55:56 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:55:56 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:55:56 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:56 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:55:56 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:55:56 rivo volumio[3210]: info: CoreStateMachine::updateTrackBlock Oct 15 09:55:56 rivo volumio[3210]: info: CorePlayQueue::getTrackBlock Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 09:55:56 rivo volumio5-onboarding[3790]: time=2026-10-15T09:55:56.274Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_PAUSED positionMs=322 volume=46 Oct 15 09:55:56 rivo volumio5-onboarding[3790]: time=2026-10-15T09:55:56.275Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:55:56 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:55:56 rivo volumio[3210]: info: VolumeController:: Volume=46 Mute =false Oct 15 09:55:56 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:55:56 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:55:56 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:56 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:55:56 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:55:56 rivo volumio5-onboarding[3790]: time=2026-10-15T09:55:56.342Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_PAUSED positionMs=322 volume=46 Oct 15 09:55:56 rivo volumio5-onboarding[3790]: time=2026-10-15T09:55:56.343Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:55:56 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:55:56 rivo volumio[3210]: info: RAAT: Requesting Headphone Status Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 15 09:55:56 rivo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 15 09:55:56 rivo volumio[3210]: info: Reporting Headphone State: false Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:56 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:56 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:55:57 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:55:57 rivo mpd[5486]: 2026-10-15T09:55:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 15 09:55:57 rivo systemd[1]: Started mpd.service - Music Player Daemon. Oct 15 09:55:57 rivo sudo[5379]: pam_unix(sudo:session): session closed for user root Oct 15 09:55:57 rivo volumio[3210]: error: updateQueue error: null Oct 15 09:55:58 rivo volumio[3210]: info: TidalConnect service started! Oct 15 09:55:59 rivo volumio[3210]: info: TidalConnect service started! Oct 15 09:55:59 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:55:59 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:59 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 15 09:55:59 rivo volumio[3210]: info: CoreCommandRouter::volumioPlay Oct 15 09:55:59 rivo volumio[3210]: info: CoreStateMachine::play index undefined Oct 15 09:55:59 rivo volumio[3210]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 09:55:59 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:55:59 rivo volumio[3210]: info: CoreStateMachine::startPlaybackTimer Oct 15 09:55:59 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:00 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:00 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:00 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 15 09:56:00 rivo volumio[3210]: info: CoreCommandRouter::volumioPlay Oct 15 09:56:00 rivo volumio[3210]: info: CoreStateMachine::play index undefined Oct 15 09:56:00 rivo volumio[3210]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 09:56:00 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:00 rivo volumio[3210]: info: CoreStateMachine::startPlaybackTimer Oct 15 09:56:00 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:08 rivo volumio[3210]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:08 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:08 rivo volumio[3210]: info: Restoring Previous Volume level: 100 false false Oct 15 09:56:08 rivo volumio[3210]: info: Output device has changed, restarting MPD Oct 15 09:56:08 rivo volumio[3210]: info: Output device has changed, restarting Shairport Sync Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:08 rivo sudo[5588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 09:56:08 rivo sudo[5588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:08 rivo sudo[5589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 09:56:08 rivo sudo[5589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:08 rivo volumio[3210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 09:56:08 rivo sudo[5588]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:08 rivo volumio[3210]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:08 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:08 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:08 rivo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:08 rivo volumio[3210]: info: Relinquishing Volumio State Oct 15 09:56:08 rivo vtcs[5518]: [2026-10-15 09:56:08.816] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 15 09:56:08 rivo vtcs[5518]: [2026-10-15 09:56:08.816] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 15 09:56:08 rivo volumio[3210]: info: Volume configurations have been set Oct 15 09:56:08 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:08 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:08 rivo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 15 09:56:08 rivo volumio[3210]: info: Relinquishing Volumio State Oct 15 09:56:08 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 15 09:56:08 rivo sudo[5598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:08 rivo sudo[5598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:08 rivo sudo[5600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:08 rivo sudo[5600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 15 09:56:08 rivo volumio[3210]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 15 09:56:08 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 15 09:56:08 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 09:56:08 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 15 09:56:08 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 09:56:08 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 15 09:56:08 rivo volumio[3210]: info: Disabling external Volume Control Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 09:56:08 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:56:08 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:56:08 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 09:56:08 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:08 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:08 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:56:08 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:56:08 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:08.955Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_PAUSED positionMs=9396 volume=100 Oct 15 09:56:08 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:08.956Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:56:08 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 09:56:09 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 15 09:56:09 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 15 09:56:09 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 15 09:56:09 rivo sudo[5615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:09 rivo sudo[5615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:09 rivo sudo[5598]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:09 rivo sudo[5618]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:09 rivo sudo[5618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:09 rivo sudo[5600]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:09 rivo sudo[5626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 09:56:09 rivo sudo[5626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:09 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:09 rivo volumio[3210]: info: MPD Permissions set Oct 15 09:56:09 rivo volumio[3210]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 15 09:56:09 rivo sudo[5633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 09:56:09 rivo sudo[5633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo sudo[5618]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:09 rivo sudo[5615]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:09 rivo sudo[5626]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:09 rivo sudo[5633]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 09:56:09 rivo sudo[5645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 09:56:09 rivo sudo[5646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 09:56:09 rivo sudo[5645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:09 rivo sudo[5646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:56:09 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 15 09:56:09 rivo qobuz-connect[5493]: 20261015 09:56:09.376 [5493.5493] INFO SampleApp: Stopping Local configuration server Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:56:09 rivo volumio[3210]: info: Starting Shairport Sync Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 15 09:56:09 rivo sudo[5660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 09:56:09 rivo sudo[5660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:09 rivo sudo[5612]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 09:56:09 rivo sudo[5612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 09:56:09 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 15 09:56:09 rivo sudo[5612]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:09 rivo sudo[5666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 15 09:56:09 rivo sudo[5666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:09 rivo sudo[5666]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:09 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 09:56:09 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 09:56:09 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 09:56:09 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 09:56:09 rivo sudo[5660]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:09 rivo sudo[5672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 15 09:56:09 rivo sudo[5672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:09 rivo sudo[5673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 15 09:56:09 rivo sudo[5673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:09 rivo volumio[3210]: info: Shairport-Sync Started Oct 15 09:56:09 rivo sudo[5672]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:09 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 15 09:56:09 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 15 09:56:09 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 15 09:56:09 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 15 09:56:09 rivo sudo[5677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 15 09:56:09 rivo sudo[5677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:09 rivo sudo[5673]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:09 rivo volumio[3210]: info: Raat Daemon started successfully Oct 15 09:56:09 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 15 09:56:09 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 15 09:56:09 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 15 09:56:09 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 15 09:56:10 rivo sudo[5677]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:10 rivo volumio[3210]: info: Raat Daemon started successfully Oct 15 09:56:10 rivo qobuz-connect[5493]: 20261015 09:56:10.090 [5493.5493] INFO SampleApp: shat down connection on UNIX socket Oct 15 09:56:10 rivo volumio[3210]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 15 09:56:10 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:56:10 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:10 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:10 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 15 09:56:10 rivo volumio[3210]: info: Executing endpoint restartRAATSocket Oct 15 09:56:10 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:56:10 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 15 09:56:10 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:56:10 rivo sudo[5645]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:10 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:10 rivo sudo[5646]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:10 rivo volumio[3210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 15 09:56:10 rivo volumio[3210]: info: Executing endpoint qc_getconfig Oct 15 09:56:10 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 15 09:56:10 rivo qobuz-connect[5712]: 20261015 09:56:10.278 [5712.5712] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 15 09:56:10 rivo qobuz-connect[5712]: 20261015 09:56:10.285 [5712.5712] INFO VolumeManager: [0xac186aa8]: Setting new playback volume: 75 Oct 15 09:56:10 rivo qobuz-connect[5712]: 20261015 09:56:10.286 [5712.5712] INFO VolumeManager: [0xac186aa8]: Setting new mute state: 0 Oct 15 09:56:10 rivo qobuz-connect[5712]: 20261015 09:56:10.286 [5712.5712] INFO AudioStreamManager: [0xac186800]: Setting new audio download buffer size: 1048576 Oct 15 09:56:10 rivo qobuz-connect[5712]: 20261015 09:56:10.286 [5712.5712] INFO QobuzConnect: [0xac187370]: Client initialized! Oct 15 09:56:10 rivo qobuz-connect[5712]: 20261015 09:56:10.286 [5712.5712] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 15 09:56:10 rivo volumio[3210]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 15 09:56:10 rivo volumio[3210]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 15 09:56:10 rivo qobuz-connect[5712]: 20261015 09:56:10.303 [5712.5712] INFO LocalConfigManager: [0xac186528]: Starting Local Configuration server Oct 15 09:56:10 rivo qobuz-connect[5712]: 20261015 09:56:10.303 [5712.5712] INFO SampleApp: Starting Local configuration server Oct 15 09:56:10 rivo qobuz-connect[5712]: 20261015 09:56:10.305 [5712.5712] INFO SampleApp: Connected to UNIX socket client 0xac171908 Oct 15 09:56:10 rivo qobuz-connect[5712]: 20261015 09:56:10.428 [5712.5712] INFO SampleApp: Playback volume changed: 75 Oct 15 09:56:10 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:10 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:12 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:56:12 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:56:12 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:56:12 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:56:12 rivo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:56:12 rivo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:56:12 rivo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:56:12 rivo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:56:12 rivo sudo[5732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 15 09:56:12 rivo sudo[5732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:12 rivo sudo[5739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 15 09:56:12 rivo sudo[5739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:12 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 15 09:56:12 rivo sudo[5732]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:12 rivo sudo[5739]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:12 rivo volumio[3210]: info: RAAT: Requesting Headphone Status Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 15 09:56:12 rivo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 15 09:56:12 rivo volumio[3210]: info: Reporting Headphone State: false Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:12 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:12 rivo volumio[3210]: info: RAAT: Requesting Headphone Status Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 15 09:56:12 rivo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 15 09:56:12 rivo volumio[3210]: info: Reporting Headphone State: false Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:12 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:12 rivo volumio[3210]: info: Executing endpoint tc_getconfig Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 15 09:56:12 rivo vtcs[5743]: STARTING TidalConnect services, version: 1.6.1 Oct 15 09:56:12 rivo vtcs[5743]: STARTED TidalConnect services. Oct 15 09:56:12 rivo volumio[3210]: info: Executing endpoint tc_connect Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 15 09:56:12 rivo volumio[3210]: info: Connecting to TidalConnect Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::servicePushState Oct 15 09:56:12 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:12 rivo volumio[3210]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_fip Received tidalconnect Oct 15 09:56:12 rivo volumio[3210]: info: CoreCommandRouter::servicePushState Oct 15 09:56:12 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:12 rivo volumio[3210]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_fip Received tidalconnect Oct 15 09:56:12 rivo mpd[5670]: 2026-10-15T09:56:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 15 09:56:12 rivo systemd[1]: Started mpd.service - Music Player Daemon. Oct 15 09:56:12 rivo sudo[5589]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:13 rivo volumio[3210]: error: updateQueue error: null Oct 15 09:56:13 rivo volumio[3210]: info: VolumeController::SetAlsaVolume100 Oct 15 09:56:13 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:56:13 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:13 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 09:56:13 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:56:13 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:13 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:13 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:56:13 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:56:13 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:13.261Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_PAUSED positionMs=13929 volume=100 Oct 15 09:56:13 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:13.263Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:56:13 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:15 rivo volumio[3210]: info: TidalConnect service started! Oct 15 09:56:15 rivo volumio[3210]: info: TidalConnect service started! Oct 15 09:56:16 rivo volumio[3210]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: Restoring Previous Volume level: 100 false true Oct 15 09:56:16 rivo volumio[3210]: info: VolumeController::SetAlsaVolume100 Oct 15 09:56:16 rivo volumio[3210]: info: Enable softmixer device for audio device number 5 Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioStop Oct 15 09:56:16 rivo volumio[3210]: info: CoreStateMachine::stop Oct 15 09:56:16 rivo volumio[3210]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 09:56:16 rivo volumio[3210]: info: CoreStateMachine::updateTrackBlock Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrackBlock Oct 15 09:56:16 rivo volumio[3210]: info: CoreStateMachine::stPlaybackTimer Oct 15 09:56:16 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:56:16 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:56:16 rivo volumio[3210]: info: CoreStateMachine::serviceStop Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::serviceStop Oct 15 09:56:16 rivo volumio[3210]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 09:56:16 rivo volumio[3210]: info: Enable softmixer device for audio device undefined Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 15 09:56:16 rivo volumio[3210]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 15 09:56:16 rivo volumio[3210]: info: Disabling external Volume Control Oct 15 09:56:16 rivo volumio[3210]: info: Output device has changed, restarting MPD Oct 15 09:56:16 rivo volumio[3210]: info: Output device has changed, restarting Shairport Sync Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:16 rivo sudo[5764]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 09:56:16 rivo sudo[5764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:16 rivo sudo[5766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 09:56:16 rivo sudo[5764]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:16 rivo sudo[5766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:16 rivo volumio[3210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 09:56:16 rivo volumio[3210]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:16 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:16 rivo volumio[3210]: info: Relinquishing Volumio State Oct 15 09:56:16 rivo vtcs[5743]: [2026-10-15 09:56:16.464] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 15 09:56:16 rivo vtcs[5743]: [2026-10-15 09:56:16.465] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=4 errorno=104 error=Connection reset by peer Oct 15 09:56:16 rivo vtcs[5743]: [2026-10-15 09:56:16.465] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 15 09:56:16 rivo volumio[3210]: info: Volume configurations have been set Oct 15 09:56:16 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 15 09:56:16 rivo volumio[3210]: info: Relinquishing Volumio State Oct 15 09:56:16 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 15 09:56:16 rivo sudo[5776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:16 rivo sudo[5776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:16 rivo sudo[5777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:16 rivo sudo[5777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 15 09:56:16 rivo volumio[3210]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 15 09:56:16 rivo volumio[3210]: info: Disabling external Volume Control Oct 15 09:56:16 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 15 09:56:16 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 09:56:16 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 09:56:16 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 09:56:16 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 09:56:16 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:56:16 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:56:16 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:16.556Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_STOPPED positionMs=0 volume=100 Oct 15 09:56:16 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:16.557Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:56:16 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:16.559Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_STOPPED positionMs=0 volume=100 Oct 15 09:56:16 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:16.560Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:56:16 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:16.586Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_STOPPED positionMs=0 volume=100 Oct 15 09:56:16 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:16.587Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:56:16 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:56:16 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:56:16 rivo volumio[3210]: error: MPD error: Error: write EPIPE Oct 15 09:56:16 rivo volumio[3210]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Oct 15 09:56:16 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 09:56:16 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 09:56:16 rivo sudo[5790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:16 rivo sudo[5790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:16 rivo sudo[5798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:16 rivo sudo[5798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:16 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 15 09:56:16 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 15 09:56:16 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 15 09:56:16 rivo sudo[5805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 09:56:16 rivo sudo[5805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:16 rivo sudo[5811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 09:56:16 rivo sudo[5811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:16 rivo sudo[5776]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:16 rivo sudo[5777]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:16 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:16 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:16 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:16 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:56:16 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:16 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:16 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:56:16 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:56:16 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:16.865Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_STOPPED positionMs=1116 volume= Oct 15 09:56:16 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:16.866Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:56:16 rivo volumio[3210]: info: MPD Permissions set Oct 15 09:56:16 rivo sudo[5805]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:16 rivo volumio[3210]: info: Software Volume ALSA configuration written Oct 15 09:56:16 rivo volumio[3210]: info: Preparing to generate the ALSA configuration file Oct 15 09:56:16 rivo sudo[5811]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:16 rivo sudo[5816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 09:56:16 rivo sudo[5816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:16 rivo sudo[5790]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:16 rivo sudo[5817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 09:56:16 rivo sudo[5817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:17 rivo sudo[5798]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo qobuz-connect[5712]: 20261015 09:56:17.072 [5712.5712] INFO SampleApp: Stopping Local configuration server Oct 15 09:56:17 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 15 09:56:17 rivo volumio[3210]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 15 09:56:17 rivo volumio[3210]: info: Reading ALSA contributions from plugins. Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 15 09:56:17 rivo volumio[3210]: info: VolumeController::SetAlsaVolume0 Oct 15 09:56:17 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:56:17 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:17 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:17 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:56:17 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:56:17 rivo sudo[5799]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 09:56:17 rivo sudo[5799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 09:56:17 rivo volumio[3210]: info: VolumeController::SetAlsaVolume0 Oct 15 09:56:17 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:17.242Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_STOPPED positionMs=1116 volume=0 Oct 15 09:56:17 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:17.242Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:56:17 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:56:17 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:56:17 rivo sudo[5799]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:17 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:17 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:56:17 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:56:17 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:17.280Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_STOPPED positionMs=1116 volume=0 Oct 15 09:56:17 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:17.281Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:56:17 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:17 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:17 rivo volumio[3210]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Oct 15 09:56:17 rivo volumio[3210]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Oct 15 09:56:17 rivo volumio[3210]: info: Starting Shairport Sync Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 15 09:56:17 rivo sudo[5862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 09:56:17 rivo sudo[5862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 15 09:56:17 rivo sudo[5867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 15 09:56:17 rivo sudo[5867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:17 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 09:56:17 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 09:56:17 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 09:56:17 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 09:56:17 rivo sudo[5862]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:17 rivo sudo[5867]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:17 rivo sudo[5873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 15 09:56:17 rivo sudo[5873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:17 rivo volumio[3210]: info: Shairport-Sync Started Oct 15 09:56:17 rivo sudo[5874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 15 09:56:17 rivo sudo[5874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:17 rivo volumio[3210]: info: Asound.conf file written Oct 15 09:56:17 rivo sudo[5873]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:17 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 15 09:56:17 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 15 09:56:17 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 15 09:56:17 rivo sudo[5878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 15 09:56:17 rivo sudo[5878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:17 rivo sudo[5879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 15 09:56:17 rivo sudo[5879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:17 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 15 09:56:17 rivo sudo[5874]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:17 rivo sudo[5879]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:17 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 15 09:56:17 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 15 09:56:17 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 15 09:56:17 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 15 09:56:17 rivo sudo[5878]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:17 rivo volumio[3210]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 15 09:56:17 rivo volumio[3210]: No state is present for card AMLAUGESOUNDMP1 Oct 15 09:56:17 rivo volumio[3210]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 15 09:56:17 rivo volumio[3210]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 15 09:56:17 rivo volumio[3210]: Hardware is initialized using a generic method Oct 15 09:56:17 rivo volumio[3210]: No state is present for card AMLAUGESOUNDMP1 Oct 15 09:56:17 rivo volumio[3210]: No state is present for card Amanero Oct 15 09:56:17 rivo volumio[3210]: Found hardware: "USB-Audio" "USB Mixer" "USB16d0:071a" "" "" Oct 15 09:56:17 rivo volumio[3210]: Hardware is initialized using a generic method Oct 15 09:56:17 rivo volumio[3210]: No state is present for card Amanero Oct 15 09:56:17 rivo volumio[3210]: info: Output device has changed, restarting MPD Oct 15 09:56:17 rivo volumio[3210]: info: Output device has changed, restarting Shairport Sync Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:17 rivo sudo[5910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 09:56:17 rivo sudo[5910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:17 rivo sudo[5910]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:17 rivo sudo[5911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 09:56:17 rivo sudo[5911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:17 rivo volumio[3210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 09:56:17 rivo volumio[3210]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:17 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:17 rivo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 15 09:56:17 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:17 rivo volumio[3210]: info: Relinquishing Volumio State Oct 15 09:56:17 rivo sudo[5920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:17 rivo sudo[5920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:17 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 15 09:56:17 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 09:56:17 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 15 09:56:17 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 09:56:17 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 09:56:17 rivo volumio[3210]: ALSA lib ./src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 15 09:56:17 rivo volumio[3210]: aplay: main:831: audio open error: Device or resource busy Oct 15 09:56:17 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 09:56:18 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 09:56:18 rivo volumio[3210]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 15 09:56:18 rivo volumio[3210]: No state is present for card AMLAUGESOUNDMP1 Oct 15 09:56:18 rivo volumio[3210]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 15 09:56:18 rivo volumio[3210]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 15 09:56:18 rivo volumio[3210]: Hardware is initialized using a generic method Oct 15 09:56:18 rivo volumio[3210]: No state is present for card AMLAUGESOUNDMP1 Oct 15 09:56:18 rivo volumio[3210]: No state is present for card Amanero Oct 15 09:56:18 rivo volumio[3210]: Found hardware: "USB-Audio" "USB Mixer" "USB16d0:071a" "" "" Oct 15 09:56:18 rivo volumio[3210]: Hardware is initialized using a generic method Oct 15 09:56:18 rivo volumio[3210]: No state is present for card Amanero Oct 15 09:56:18 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:18 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:18 rivo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 15 09:56:18 rivo volumio[3210]: info: Relinquishing Volumio State Oct 15 09:56:18 rivo volumio[3210]: info: Output device has changed, restarting MPD Oct 15 09:56:18 rivo volumio[3210]: info: Output device has changed, restarting Shairport Sync Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:18 rivo sudo[5932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:18 rivo sudo[5932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:18 rivo sudo[5935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 09:56:18 rivo sudo[5935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:18 rivo sudo[5936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 09:56:18 rivo sudo[5936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:18 rivo sudo[5935]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:18 rivo sudo[5920]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:18 rivo volumio[3210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 09:56:18 rivo volumio[3210]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:18 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:18 rivo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: Relinquishing Volumio State Oct 15 09:56:18 rivo qobuz-connect[5712]: 20261015 09:56:18.322 [5712.5712] INFO SampleApp: shat down connection on UNIX socket Oct 15 09:56:18 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 15 09:56:18 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:56:18 rivo sudo[5947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:18 rivo sudo[5947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 15 09:56:18 rivo volumio[3210]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 15 09:56:18 rivo volumio[3210]: info: Disabling external Volume Control Oct 15 09:56:18 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 15 09:56:18 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 09:56:18 rivo sudo[5932]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:18 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 15 09:56:18 rivo sudo[5962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:18 rivo sudo[5962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:18 rivo sudo[5961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:18 rivo sudo[5961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:18 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 09:56:18 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 09:56:18 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 09:56:18 rivo sudo[5964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:18 rivo sudo[5964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:18 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 09:56:18 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:56:18 rivo sudo[5817]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:18 rivo sudo[5816]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:18 rivo sudo[5975]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 09:56:18 rivo sudo[5975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:18 rivo sudo[5975]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:18 rivo sudo[5981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 09:56:18 rivo sudo[5981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:18 rivo sudo[5985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 09:56:18 rivo sudo[5985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:18 rivo sudo[5988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:56:18 rivo sudo[5988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:18 rivo sudo[5964]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:56:18 rivo sudo[5962]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:18 rivo sudo[5947]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:18 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:56:19 rivo sudo[5961]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:56:19 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:19 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:19 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:56:19 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:56:19 rivo sudo[5981]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:19.064Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_STOPPED positionMs=1116 volume=100 Oct 15 09:56:19 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:19.064Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:56:19 rivo volumio[3210]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Oct 15 09:56:19 rivo volumio[3210]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 15 09:56:19 rivo volumio[3210]: info: MPD Permissions set Oct 15 09:56:19 rivo sudo[5988]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo volumio[3210]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 15 09:56:19 rivo sudo[6011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 09:56:19 rivo volumio[3210]: info: MPD Permissions set Oct 15 09:56:19 rivo volumio[3210]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 15 09:56:19 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:19 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:19 rivo sudo[6011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo volumio[3210]: info: Raat Daemon started successfully Oct 15 09:56:19 rivo volumio[3210]: info: Raat Daemon started successfully Oct 15 09:56:19 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 15 09:56:19 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 15 09:56:19 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:56:19 rivo sudo[6012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 09:56:19 rivo sudo[6012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo volumio[3210]: info: Executing endpoint qc_getconfig Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:56:19 rivo sudo[5985]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 15 09:56:19 rivo sudo[5973]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 09:56:19 rivo sudo[5973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 09:56:19 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 15 09:56:19 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 15 09:56:19 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:56:19 rivo sudo[5973]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo sudo[6011]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo sudo[6012]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 15 09:56:19 rivo sudo[6038]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 15 09:56:19 rivo sudo[6038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 15 09:56:19 rivo sudo[6038]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo sudo[6043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 15 09:56:19 rivo sudo[6043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo volumio[3210]: info: Executing endpoint restartRAATSocket Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 15 09:56:19 rivo sudo[6046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 15 09:56:19 rivo sudo[6043]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo sudo[6046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo sudo[6048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 15 09:56:19 rivo sudo[6048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:19 rivo volumio[3210]: info: Executing endpoint qc_getconfig Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 15 09:56:19 rivo sudo[6051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 15 09:56:19 rivo sudo[6051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo qobuz-connect[6026]: 20261015 09:56:19.595 [6026.6026] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 15 09:56:19 rivo volumio[3210]: info: Starting Shairport Sync Oct 15 09:56:19 rivo qobuz-connect[6026]: 20261015 09:56:19.601 [6026.6026] INFO VolumeManager: [0xabc76aa8]: Setting new playback volume: 75 Oct 15 09:56:19 rivo qobuz-connect[6026]: 20261015 09:56:19.601 [6026.6026] INFO VolumeManager: [0xabc76aa8]: Setting new mute state: 0 Oct 15 09:56:19 rivo qobuz-connect[6026]: 20261015 09:56:19.601 [6026.6026] INFO AudioStreamManager: [0xabc76800]: Setting new audio download buffer size: 1048576 Oct 15 09:56:19 rivo qobuz-connect[6026]: 20261015 09:56:19.602 [6026.6026] INFO QobuzConnect: [0xabc77370]: Client initialized! Oct 15 09:56:19 rivo qobuz-connect[6026]: 20261015 09:56:19.602 [6026.6026] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 15 09:56:19 rivo volumio[3210]: info: Starting Shairport Sync Oct 15 09:56:19 rivo sudo[6048]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo qobuz-connect[6026]: 20261015 09:56:19.640 [6026.6026] INFO LocalConfigManager: [0xabc76528]: Starting Local Configuration server Oct 15 09:56:19 rivo qobuz-connect[6026]: 20261015 09:56:19.640 [6026.6026] INFO SampleApp: Starting Local configuration server Oct 15 09:56:19 rivo qobuz-connect[6026]: 20261015 09:56:19.641 [6026.6026] INFO SampleApp: Connected to UNIX socket client 0xabc61908 Oct 15 09:56:19 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 15 09:56:19 rivo volumio[3210]: info: Preparing to generate the ALSA configuration file Oct 15 09:56:19 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 15 09:56:19 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 15 09:56:19 rivo sudo[6058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 09:56:19 rivo sudo[6058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo sudo[6060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 15 09:56:19 rivo sudo[6060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo sudo[6063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 09:56:19 rivo volumio[3210]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 15 09:56:19 rivo volumio[3210]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 15 09:56:19 rivo volumio[3210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 15 09:56:19 rivo volumio[3210]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 15 09:56:19 rivo volumio[3210]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 15 09:56:19 rivo volumio[3210]: info: Reading ALSA contributions from plugins. Oct 15 09:56:19 rivo sudo[6063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 15 09:56:19 rivo sudo[6051]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo sudo[6046]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo qobuz-connect[6026]: 20261015 09:56:19.746 [6026.6026] INFO SampleApp: Playback volume changed: 75 Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:19 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:19 rivo volumio[3210]: info: Raat Daemon started successfully Oct 15 09:56:19 rivo volumio[3210]: info: Raat Daemon started successfully Oct 15 09:56:19 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 15 09:56:19 rivo volumio[3210]: info: Asound.conf file unchanged, so no further update is needed Oct 15 09:56:19 rivo volumio[3210]: info: Output device has changed, restarting MPD Oct 15 09:56:19 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 09:56:19 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 09:56:19 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 09:56:19 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 15 09:56:19 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 15 09:56:19 rivo volumio[3210]: info: Output device has changed, restarting Shairport Sync Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:19 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 15 09:56:19 rivo sudo[6060]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo sudo[6079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 09:56:19 rivo sudo[6079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo sudo[6075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 09:56:19 rivo sudo[6075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:19 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 09:56:19 rivo sudo[6058]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:19 rivo volumio[3210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 09:56:19 rivo volumio[3210]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 09:56:19 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo sudo[6063]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:20 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:20 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:20 rivo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: Relinquishing Volumio State Oct 15 09:56:20 rivo volumio[3210]: info: Preparing to generate the ALSA configuration file Oct 15 09:56:20 rivo sudo[6075]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:20 rivo sudo[6092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:20 rivo sudo[6092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo sudo[6108]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:20 rivo sudo[6108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 15 09:56:20 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 15 09:56:20 rivo volumio[3210]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 15 09:56:20 rivo volumio[3210]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 15 09:56:20 rivo volumio[3210]: info: Reading ALSA contributions from plugins. Oct 15 09:56:20 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 09:56:20 rivo volumio[3210]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 15 09:56:20 rivo volumio[3210]: info: MPD Permissions set Oct 15 09:56:20 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:56:20 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 15 09:56:20 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 09:56:20 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 09:56:20 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:56:20 rivo sudo[6116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 09:56:20 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 09:56:20 rivo sudo[6116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo sudo[6117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 09:56:20 rivo sudo[6117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 09:56:20 rivo volumio[3210]: info: Shairport-Sync Started Oct 15 09:56:20 rivo volumio[3210]: info: Shairport-Sync Started Oct 15 09:56:20 rivo volumio[3210]: info: Raat Daemon started successfully Oct 15 09:56:20 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:56:20 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: RAAT: Requesting Headphone Status Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 15 09:56:20 rivo volumio[3210]: info: Starting Shairport Sync Oct 15 09:56:20 rivo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:56:20 rivo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:56:20 rivo volumio[3210]: info: RAAT: Requesting Headphone Status Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 15 09:56:20 rivo volumio[3210]: info: Asound.conf file unchanged, so no further update is needed Oct 15 09:56:20 rivo volumio[3210]: info: Output device has changed, restarting MPD Oct 15 09:56:20 rivo sudo[6145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 09:56:20 rivo sudo[6145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo volumio[3210]: info: Output device has changed, restarting Shairport Sync Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:20 rivo sudo[6148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 15 09:56:20 rivo sudo[6148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo sudo[6150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 09:56:20 rivo sudo[6150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo sudo[6150]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:20 rivo sudo[6154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 09:56:20 rivo sudo[6154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo volumio[3210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 09:56:20 rivo volumio[3210]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:20 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:20 rivo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: Relinquishing Volumio State Oct 15 09:56:20 rivo sudo[6092]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:20 rivo sudo[6116]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:20 rivo sudo[6163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:20 rivo sudo[6163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo sudo[6165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 09:56:20 rivo sudo[6165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo sudo[6108]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:20 rivo sudo[6166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 09:56:20 rivo sudo[6166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 15 09:56:20 rivo volumio[3210]: info: Reporting Headphone State: false Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:20 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 09:56:20 rivo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:56:20 rivo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 15 09:56:20 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 15 09:56:20 rivo sudo[6148]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:20 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 09:56:20 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 09:56:20 rivo sudo[6174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 09:56:20 rivo sudo[6174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:56:20 rivo sudo[6179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 15 09:56:20 rivo sudo[6179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:20 rivo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 15 09:56:20 rivo volumio[3210]: info: Reporting Headphone State: false Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:20 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:20 rivo volumio[3210]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 15 09:56:20 rivo volumio[3210]: info: MPD Permissions set Oct 15 09:56:20 rivo volumio[3210]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 15 09:56:20 rivo volumio[3210]: Job for vtcs.service canceled. Oct 15 09:56:20 rivo volumio[3210]: {"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":""} Oct 15 09:56:20 rivo volumio[3210]: info: VolumeController::SetAlsaVolume100 Oct 15 09:56:20 rivo volumio[3210]: info: CoreStateMachine::pushState Oct 15 09:56:20 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::volumioPushState Oct 15 09:56:20 rivo volumio[3210]: info: CoreCommandRouter::volumioGetState Oct 15 09:56:20 rivo volumio[3210]: info: CorePlayQueue::getTrack 0 Oct 15 09:56:20 rivo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Oct 15 09:56:20 rivo volumio[3210]: info: MRS: Pushing multiroomSync output Oct 15 09:56:20 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:20.970Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" state=STATUS_STOPPED positionMs=1116 volume=100 Oct 15 09:56:20 rivo volumio5-onboarding[3790]: time=2026-10-15T09:56:20.971Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.31:49420,00:00:00:00:00:00%02 @ 0x17b1050" id="https://icecast.radiofrance.fr/fipjazz-hifi.aac?id=radiofrance" title="FIP Jazz" Oct 15 09:56:21 rivo volumio[3210]: info: Updating RAAT Signal Path Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 15 09:56:21 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 15 09:56:21 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 09:56:21 rivo sudo[6174]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:21 rivo sudo[6163]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:21 rivo sudo[6166]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:21 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 15 09:56:21 rivo sudo[6198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 09:56:21 rivo sudo[6198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:21 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 09:56:21 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 09:56:21 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 09:56:21 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 09:56:21 rivo qobuz-connect[6026]: 20261015 09:56:21.178 [6026.6026] INFO SampleApp: Stopping Local configuration server Oct 15 09:56:21 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 15 09:56:21 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 09:56:21 rivo sudo[6205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 15 09:56:21 rivo sudo[6205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 09:56:21 rivo volumio[3210]: info: Not Reporting Auto name since its the default one Oct 15 09:56:21 rivo sudo[6145]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:21 rivo sudo[6179]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:21 rivo volumio[3210]: info: TidalConnect service stoped! Oct 15 09:56:21 rivo volumio[3210]: info: Shairport-Sync Started Oct 15 09:56:21 rivo volumio[3210]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 15 09:56:21 rivo volumio[3210]: Job for vtcs.service canceled. Oct 15 09:56:21 rivo volumio[3210]: {"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":""} Oct 15 09:56:21 rivo volumio[3210]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 09:56:21 rivo volumio[3210]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 15 09:56:21 rivo volumio[3210]: Job for vtcs.service canceled. Oct 15 09:56:21 rivo volumio[3210]: at ChildProcess.exithandler (node:child_process:421:12) Oct 15 09:56:21 rivo volumio[3210]: at ChildProcess.emit (node:events:514:28) Oct 15 09:56:21 rivo volumio[3210]: at maybeClose (node:internal/child_process:1105:16) Oct 15 09:56:21 rivo volumio[3210]: at Socket. (node:internal/child_process:457:11) Oct 15 09:56:21 rivo volumio[3210]: at Socket.emit (node:events:514:28) Oct 15 09:56:21 rivo volumio[3210]: at Pipe. (node:net:337:12) { Oct 15 09:56:21 rivo volumio[3210]: code: 1, Oct 15 09:56:21 rivo volumio[3210]: killed: false, Oct 15 09:56:21 rivo volumio[3210]: signal: null, Oct 15 09:56:21 rivo volumio[3210]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 15 09:56:21 rivo volumio[3210]: stdout: '', Oct 15 09:56:21 rivo volumio[3210]: stderr: 'Job for vtcs.service canceled.\n' Oct 15 09:56:21 rivo volumio[3210]: } Oct 15 09:56:21 rivo volumio[3210]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 09:56:21 rivo sudo[6205]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:21 rivo sudo[6227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 15 09:56:21 rivo sudo[6227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 09:56:21 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 15 09:56:21 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 15 09:56:21 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 15 09:56:21 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 15 09:56:21 rivo sudo[6227]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:21 rivo qobuz-connect[6026]: 20261015 09:56:21.672 [6026.6026] INFO SampleApp: shat down connection on UNIX socket Oct 15 09:56:21 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 15 09:56:21 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:56:21 rivo sudo[6203]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 09:56:21 rivo sudo[6203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 09:56:21 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 09:56:21 rivo sudo[6198]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:21 rivo sudo[6165]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:21 rivo sudo[6203]: pam_unix(sudo:session): session closed for user root Oct 15 09:56:22 rivo sudo[6247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 09:55' Oct 15 09:56:22 rivo sudo[6247]: 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="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:27:36 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="be0c94671215c2b1bf2440825a1e4211"