Oct 12 09:32:01 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:32:01 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:32:01 rivo volumio[3567]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:32:01 rivo volumio[3567]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:32:01 rivo sudo[14598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 09:32:01 rivo sudo[14598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:01 rivo sudo[14598]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:01 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:32:01 rivo volumio[3567]: info: RAAT: Requesting Headphone Status Oct 12 09:32:01 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 09:32:01 rivo volumio[3567]: info: MCU Signalled Headphone Mode Disabled Oct 12 09:32:01 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 12 09:32:01 rivo volumio[3567]: info: Reporting Headphone State: false Oct 12 09:32:01 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:01 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:01 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:01 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:32:01 rivo volumio[3567]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:32:01 rivo volumio[3567]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:32:02 rivo sudo[14610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 09:32:02 rivo sudo[14610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:02 rivo sudo[14610]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:02 rivo volumio[3567]: info: TidalConnect service started! Oct 12 09:32:02 rivo volumio[3567]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 12 09:32:02 rivo volumio[3567]: info: CoreStateMachine::getcurrentVolume Oct 12 09:32:02 rivo volumio[3567]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 09:32:02 rivo volumio[3567]: info: TidalConnect service started! Oct 12 09:32:02 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:32:02 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:02 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:02 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:02 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:02 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:02 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:02 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:02 rivo volumio[3567]: info: CoreStateMachine::updateTrackBlock Oct 12 09:32:02 rivo volumio[3567]: info: CorePlayQueue::getTrackBlock Oct 12 09:32:02 rivo volumio[3567]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 09:32:02 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:02.279+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:02 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:02.280+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:02 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:02 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:32:02 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:02 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:02 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:02 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:02 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:02 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:02 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:02 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:02.343+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:02 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:02.343+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:02 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:02 rivo volumio[3567]: info: RAAT: Requesting Headphone Status Oct 12 09:32:02 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 09:32:02 rivo volumio[3567]: info: MCU Signalled Headphone Mode Disabled Oct 12 09:32:02 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 12 09:32:02 rivo volumio[3567]: info: Reporting Headphone State: false Oct 12 09:32:02 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:02 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:02 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:02 rivo mpd[14530]: 2026-10-12T09:32:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 09:32:02 rivo systemd[1]: Started mpd.service - Music Player Daemon. Oct 12 09:32:02 rivo sudo[14432]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:02 rivo sudo[14290]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:02 rivo sudo[14107]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:03 rivo volumio[3567]: error: MPD error: The expression evaluated to a falsy value: Oct 12 09:32:03 rivo volumio[3567]: assert.ok(self.idling) Oct 12 09:32:03 rivo volumio[3567]: error: The expression evaluated to a falsy value: Oct 12 09:32:03 rivo volumio[3567]: assert.ok(self.idling) Oct 12 09:32:03 rivo volumio[3567]: error: MPD error: The expression evaluated to a falsy value: Oct 12 09:32:03 rivo volumio[3567]: assert.ok(self.idling) Oct 12 09:32:03 rivo volumio[3567]: error: The expression evaluated to a falsy value: Oct 12 09:32:03 rivo volumio[3567]: assert.ok(self.idling) Oct 12 09:32:03 rivo volumio[3567]: error: updateQueue error: null Oct 12 09:32:04 rivo volumio[3567]: info: TidalConnect service started! Oct 12 09:32:05 rivo volumio[3567]: info: TidalConnect service started! Oct 12 09:32:11 rivo volumio[3567]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:11 rivo sudo[14638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 09:32:11 rivo sudo[14638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:11 rivo sudo[14638]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 09:32:11 rivo sudo[14639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 09:32:11 rivo sudo[14639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:11 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:11 rivo volumio[3567]: info: MPD Permissions set Oct 12 09:32:11 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 12 09:32:11 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 09:32:11 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 09:32:11 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 09:32:11 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 09:32:11 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 09:32:11 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 09:32:11 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 09:32:11 rivo sudo[14653]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 09:32:11 rivo sudo[14653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:32:11 rivo sudo[14653]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:14 rivo mpd[14656]: 2026-10-12T09:32:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 09:32:14 rivo systemd[1]: Started mpd.service - Music Player Daemon. Oct 12 09:32:14 rivo sudo[14639]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:14 rivo volumio[3567]: error: updateQueue error: null Oct 12 09:32:20 rivo volumio[3567]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 12 09:32:20 rivo volumio[3567]: info: Preparing to save Alsa Options, stopping services first Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::volumioPause Oct 12 09:32:20 rivo volumio[3567]: info: CoreStateMachine::pause Oct 12 09:32:20 rivo volumio[3567]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Amanero Combo768"}} Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:32:20 rivo volumio[3567]: info: Setting mixer PCM for card Amanero Combo768 Oct 12 09:32:20 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:20 rivo volumio[3567]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 12 09:32:20 rivo vtcs[14498]: [2026-10-12 09:32:20.181] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 12 09:32:20 rivo vtcs[14498]: [2026-10-12 09:32:20.186] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 12 09:32:20 rivo sudo[14666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:32:20 rivo sudo[14666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 09:32:20 rivo volumio[3567]: info: Updating Volume Controller Parameters: Device: 5 Name: Amanero Combo768 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 12 09:32:20 rivo volumio[3567]: info: Disabling external Volume Control Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 09:32:20 rivo volumio[3567]: info: Preparing to generate the ALSA configuration file Oct 12 09:32:20 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 12 09:32:20 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 12 09:32:20 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 12 09:32:20 rivo sudo[14666]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:20 rivo sudo[14674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:32:20 rivo sudo[14674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo volumio[3567]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 09:32:20 rivo volumio[3567]: info: Reading ALSA contributions from plugins. Oct 12 09:32:20 rivo volumio[3567]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 12 09:32:20 rivo volumio[3567]: info: VolumeController:: Starting alsactl monitor Oct 12 09:32:20 rivo sudo[14679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:32:20 rivo sudo[14679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo sudo[14674]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:32:20 rivo sudo[14679]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 12 09:32:20 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:32:20 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:20 rivo sudo[14688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:32:20 rivo sudo[14688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:20 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:20 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:20 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:20.467+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:20 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:20.467+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:20 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: Asound.conf file unchanged, so no further update is needed Oct 12 09:32:20 rivo volumio[3567]: info: Output device has changed, restarting MPD Oct 12 09:32:20 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 09:32:20 rivo qobuz-connect[14493]: 20261012 09:32:20.512 [14493.14493] INFO SampleApp: Stopping Local configuration server Oct 12 09:32:20 rivo volumio[3567]: info: Output device has changed, restarting Shairport Sync Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:32:20 rivo sudo[14691]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 09:32:20 rivo sudo[14691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo sudo[14691]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:20 rivo sudo[14693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 09:32:20 rivo sudo[14693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo volumio[3567]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 09:32:20 rivo volumio[3567]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:20 rivo volumio[3567]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 12 09:32:20 rivo sudo[14702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:32:20 rivo sudo[14702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo sudo[14705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:32:20 rivo sudo[14705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo volumio[3567]: info: MPD Permissions set Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 09:32:20 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 09:32:20 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 09:32:20 rivo sudo[14710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:32:20 rivo sudo[14710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 09:32:20 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 09:32:20 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:20 rivo volumio[3567]: info: Not Reporting Auto name since its the default one Oct 12 09:32:20 rivo volumio[3567]: info: Starting Shairport Sync Oct 12 09:32:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:32:20 rivo sudo[14732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 09:32:20 rivo sudo[14732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo sudo[14702]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:20 rivo sudo[14710]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:20 rivo sudo[14741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:32:20 rivo sudo[14741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo sudo[14742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:32:20 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 09:32:20 rivo sudo[14742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:20 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 09:32:20 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 09:32:20 rivo sudo[14705]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:20 rivo sudo[14741]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:20 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 09:32:20 rivo sudo[14732]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:20 rivo volumio[3567]: info: Shairport-Sync Started Oct 12 09:32:20 rivo sudo[14746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:32:20 rivo sudo[14746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:21 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 09:32:21 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 09:32:21 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 09:32:21 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 09:32:21 rivo sudo[14746]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:21 rivo volumio[3567]: info: Not Reporting Auto name since its the default one Oct 12 09:32:21 rivo volumio[3567]: info: Raat Daemon started successfully Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:32:21 rivo volumio[3567]: info: Executing endpoint restartRAATSocket Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 09:32:21 rivo sudo[14725]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 09:32:21 rivo sudo[14725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:32:21 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:21 rivo sudo[14796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:32:21 rivo sudo[14796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:21 rivo sudo[14725]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:21 rivo volumio[3567]: 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 12 09:32:21 rivo sudo[14796]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:21 rivo qobuz-connect[14493]: 20261012 09:32:21.348 [14493.14493] INFO SampleApp: shat down connection on UNIX socket Oct 12 09:32:21 rivo volumio[3567]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 12 09:32:21 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:21 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:21 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 09:32:21 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:32:21 rivo sudo[14802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:32:21 rivo sudo[14802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:21 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:32:21 rivo sudo[14688]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:21 rivo sudo[14742]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:21 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 09:32:21 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 09:32:21 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 09:32:21 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 09:32:21 rivo sudo[14802]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:21 rivo volumio[3567]: info: Raat Daemon started successfully Oct 12 09:32:21 rivo volumio[3567]: info: Executing endpoint restartRAATSocket Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 09:32:21 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:21 rivo volumio[3567]: info: Executing endpoint qc_getconfig Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 12 09:32:21 rivo qobuz-connect[14804]: 20261012 09:32:21.629 [14804.14804] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 12 09:32:21 rivo volumio[3567]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 12 09:32:21 rivo volumio[3567]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 12 09:32:21 rivo volumio[3567]: 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 12 09:32:21 rivo qobuz-connect[14804]: 20261012 09:32:21.642 [14804.14804] INFO VolumeManager: [0xacd0c018]: Setting new playback volume: 75 Oct 12 09:32:21 rivo qobuz-connect[14804]: 20261012 09:32:21.642 [14804.14804] INFO VolumeManager: [0xacd0c018]: Setting new mute state: 0 Oct 12 09:32:21 rivo qobuz-connect[14804]: 20261012 09:32:21.642 [14804.14804] INFO AudioStreamManager: [0xacd0bd70]: Setting new audio download buffer size: 1048576 Oct 12 09:32:21 rivo qobuz-connect[14804]: 20261012 09:32:21.642 [14804.14804] INFO QobuzConnect: [0xacd0c8e0]: Client initialized! Oct 12 09:32:21 rivo qobuz-connect[14804]: 20261012 09:32:21.642 [14804.14804] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 12 09:32:21 rivo qobuz-connect[14804]: 20261012 09:32:21.663 [14804.14804] INFO LocalConfigManager: [0xacd0ba98]: Starting Local Configuration server Oct 12 09:32:21 rivo qobuz-connect[14804]: 20261012 09:32:21.664 [14804.14804] INFO SampleApp: Starting Local configuration server Oct 12 09:32:21 rivo qobuz-connect[14804]: 20261012 09:32:21.666 [14804.14804] INFO SampleApp: Connected to UNIX socket client 0xaccf6908 Oct 12 09:32:21 rivo qobuz-connect[14804]: 20261012 09:32:21.779 [14804.14804] INFO SampleApp: Playback volume changed: 75 Oct 12 09:32:21 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:21 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:23 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:32:23 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:32:23 rivo volumio[3567]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:32:23 rivo volumio[3567]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:32:23 rivo sudo[14829]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 09:32:23 rivo sudo[14829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:23 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 12 09:32:23 rivo sudo[14829]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:23 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:32:23 rivo volumio[3567]: info: RAAT: Requesting Headphone Status Oct 12 09:32:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 09:32:23 rivo volumio[3567]: info: MCU Signalled Headphone Mode Disabled Oct 12 09:32:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 12 09:32:23 rivo volumio[3567]: info: Reporting Headphone State: false Oct 12 09:32:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:23 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:23 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:32:23 rivo volumio[3567]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:32:23 rivo volumio[3567]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:32:23 rivo volumio[3567]: info: Executing endpoint tc_getconfig Oct 12 09:32:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 12 09:32:23 rivo vtcs[14833]: STARTING TidalConnect services, version: 1.6.1 Oct 12 09:32:23 rivo vtcs[14833]: STARTED TidalConnect services. Oct 12 09:32:23 rivo volumio[3567]: info: Executing endpoint tc_connect Oct 12 09:32:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 12 09:32:23 rivo volumio[3567]: info: Connecting to TidalConnect Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::servicePushState Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:23 rivo sudo[14843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 09:32:24 rivo sudo[14843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 12 09:32:24 rivo volumio[3567]: verbose: CURRENT POSITION 0 Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::syncState stateService stop Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::syncState currentStatus pause Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::servicePushState Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 12 09:32:24 rivo volumio[3567]: verbose: CURRENT POSITION 0 Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::syncState stateService stop Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::syncState currentStatus pause Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.022+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.023+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.024+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.024+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.025+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.026+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.027+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.027+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:24 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:24 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:24 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:24 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:24 rivo sudo[14843]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:24 rivo volumio[3567]: info: RAAT: Requesting Headphone Status Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::getcurrentVolume Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 09:32:24 rivo volumio[3567]: info: MCU Signalled Headphone Mode Disabled Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 12 09:32:24 rivo volumio[3567]: info: Reporting Headphone State: false Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:24 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:24 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::updateTrackBlock Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrackBlock Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.335+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.335+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:24 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:24 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:32:24 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:24 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:24 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:24 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.396+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:24 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:24.397+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:24 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:24 rivo mpd[14801]: 2026-10-12T09:32:24 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 09:32:24 rivo systemd[1]: Started mpd.service - Music Player Daemon. Oct 12 09:32:24 rivo sudo[14693]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:24 rivo volumio[3567]: error: updateQueue error: null Oct 12 09:32:26 rivo volumio[3567]: info: TidalConnect service started! Oct 12 09:32:27 rivo volumio[3567]: info: TidalConnect service started! Oct 12 09:32:54 rivo volumio[3567]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:54 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Oct 12 09:32:54 rivo volumio[3567]: info: Restoring Previous Volume level: 43 false false Oct 12 09:32:54 rivo volumio[3567]: info: Volume configurations have been set Oct 12 09:32:54 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:54 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:54 rivo volumio[3567]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 12 09:32:54 rivo vtcs[14833]: [2026-10-12 09:32:54.041] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 12 09:32:54 rivo vtcs[14833]: [2026-10-12 09:32:54.042] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 12 09:32:54 rivo sudo[14920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 09:32:54 rivo sudo[14920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:54 rivo sudo[14922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 09:32:54 rivo sudo[14922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:54 rivo sudo[14920]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:54 rivo sudo[14923]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:32:54 rivo sudo[14923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 09:32:54 rivo volumio[3567]: info: Updating Volume Controller Parameters: Device: 5 Name: Amanero Combo768 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 12 09:32:54 rivo volumio[3567]: info: Disabling external Volume Control Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 09:32:54 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 12 09:32:54 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 12 09:32:54 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 12 09:32:54 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 12 09:32:54 rivo sudo[14931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:32:54 rivo sudo[14931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:54 rivo sudo[14923]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:54 rivo volumio[3567]: info: MPD Permissions set Oct 12 09:32:54 rivo volumio[3567]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 12 09:32:54 rivo volumio[3567]: info: VolumeController:: Starting alsactl monitor Oct 12 09:32:54 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 09:32:54 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 09:32:54 rivo sudo[14938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:32:54 rivo sudo[14938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:54 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 09:32:54 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 09:32:54 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:32:54 rivo sudo[14931]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:54 rivo sudo[14938]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 12 09:32:54 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:32:54 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:54 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:54 rivo sudo[14961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:54 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:54 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:54 rivo sudo[14961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:54 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:54 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:54.412+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:54 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:54.413+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:54 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 09:32:54 rivo qobuz-connect[14804]: 20261012 09:32:54.453 [14804.14804] INFO SampleApp: Stopping Local configuration server Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:32:54 rivo volumio[3567]: info: Not Reporting Auto name since its the default one Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:32:54 rivo sudo[14970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:32:54 rivo sudo[14970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:54 rivo sudo[14970]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:54 rivo sudo[14972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:32:54 rivo sudo[14972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:54 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 09:32:54 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 09:32:54 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 09:32:54 rivo sudo[14953]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 09:32:54 rivo sudo[14953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:32:54 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 09:32:54 rivo sudo[14972]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:54 rivo volumio[3567]: info: Raat Daemon started successfully Oct 12 09:32:54 rivo sudo[14953]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:54 rivo volumio[3567]: info: Executing endpoint restartRAATSocket Oct 12 09:32:54 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 09:32:54 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:54 rivo volumio[3567]: 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 12 09:32:55 rivo qobuz-connect[14804]: 20261012 09:32:55.704 [14804.14804] INFO SampleApp: shat down connection on UNIX socket Oct 12 09:32:55 rivo volumio[3567]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 12 09:32:55 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:32:55 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:55 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:55 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 09:32:55 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:32:55 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:32:55 rivo sudo[14961]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:55 rivo volumio[3567]: info: Executing endpoint qc_getconfig Oct 12 09:32:55 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 12 09:32:55 rivo qobuz-connect[14988]: 20261012 09:32:55.852 [14988.14988] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 12 09:32:55 rivo qobuz-connect[14988]: 20261012 09:32:55.858 [14988.14988] INFO VolumeManager: [0xabf1a018]: Setting new playback volume: 75 Oct 12 09:32:55 rivo qobuz-connect[14988]: 20261012 09:32:55.858 [14988.14988] INFO VolumeManager: [0xabf1a018]: Setting new mute state: 0 Oct 12 09:32:55 rivo qobuz-connect[14988]: 20261012 09:32:55.858 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: Setting new audio download buffer size: 1048576 Oct 12 09:32:55 rivo qobuz-connect[14988]: 20261012 09:32:55.858 [14988.14988] INFO QobuzConnect: [0xabf1a8e0]: Client initialized! Oct 12 09:32:55 rivo qobuz-connect[14988]: 20261012 09:32:55.858 [14988.14988] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 12 09:32:55 rivo volumio[3567]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 12 09:32:55 rivo volumio[3567]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 12 09:32:55 rivo qobuz-connect[14988]: 20261012 09:32:55.872 [14988.14988] INFO LocalConfigManager: [0xabf19a98]: Starting Local Configuration server Oct 12 09:32:55 rivo qobuz-connect[14988]: 20261012 09:32:55.872 [14988.14988] INFO SampleApp: Starting Local configuration server Oct 12 09:32:55 rivo qobuz-connect[14988]: 20261012 09:32:55.872 [14988.14988] INFO SampleApp: Connected to UNIX socket client 0xabf04908 Oct 12 09:32:56 rivo qobuz-connect[14988]: 20261012 09:32:56.002 [14988.14988] INFO SampleApp: Playback volume changed: 75 Oct 12 09:32:56 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:56 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:57 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:32:57 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:32:57 rivo volumio[3567]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:32:57 rivo volumio[3567]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:32:57 rivo sudo[15002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 09:32:57 rivo sudo[15002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:32:57 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 12 09:32:57 rivo sudo[15002]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:57 rivo volumio[3567]: info: RAAT: Requesting Headphone Status Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 09:32:57 rivo volumio[3567]: info: MCU Signalled Headphone Mode Disabled Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 12 09:32:57 rivo volumio[3567]: info: Reporting Headphone State: false Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:32:57 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:57 rivo volumio[3567]: info: Executing endpoint tc_getconfig Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 12 09:32:57 rivo vtcs[15006]: STARTING TidalConnect services, version: 1.6.1 Oct 12 09:32:57 rivo vtcs[15006]: STARTED TidalConnect services. Oct 12 09:32:57 rivo volumio[3567]: info: Executing endpoint tc_connect Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 12 09:32:57 rivo volumio[3567]: info: Connecting to TidalConnect Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::servicePushState Oct 12 09:32:57 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:57 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:57 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:57 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:57 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:57 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:57 rivo volumio[3567]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 12 09:32:57 rivo volumio[3567]: verbose: CURRENT POSITION 0 Oct 12 09:32:57 rivo volumio[3567]: info: CoreStateMachine::syncState stateService stop Oct 12 09:32:57 rivo volumio[3567]: info: CoreStateMachine::syncState currentStatus pause Oct 12 09:32:57 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:57 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:57 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:57 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:57 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:57 rivo mpd[14982]: 2026-10-12T09:32:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::servicePushState Oct 12 09:32:57 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:57 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:57 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:57 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:57 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:57 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:57 rivo volumio[3567]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 12 09:32:57 rivo systemd[1]: Started mpd.service - Music Player Daemon. Oct 12 09:32:57 rivo sudo[14922]: pam_unix(sudo:session): session closed for user root Oct 12 09:32:57 rivo volumio[3567]: verbose: CURRENT POSITION 0 Oct 12 09:32:57 rivo volumio[3567]: info: CoreStateMachine::syncState stateService stop Oct 12 09:32:57 rivo volumio[3567]: info: CoreStateMachine::syncState currentStatus pause Oct 12 09:32:57 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:57 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:57 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:57 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:57 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:57 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:57 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:57.920+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:57 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:57.920+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:57 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:57.922+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:57 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:57.922+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:57 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:57.923+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:57 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:57.923+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:57 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:57.924+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:57 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:57.924+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:57 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:57 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:57 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:57 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:32:57 rivo volumio[3567]: error: updateQueue error: null Oct 12 09:32:58 rivo volumio[3567]: info: VolumeController::SetAlsaVolume43 Oct 12 09:32:58 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:32:58 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:58 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:32:58 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:32:58 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:32:58 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:32:58 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:32:58 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:58.555+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:32:58 rivo volumio5-onboarding[3950]: time=2026-10-12T09:32:58.555+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:32:58 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:00 rivo qobuz-connect[14988]: 20261012 09:33:00.214 [14988.14991] INFO SampleApp: API endpoint invoked: get-display-info Oct 12 09:33:00 rivo qobuz-connect[14988]: 20261012 09:33:00.225 [14988.14991] INFO SampleApp: API endpoint invoked: get-connect-info Oct 12 09:33:00 rivo volumio[3567]: info: TidalConnect service started! Oct 12 09:33:01 rivo qobuz-connect[14988]: 20261012 09:33:01.711 [14988.14991] INFO SampleApp: API endpoint invoked: connect-to-qconnect Oct 12 09:33:01 rivo qobuz-connect[14988]: 20261012 09:33:01.711 [14988.14988] INFO EndpointManager: [0xabf197a0]: Updating API endpoint Oct 12 09:33:01 rivo qobuz-connect[14988]: 20261012 09:33:01.711 [14988.14988] INFO EndpointManager: [0xabf197a0]: Updating QConnect endpoint Oct 12 09:33:01 rivo qobuz-connect[14988]: 20261012 09:33:01.711 [14988.14988] INFO ActiveStateManager: [0xabf18838]: Setting new active state: active Oct 12 09:33:01 rivo qobuz-connect[14988]: 20261012 09:33:01.711 [14988.14988] INFO PlaybackSessionManager: [0xabf19ab8]: Starting playback session maintenance Oct 12 09:33:01 rivo qobuz-connect[14988]: 20261012 09:33:01.712 [14988.14988] INFO HttpDownloader: [0xabf19c60]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Oct 12 09:33:01 rivo qobuz-connect[14988]: 20261012 09:33:01.712 [14988.14988] INFO CloudClient: [0xabf1a188]: Connecting to the cloud Oct 12 09:33:01 rivo qobuz-connect[14988]: 20261012 09:33:01.712 [14988.14988] INFO SampleApp: Renderer is now active Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.128 [14988.14988] INFO CloudClient: [0xabf1a188]: Connection established Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.128 [14988.14988] INFO QwspMessageSender: [0xac0b0528]: Sending Authenticate message Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.128 [14988.14988] INFO QwspMessageSender: [0xac0b0528]: Sending Subscribe message Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.128 [14988.14988] INFO QConnectMessageSender: [0xabf558f8]: Sending JoinSession message Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.128 [14988.14988] INFO QConnectMessageSender: [0xabf558f8]: Sending VolumeChanged message Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.128 [14988.14988] INFO QConnectMessageSender: [0xabf558f8]: Sending VolumeMuted message Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.128 [14988.14988] INFO QConnectMessageSender: [0xabf558f8]: Sending MaxAudioQualityChanged message Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.129 [14988.14988] INFO QwspMessageSender: [0xac0b0528]: Sending Payload message Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.171 [14988.14988] INFO PlaybackSessionManager: [0xabf19ab8]: Playback session has been refreshed Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Received SetActive message: active Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Received SetState message: Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Playing state: Playing Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Playback position: 118 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Queue version: 4.1 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Current track: TID: 25005428, QID: 1, Context UUID: b1f58f09-4abf-4e37-97e3-40177d61a4b6 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Next track: TID: 25005429, QID: 2, Context UUID: b1f58f09-4abf-4e37-97e3-40177d61a4b6 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO MediaEngine: [0xabf19cc0]: Stopping playback, clearing tracks Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO MediaEngine: [0xabf19cc0]: Initiating playback Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO RendererActionAvailabilityManager: [0xabf1a1f8]: Renderer action 'Next' is available Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Received SetLoopMode message: Off Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO PlaybackModeManager: [0xabf19f88]: Setting new loop mode: Off Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO MediaEngine: [0xabf19cc0]: Setting current track: 25005428, initial offset: 118ms Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: Clearing all streams Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.291 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: New stream: 1 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.292 [14988.14988] INFO HttpDownloader: [0xabf31bd8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=bd0babc68ec18f15bd51884485999d9d&request_ts=1791757982&track_id=25005428 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.292 [14988.14988] INFO HttpDownloader: [0xac054a98]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=25005428 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.292 [14988.14988] INFO HttpDownloader: [0xabf1fec8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=d0646efa155591de4cc7d55ce6c24fc1&request_ts=1791757982&track_id=25005428 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.292 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: [Stream 1]: Running audio stream Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.292 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Received SetShuffleMode message: disabled Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.292 [14988.14988] INFO PlaybackModeManager: [0xabf19f88]: Setting new shuffle mode: disabled Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.292 [14988.14988] INFO MediaEngine: [0xabf19cc0]: Setting next track: 25005429 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.292 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: New stream: 2 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.292 [14988.14988] INFO HttpDownloader: [0xac04f968]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=2719670bedb9c33c700ee67ebfba869b&request_ts=1791757982&track_id=25005429 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.292 [14988.14988] INFO HttpDownloader: [0xabf249f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=25005429 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.292 [14988.14988] INFO HttpDownloader: [0xabf24ce8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=d1b76e25ee508c8a0d24278c08fc4c13&request_ts=1791757982&track_id=25005429 Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.294 [14988.14988] INFO MediaEngine: [0xabf19cc0]: Waiting for current stream to start before starting audio renderer Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.578 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: [Stream 1]: Metadata became available: Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.578 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: Title: Aircraft Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.578 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: Artist: Dennis Chambers Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.578 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: Album: Jazz Rock Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.578 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: Album art URL: https://static.qobuz.com/images/covers/17/30/3614592893017_600.jpg Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.587 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: [Stream 2]: Metadata became available: Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.587 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: Title: Roller Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.587 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: Artist: Billy Cobham Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.587 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: Album: Jazz Rock Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.587 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: Album art URL: https://static.qobuz.com/images/covers/17/30/3614592893017_600.jpg Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.628 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=11766482&eid=25005429&fmt=6&profile=raw&app_id=174516466&cid=4159443&etsp=1791761582&hmac=sBM3mr6f91yCjlC04PlZq04vCRM Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.673 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: [Stream 1]: stream information have been fetched Oct 12 09:33:02 rivo qobuz-connect[14988]: 20261012 09:33:02.709 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: [Stream 2]: stream information have been fetched Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.139 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=11766482&eid=25005428&fmt=6&profile=raw&app_id=174516466&cid=4159443&etsp=1791761582&hmac=8Rzeoi3y9VAXBLnP64nj1gHwNdw Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.139 [14988.14988] INFO UrlAudioSource: [0xabf203d0]: Starting URL audio source, initial position: 118ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=11766482&eid=25005428&fmt=6&profile=raw&app_id=174516466&cid=4159443&etsp=1791761582&hmac=8Rzeoi3y9VAXBLnP64nj1gHwNdw Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.139 [14988.14988] INFO ContentFetcher: [0xac012e80]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=11766482&eid=25005428&fmt=6&profile=raw&app_id=174516466&cid=4159443&etsp=1791761582&hmac=8Rzeoi3y9VAXBLnP64nj1gHwNdw, offset: 0 Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.139 [14988.14988] INFO AudioRenderer: [0xabf19e28]: Starting audio renderer, initial playback state: Playing Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.139 [14988.14988] INFO SampleApp: [Stream 1]: New audio stream (starting from 117ms) Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.143 [14988.14988] INFO SampleApp: [Stream 1]: Stream metadata became available: Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.143 [14988.14988] INFO SampleApp: Title: Aircraft Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.143 [14988.14988] INFO SampleApp: Artist: Dennis Chambers Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.143 [14988.14988] INFO SampleApp: Album: Jazz Rock Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.143 [14988.14988] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/17/30/3614592893017_600.jpg Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:03 rivo volumio[3567]: info: QobuzConnect: setActiveState() Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:03 rivo volumio[3567]: info: QobuzConnect: Set Qobuz connect default volume to 43 Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:03 rivo volumio[3567]: info: QobuzConnect: Stopping currently active service Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioStop Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::stop Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::updateTrackBlock Oct 12 09:33:03 rivo volumio[3567]: info: CorePlayQueue::getTrackBlock Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::stPlaybackTimer Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:03 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::serviceStop Oct 12 09:33:03 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:03 rivo volumio[3567]: info: ControllerMpd::stop Oct 12 09:33:03 rivo volumio[3567]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 09:33:03 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:03 rivo volumio[3567]: info: sendMpdCommand stop took 13 milliseconds Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::servicePushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.157 [14988.14988] INFO VolumeManager: [0xabf1a018]: Setting new playback volume: 43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.153+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.154+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:03 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:03 rivo volumio[3567]: verbose: STATE SERVICE {"status":"stop","service":"qobuzconnect","title":"Aircraft","artist":"Dennis Chambers","album":"Jazz Rock","albumart":"https://static.qobuz.com/images/covers/17/30/3614592893017_600.jpg","uri":"","icon":"fa fa-spinner","trackType":"qobuz","codec":"","seek":130,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"stream":false,"repeatMode":"all"} Oct 12 09:33:03 rivo volumio[3567]: verbose: CURRENT POSITION 0 Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::syncState stateService stop Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::syncState currentStatus stop Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:03 rivo volumio[3567]: info: No code Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:03 rivo volumio[3567]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.184+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=130 volume=43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.184+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title=Aircraft Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.186+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=130 volume=43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.186+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=130 volume=43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.187+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title=Aircraft Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.187+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title=Aircraft Oct 12 09:33:03 rivo volumio[3567]: info: QobuzConnect: setActiveState() completed, send playback confirmation Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.196 [14988.14988] INFO SampleApp: Initiating playback, initial state: Playing Oct 12 09:33:03 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:03 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:03 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.236 [14988.14988] INFO SampleApp: Playback state changed: Playing Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.237 [14988.14988] INFO StreamingEventReporter: [0xabf1a648]: Reporting streaming started event Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.237 [14988.14988] INFO HttpDownloader: [0xabf1a6a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.237 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Need to send state update Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.237 [14988.14988] INFO QConnectMessageSender: [0xabf558f8]: Sending StateUpdated message Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.237 [14988.14988] INFO QwspMessageSender: [0xac0b0528]: Sending Payload message Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.237 [14988.14988] INFO QConnectMessageSender: [0xabf558f8]: Sending FileAudioQualityChanged message Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::servicePushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.238 [14988.14988] INFO QwspMessageSender: [0xac0b0528]: Sending Payload message Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.238 [14988.14988] INFO QConnectMessageSender: [0xabf558f8]: Sending DeviceAudioQualityChanged message Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.239 [14988.14988] INFO QwspMessageSender: [0xac0b0528]: Sending Payload message Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:03 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:03 rivo volumio[3567]: verbose: STATE SERVICE {"status":"stop","service":"qobuzconnect","title":"Aircraft","artist":"Dennis Chambers","album":"Jazz Rock","albumart":"https://static.qobuz.com/images/covers/17/30/3614592893017_600.jpg","uri":"","icon":"fa fa-spinner","trackType":"qobuz","codec":"","seek":116,"duration":326,"samplerate":"44.1 kHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"stream":false,"repeatMode":"all"} Oct 12 09:33:03 rivo volumio[3567]: verbose: CURRENT POSITION 0 Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::syncState stateService stop Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::syncState currentStatus stop Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:03 rivo volumio[3567]: info: No code Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.258+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=116 volume=43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.259+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title=Aircraft Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.260+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=116 volume=43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.260+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title=Aircraft Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.270+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=116 volume=43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.272+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title=Aircraft Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::servicePushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::servicePushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::servicePushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.305+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_PLAYING positionMs=116 volume=43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.305+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title=Aircraft Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.305+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_PLAYING positionMs=116 volume=43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.305+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title=Aircraft Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.309+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_PLAYING positionMs=116 volume=43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.310+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title=Aircraft Oct 12 09:33:03 rivo volumio[3567]: info: Signalling Playback active due to playback status change Oct 12 09:33:03 rivo volumio[3567]: info: Signalling Playback active due to playback status change Oct 12 09:33:03 rivo volumio[3567]: info: Signalling Playback active due to playback status change Oct 12 09:33:03 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:03 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:03 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:03 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:03 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:03 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:03 rivo volumio[3567]: info: MCU Signalled Playback Active Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.478 [14988.14988] INFO SampleApp: Playback volume changed: 43 Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.479 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Volume changed: 43 Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.479 [14988.14988] INFO QConnectMessageSender: [0xabf558f8]: Sending VolumeChanged message Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.479 [14988.14988] INFO QwspMessageSender: [0xac0b0528]: Sending Payload message Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: QobuzConnect: Volume received from daemon: 43 Oct 12 09:33:03 rivo volumio[3567]: info: VolumeController::SetAlsaVolume43 Oct 12 09:33:03 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.511 [14988.14988] INFO UrlAudioSource: [0xabf203d0]: Content info extracted Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.511 [14988.14988] INFO UrlAudioSource: [0xabf203d0]: Size: 40821914 Oct 12 09:33:03 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:03 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.528 [14988.15053] INFO AudioDecoder: [0xac10aa90]: Stream info: Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.528 [14988.15053] INFO AudioDecoder: [0xac10aa90]: Total samples: 14353527 Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.529 [14988.15053] INFO AudioDecoder: [0xac10aa90]: Sample rate: 44100 Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.529 [14988.15053] INFO AudioDecoder: [0xac10aa90]: Channel count: 2 Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.529 [14988.15053] INFO AudioDecoder: [0xac10aa90]: Bits per sample: 16 Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.529 [14988.15053] INFO AudioDecoder: [0xac10aa90]: Duration: 325476 Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.529 [14988.14988] INFO UrlAudioSource: [0xabf203d0]: Audio properties have changed Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.529 [14988.14988] INFO UrlAudioSource: [0xabf203d0]: Seeking input stream to byte offset: 6973 Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.529 [14988.14988] INFO ContentFetcher: [0xac012e80]: Adjusting download offset to: 6973 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.532+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_PLAYING positionMs=116 volume=43 Oct 12 09:33:03 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:03.533+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title=Aircraft Oct 12 09:33:03 rivo volumio[3567]: info: Signalling Playback active due to playback status change Oct 12 09:33:03 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.752 [14988.14988] INFO UrlAudioSource: [0xabf203d0]: Seeking input stream to byte offset: 640 Oct 12 09:33:03 rivo qobuz-connect[14988]: 20261012 09:33:03.752 [14988.14988] INFO ContentFetcher: [0xac012e80]: Adjusting download offset to: 640 Oct 12 09:33:04 rivo qobuz-connect[14988]: 20261012 09:33:04.019 [14988.14988] INFO UrlAudioSource: [0xabf203d0]: Seeking input stream to byte offset: 2179 Oct 12 09:33:04 rivo qobuz-connect[14988]: 20261012 09:33:04.019 [14988.14988] INFO ContentFetcher: [0xac012e80]: Adjusting download offset to: 2179 Oct 12 09:33:04 rivo qobuz-connect[14988]: 20261012 09:33:04.178 [14988.14988] INFO UrlAudioSource: [0xabf203d0]: Seeking input stream to byte offset: 654 Oct 12 09:33:04 rivo qobuz-connect[14988]: 20261012 09:33:04.178 [14988.14988] INFO ContentFetcher: [0xac012e80]: Adjusting download offset to: 654 Oct 12 09:33:05 rivo volumio[3567]: info: Executing endpoint metavolumio Oct 12 09:33:05 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 12 09:33:05 rivo volumio[3567]: info: Executing endpoint metavolumio Oct 12 09:33:05 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 12 09:33:05 rivo volumio[3567]: info: Executing endpoint metavolumio Oct 12 09:33:05 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 12 09:33:05 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:05.777+11:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s Oct 12 09:33:05 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:05.791+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=14.391908ms Oct 12 09:33:05 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:05.796+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=16.835122ms Oct 12 09:33:05 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:05.894+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=https://www.googleapis.com duration=117.232475ms Oct 12 09:33:05 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:05.984+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=https://functions.volumio.cloud duration=204.627923ms Oct 12 09:33:06 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:06.005+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=https://functions.volumio.cloud duration=226.420389ms Oct 12 09:33:06 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 09:33:06 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 09:33:06 rivo volumio[3567]: info: Discovery: Getting this device information Oct 12 09:33:06 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:06 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 09:33:06 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:06.073+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=http://pushupdates.volumio.org duration=294.792461ms Oct 12 09:33:06 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:06.077+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=https://securetoken.googleapis.com duration=299.925307ms Oct 12 09:33:06 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:06.094+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=https://google.com duration=316.449594ms Oct 12 09:33:06 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:06.102+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=322.321941ms Oct 12 09:33:06 rivo volumio[3567]: verbose: New Socket.io Connection to 192.168.20.18:3000 from 192.168.20.16 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 12 09:33:06 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:06.194+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=https://database.volumio.cloud duration=413.720773ms Oct 12 09:33:06 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 09:33:06 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 09:33:06 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:06.322+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=544.287278ms Oct 12 09:33:06 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:06.389+11:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=http://plugins.volumio.org duration=610.426845ms Oct 12 09:33:06 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:06.835+11:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.20.16:45174 @ 0x315a2d0" latency=421.029976ms timeout=10s endpoint=http://cddb.volumio.org duration=1.057468361s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 12 09:33:13 rivo qobuz-connect[14988]: 20261012 09:33:13.237 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Need to send state update Oct 12 09:33:13 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:13 rivo volumio[3567]: info: CoreCommandRouter::servicePushState Oct 12 09:33:13 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:13 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 09:33:13 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:13 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:13 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:13 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:13 rivo qobuz-connect[14988]: 20261012 09:33:13.245 [14988.14988] INFO QConnectMessageSender: [0xabf558f8]: Sending StateUpdated message Oct 12 09:33:13 rivo qobuz-connect[14988]: 20261012 09:33:13.245 [14988.14988] INFO QwspMessageSender: [0xac0b0528]: Sending Payload message Oct 12 09:33:13 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:13.262+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_PLAYING positionMs=7910 volume=43 Oct 12 09:33:13 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:13.262+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title=Aircraft Oct 12 09:33:13 rivo volumio[3567]: info: Signalling Playback active due to playback status change Oct 12 09:33:13 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:16 rivo volumio[3567]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 12 09:33:16 rivo volumio[3567]: info: Preparing to save Alsa Options, stopping services first Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioPause Oct 12 09:33:16 rivo volumio[3567]: info: CoreStateMachine::pause Oct 12 09:33:16 rivo volumio[3567]: info: CoreStateMachine::stPlaybackTimer Oct 12 09:33:16 rivo volumio[3567]: info: CoreStateMachine::servicePause Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::servicePause Oct 12 09:33:16 rivo volumio[3567]: info: QobuzConnect: Received pause Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:16 rivo volumio[3567]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Amanero Combo768"}} Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.138 [14988.14988] INFO MediaEngine: [0xabf19cc0]: Pausing playback Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.140 [14988.14988] INFO AudioRenderer: [0xabf19e28]: Pausing audio renderer Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.140 [14988.14988] INFO SampleApp: Pausing playback Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.141 [14988.14988] INFO SampleApp: Playback state changed: Paused Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.141 [14988.14988] INFO StreamingEventReporter: [0xabf1a648]: Reporting streaming ended event Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.141 [14988.14988] INFO HttpDownloader: [0xabf1a6a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.141 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Need to send state update Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.141 [14988.14988] INFO QConnectMessageSender: [0xabf558f8]: Sending StateUpdated message Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.141 [14988.14988] INFO QwspMessageSender: [0xac0b0528]: Sending Payload message Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:16 rivo volumio[3567]: info: Setting mixer PCM for card Amanero Combo768 Oct 12 09:33:16 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:16 rivo volumio[3567]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State Oct 12 09:33:16 rivo volumio[3567]: verbose: UNSET VOLATILE: Service: qobuzconnect Oct 12 09:33:16 rivo volumio[3567]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Oct 12 09:33:16 rivo volumio[3567]: info: QobuzConnect: Received stop Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:16 rivo volumio[3567]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.226 [14988.14988] INFO MediaEngine: [0xabf19cc0]: Stopping playback, keeping tracks Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.227 [14988.14988] INFO AudioRenderer: [0xabf19e28]: Stopping audio renderer Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.227 [14988.14988] INFO SampleApp: Stopping playback Oct 12 09:33:16 rivo vtcs[15006]: [2026-10-12 09:33:16.225] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 12 09:33:16 rivo vtcs[15006]: [2026-10-12 09:33:16.226] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.258 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: [Stream 1]: Disposing of audio stream Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.258 [14988.14988] INFO ContentFetcher: [0xac012e80]: Canceling download Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.268 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: New stream: 3 Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.268 [14988.14988] INFO HttpDownloader: [0xabf31bd8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=505058e51367becdb2cc33c38c962a73&request_ts=1791757996&track_id=25005428 Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.268 [14988.14988] INFO HttpDownloader: [0xac0add70]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=25005428 Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.268 [14988.14988] INFO HttpDownloader: [0xac130100]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=5c04ec42565f4b35716dfd50f589157d&request_ts=1791757996&track_id=25005428 Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.268 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: [Stream 3]: Running audio stream Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.268 [14988.14988] INFO AudioStreamManager: [0xabf19d70]: New stream: 4 Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.268 [14988.14988] INFO HttpDownloader: [0xabf249d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=431c0177f8ef7cc5ae3462dc0a107e04&request_ts=1791757996&track_id=25005429 Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.268 [14988.14988] INFO HttpDownloader: [0xac0249f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=25005429 Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.268 [14988.14988] INFO HttpDownloader: [0xac05a4e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=2ed678816e6677b82a220cb36476f963&request_ts=1791757996&track_id=25005429 Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.271 [14988.14988] INFO SampleApp: Playback state changed: Stopped Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.271 [14988.14988] INFO ProtocolHandler: [0xabf1a298]: Need to send state update Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.271 [14988.14988] INFO QConnectMessageSender: [0xabf558f8]: Sending StateUpdated message Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.271 [14988.14988] INFO QwspMessageSender: [0xac0b0528]: Sending Payload message Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.271 [14988.14988] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Oct 12 09:33:16 rivo sudo[15092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:16 rivo sudo[15092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 09:33:16 rivo volumio[3567]: info: Updating Volume Controller Parameters: Device: 5 Name: Amanero Combo768 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 12 09:33:16 rivo volumio[3567]: info: Disabling external Volume Control Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 09:33:16 rivo volumio[3567]: info: Preparing to generate the ALSA configuration file Oct 12 09:33:16 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 12 09:33:16 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 12 09:33:16 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 12 09:33:16 rivo sudo[15092]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:16 rivo sudo[15098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:16 rivo sudo[15098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:16 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:16 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:16 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:16 rivo volumio[3567]: info: QobuzConnect: playback_stop received, deferred solved Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:16 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:16 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:16 rivo volumio[3567]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 09:33:16 rivo volumio[3567]: info: Reading ALSA contributions from plugins. Oct 12 09:33:16 rivo volumio[3567]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 12 09:33:16 rivo volumio[3567]: info: VolumeController:: Starting alsactl monitor Oct 12 09:33:16 rivo sudo[15105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:33:16 rivo sudo[15105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:16 rivo sudo[15098]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:16 rivo sudo[15105]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:16 rivo sudo[15113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:33:16 rivo sudo[15113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 12 09:33:16 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:33:16 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:16 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:16 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:16 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:16 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:16 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:16.515+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:33:16 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:16.515+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:33:16 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.534 [14988.14988] INFO HttpDownloader: [0xac130100]: Canceling download Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.534 [14988.14988] INFO HttpDownloader: [0xac0add70]: Canceling download Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.534 [14988.14988] INFO HttpDownloader: [0xabf31bd8]: Canceling download Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.534 [14988.14988] INFO HttpDownloader: [0xac05a4e8]: Canceling download Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.534 [14988.14988] INFO HttpDownloader: [0xac0249f0]: Canceling download Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.534 [14988.14988] INFO HttpDownloader: [0xabf249d0]: Canceling download Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.534 [14988.14988] INFO CloudClient: [0xabf1a188]: Disconnecting from the cloud Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.534 [14988.14988] INFO CloudClient: [0xabf1a188]: Connection closed Oct 12 09:33:16 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 09:33:16 rivo volumio[3567]: info: Asound.conf file unchanged, so no further update is needed Oct 12 09:33:16 rivo volumio[3567]: info: Output device has changed, restarting MPD Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.546 [14988.14988] INFO HttpDownloader: [0xabf1a6a0]: Canceling download Oct 12 09:33:16 rivo qobuz-connect[14988]: 20261012 09:33:16.558 [14988.14988] INFO SampleApp: Stopping Local configuration server Oct 12 09:33:16 rivo volumio[3567]: info: Output device has changed, restarting Shairport Sync Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:16 rivo sudo[15117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 09:33:16 rivo sudo[15117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:16 rivo sudo[15119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 09:33:16 rivo sudo[15119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:16 rivo sudo[15117]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:16 rivo volumio[3567]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 09:33:16 rivo volumio[3567]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:16 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:16 rivo volumio[3567]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 12 09:33:16 rivo sudo[15128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:16 rivo sudo[15128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:16 rivo sudo[15130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:16 rivo sudo[15130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:16 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 09:33:16 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 09:33:16 rivo sudo[15137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: Not Reporting Auto name since its the default one Oct 12 09:33:16 rivo volumio[3567]: info: MPD Permissions set Oct 12 09:33:16 rivo volumio[3567]: info: MCU Signalled Playback Inactive Oct 12 09:33:16 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 09:33:16 rivo sudo[15137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:16 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 09:33:16 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:16 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 09:33:16 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:33:17 rivo sudo[15128]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:17 rivo sudo[15137]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:17 rivo volumio[3567]: info: Starting Shairport Sync Oct 12 09:33:17 rivo sudo[15130]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:17 rivo sudo[15162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:33:17 rivo sudo[15162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:17 rivo sudo[15160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:33:17 rivo sudo[15160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:17 rivo sudo[15165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 09:33:17 rivo sudo[15165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:17 rivo volumio[3567]: info: Not Reporting Auto name since its the default one Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:33:17 rivo sudo[15160]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:17 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 09:33:17 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 09:33:17 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 09:33:17 rivo sudo[15175]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:33:17 rivo sudo[15175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:17 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 09:33:17 rivo sudo[15165]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:17 rivo volumio[3567]: info: Shairport-Sync Started Oct 12 09:33:17 rivo sudo[15177]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:33:17 rivo sudo[15177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:17 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 09:33:17 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 09:33:17 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 09:33:17 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 09:33:17 rivo sudo[15175]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:17 rivo volumio[3567]: info: Raat Daemon started successfully Oct 12 09:33:17 rivo sudo[15177]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:17 rivo sudo[15183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:33:17 rivo sudo[15183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:17 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 09:33:17 rivo volumio[3567]: info: Executing endpoint restartRAATSocket Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 09:33:17 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 09:33:17 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 09:33:17 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 09:33:17 rivo sudo[15183]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:17 rivo volumio[3567]: info: Raat Daemon started successfully Oct 12 09:33:17 rivo sudo[15148]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 09:33:17 rivo sudo[15148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:33:17 rivo sudo[15148]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:17 rivo volumio[3567]: info: Executing endpoint restartRAATSocket Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 09:33:17 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:17 rivo volumio[3567]: 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 12 09:33:17 rivo qobuz-connect[14988]: 20261012 09:33:17.731 [14988.14988] INFO SampleApp: shat down connection on UNIX socket Oct 12 09:33:17 rivo volumio[3567]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 12 09:33:17 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:17 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:17 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 09:33:17 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:33:17 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:33:17 rivo sudo[15162]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:17 rivo sudo[15113]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:17 rivo volumio[3567]: info: Executing endpoint qc_getconfig Oct 12 09:33:17 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 12 09:33:17 rivo qobuz-connect[15218]: 20261012 09:33:17.907 [15218.15218] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 12 09:33:17 rivo volumio[3567]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 12 09:33:17 rivo volumio[3567]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 12 09:33:17 rivo qobuz-connect[15218]: 20261012 09:33:17.925 [15218.15218] INFO VolumeManager: [0xab217018]: Setting new playback volume: 75 Oct 12 09:33:17 rivo qobuz-connect[15218]: 20261012 09:33:17.925 [15218.15218] INFO VolumeManager: [0xab217018]: Setting new mute state: 0 Oct 12 09:33:17 rivo qobuz-connect[15218]: 20261012 09:33:17.925 [15218.15218] INFO AudioStreamManager: [0xab216d70]: Setting new audio download buffer size: 1048576 Oct 12 09:33:17 rivo qobuz-connect[15218]: 20261012 09:33:17.925 [15218.15218] INFO QobuzConnect: [0xab2178e0]: Client initialized! Oct 12 09:33:17 rivo qobuz-connect[15218]: 20261012 09:33:17.925 [15218.15218] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 12 09:33:17 rivo qobuz-connect[15218]: 20261012 09:33:17.940 [15218.15218] INFO LocalConfigManager: [0xab216a98]: Starting Local Configuration server Oct 12 09:33:17 rivo qobuz-connect[15218]: 20261012 09:33:17.940 [15218.15218] INFO SampleApp: Starting Local configuration server Oct 12 09:33:17 rivo qobuz-connect[15218]: 20261012 09:33:17.941 [15218.15218] INFO SampleApp: Connected to UNIX socket client 0xab201908 Oct 12 09:33:18 rivo qobuz-connect[15218]: 20261012 09:33:18.057 [15218.15218] INFO SampleApp: Playback volume changed: 75 Oct 12 09:33:18 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:18 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:18 rivo volumio[3567]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 12 09:33:18 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 12 09:33:18 rivo volumio[3567]: info: Preparing to save Alsa Options, stopping services first Oct 12 09:33:18 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:18 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:18 rivo volumio[3567]: info: CoreCommandRouter::volumioPause Oct 12 09:33:18 rivo volumio[3567]: info: CoreStateMachine::pause Oct 12 09:33:18 rivo volumio[3567]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Amanero Combo768"}} Oct 12 09:33:18 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:19 rivo volumio[3567]: info: Setting mixer PCM for card Amanero Combo768 Oct 12 09:33:19 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:19 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:19 rivo volumio[3567]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 12 09:33:19 rivo sudo[15231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:19 rivo sudo[15231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 09:33:19 rivo volumio[3567]: info: Updating Volume Controller Parameters: Device: 5 Name: Amanero Combo768 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 12 09:33:19 rivo volumio[3567]: info: Disabling external Volume Control Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 09:33:19 rivo volumio[3567]: info: Preparing to generate the ALSA configuration file Oct 12 09:33:19 rivo sudo[15231]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:19 rivo sudo[15238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:19 rivo sudo[15238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo volumio[3567]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 09:33:19 rivo volumio[3567]: info: Reading ALSA contributions from plugins. Oct 12 09:33:19 rivo volumio[3567]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 12 09:33:19 rivo volumio[3567]: info: VolumeController:: Starting alsactl monitor Oct 12 09:33:19 rivo sudo[15244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:33:19 rivo sudo[15244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:19 rivo sudo[15238]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:19 rivo sudo[15244]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 12 09:33:19 rivo sudo[15253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:33:19 rivo sudo[15253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 12 09:33:19 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:33:19 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:19 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:19 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:19 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:19 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:19 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:19.383+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:33:19 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:19.384+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:33:19 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:19 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: Asound.conf file unchanged, so no further update is needed Oct 12 09:33:19 rivo volumio[3567]: info: Output device has changed, restarting MPD Oct 12 09:33:19 rivo qobuz-connect[15218]: 20261012 09:33:19.420 [15218.15218] INFO SampleApp: Stopping Local configuration server Oct 12 09:33:19 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 09:33:19 rivo volumio[3567]: info: Output device has changed, restarting Shairport Sync Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:19 rivo sudo[15256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 09:33:19 rivo sudo[15256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo sudo[15256]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:19 rivo sudo[15258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 09:33:19 rivo sudo[15258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo volumio[3567]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 09:33:19 rivo volumio[3567]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:19 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:19 rivo volumio[3567]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo sudo[15267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:19 rivo sudo[15267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo sudo[15270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:19 rivo sudo[15270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 09:33:19 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 09:33:19 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 09:33:19 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 09:33:19 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 09:33:19 rivo volumio[3567]: info: MPD Permissions set Oct 12 09:33:19 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:33:19 rivo sudo[15276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:33:19 rivo sudo[15276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: Not Reporting Auto name since its the default one Oct 12 09:33:19 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 09:33:19 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:33:19 rivo sudo[15276]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:19 rivo volumio[3567]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:33:19 rivo volumio[3567]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:33:19 rivo sudo[15300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:33:19 rivo sudo[15300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo volumio[3567]: info: Starting Shairport Sync Oct 12 09:33:19 rivo sudo[15267]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:19 rivo sudo[15302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:33:19 rivo sudo[15302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo sudo[15270]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:19 rivo sudo[15306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 09:33:19 rivo sudo[15306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo sudo[15308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 09:33:19 rivo sudo[15308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo sudo[15302]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:19 rivo sudo[15313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:33:19 rivo sudo[15313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:19 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 09:33:19 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 12 09:33:19 rivo sudo[15306]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:19 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 09:33:19 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 09:33:19 rivo volumio[3567]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 12 09:33:19 rivo volumio[3567]: info: Preparing to save Alsa Options, stopping services first Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:19 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::volumioPause Oct 12 09:33:19 rivo volumio[3567]: info: CoreStateMachine::pause Oct 12 09:33:19 rivo volumio[3567]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Amanero Combo768"}} Oct 12 09:33:19 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:19 rivo qobuz-connect[15218]: 20261012 09:33:19.950 [15218.15218] INFO SampleApp: shat down connection on UNIX socket Oct 12 09:33:19 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 09:33:19 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:20 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:33:20 rivo sudo[15253]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:20 rivo sudo[15300]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:20 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 09:33:20 rivo sudo[15308]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:20 rivo volumio[3567]: info: Setting mixer PCM for card Amanero Combo768 Oct 12 09:33:20 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:20 rivo volumio[3567]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 12 09:33:20 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 09:33:20 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 09:33:20 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 09:33:20 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 09:33:20 rivo sudo[15313]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:20 rivo sudo[15329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:20 rivo sudo[15329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 09:33:20 rivo volumio[3567]: info: Updating Volume Controller Parameters: Device: 5 Name: Amanero Combo768 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 12 09:33:20 rivo volumio[3567]: info: Disabling external Volume Control Oct 12 09:33:20 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 09:33:20 rivo volumio[3567]: info: Preparing to generate the ALSA configuration file Oct 12 09:33:20 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 12 09:33:20 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 12 09:33:20 rivo sudo[15287]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 09:33:20 rivo sudo[15287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:33:20 rivo sudo[15329]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:20 rivo sudo[15287]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:20 rivo sudo[15360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:20 rivo sudo[15360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:20 rivo sudo[15367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:33:20 rivo sudo[15367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: Not Reporting Auto name since its the default one Oct 12 09:33:20 rivo volumio[3567]: info: RAAT: Requesting Headphone Status Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 09:33:20 rivo volumio[3567]: info: RAAT: Requesting Headphone Status Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 12 09:33:20 rivo volumio[3567]: info: CoreStateMachine::getcurrentVolume Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 09:33:20 rivo volumio[3567]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 09:33:20 rivo volumio[3567]: info: Reading ALSA contributions from plugins. Oct 12 09:33:20 rivo volumio[3567]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 12 09:33:20 rivo volumio[3567]: info: VolumeController:: Starting alsactl monitor Oct 12 09:33:20 rivo sudo[15367]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:20 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:33:20 rivo sudo[15360]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:20 rivo volumio[3567]: info: Raat Daemon started successfully Oct 12 09:33:20 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:33:20 rivo volumio[3567]: info: Shairport-Sync Started Oct 12 09:33:20 rivo volumio[3567]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 12 09:33:20 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:20 rivo volumio[3567]: info: Executing endpoint qc_getconfig Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 12 09:33:20 rivo sudo[15376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:33:20 rivo sudo[15376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:20 rivo qobuz-connect[15322]: 20261012 09:33:20.554 [15322.15322] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 12 09:33:20 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:33:20 rivo qobuz-connect[15322]: 20261012 09:33:20.560 [15322.15322] INFO VolumeManager: [0xabdc6018]: Setting new playback volume: 75 Oct 12 09:33:20 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:20 rivo qobuz-connect[15322]: 20261012 09:33:20.563 [15322.15322] INFO VolumeManager: [0xabdc6018]: Setting new mute state: 0 Oct 12 09:33:20 rivo qobuz-connect[15322]: 20261012 09:33:20.563 [15322.15322] INFO AudioStreamManager: [0xabdc5d70]: Setting new audio download buffer size: 1048576 Oct 12 09:33:20 rivo qobuz-connect[15322]: 20261012 09:33:20.563 [15322.15322] INFO QobuzConnect: [0xabdc68e0]: Client initialized! Oct 12 09:33:20 rivo qobuz-connect[15322]: 20261012 09:33:20.563 [15322.15322] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:20 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:20 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:20 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 09:33:20 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:20.582+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:33:20 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:20.583+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:33:20 rivo volumio[3567]: info: MCU Signalled Headphone Mode Disabled Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 12 09:33:20 rivo volumio[3567]: info: Reporting Headphone State: false Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:20 rivo volumio[3567]: info: MCU Signalled Headphone Mode Disabled Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 12 09:33:20 rivo volumio[3567]: info: Reporting Headphone State: false Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:20 rivo volumio[3567]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 12 09:33:20 rivo volumio[3567]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 12 09:33:20 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:20 rivo qobuz-connect[15322]: 20261012 09:33:20.621 [15322.15322] INFO LocalConfigManager: [0xabdc5a98]: Starting Local Configuration server Oct 12 09:33:20 rivo qobuz-connect[15322]: 20261012 09:33:20.622 [15322.15322] INFO SampleApp: Starting Local configuration server Oct 12 09:33:20 rivo qobuz-connect[15322]: 20261012 09:33:20.632 [15322.15322] INFO SampleApp: Stopping Local configuration server Oct 12 09:33:20 rivo volumio[3567]: info: Executing endpoint restartRAATSocket Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 09:33:20 rivo qobuz-connect[15322]: 20261012 09:33:20.643 [15322.15322] INFO SampleApp: shat down connection on UNIX socket Oct 12 09:33:20 rivo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV Oct 12 09:33:20 rivo systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 12 09:33:20 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:33:20 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:33:20 rivo sudo[15376]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: Not Reporting Auto name since its the default one Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:33:20 rivo volumio[3567]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 12 09:33:20 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:20 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:33:20 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:20 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:20 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:20 rivo volumio[3567]: info: CoreStateMachine::updateTrackBlock Oct 12 09:33:20 rivo volumio[3567]: info: CorePlayQueue::getTrackBlock Oct 12 09:33:20 rivo volumio[3567]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 09:33:20 rivo sudo[15405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:33:20 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:20.944+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:33:20 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:20.945+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:33:20 rivo sudo[15405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:20 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:20 rivo volumio[3567]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:33:20 rivo volumio[3567]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:33:20 rivo sudo[15405]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:21 rivo volumio[3567]: info: Executing endpoint qc_getconfig Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 12 09:33:21 rivo sudo[15409]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 09:33:21 rivo sudo[15409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:21 rivo sudo[15410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:33:21 rivo sudo[15410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:21 rivo qobuz-connect[15391]: 20261012 09:33:21.067 [15391.15391] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 12 09:33:21 rivo qobuz-connect[15391]: 20261012 09:33:21.076 [15391.15391] INFO VolumeManager: [0xaac70018]: Setting new playback volume: 75 Oct 12 09:33:21 rivo qobuz-connect[15391]: 20261012 09:33:21.076 [15391.15391] INFO VolumeManager: [0xaac70018]: Setting new mute state: 0 Oct 12 09:33:21 rivo qobuz-connect[15391]: 20261012 09:33:21.077 [15391.15391] INFO AudioStreamManager: [0xaac6fd70]: Setting new audio download buffer size: 1048576 Oct 12 09:33:21 rivo qobuz-connect[15391]: 20261012 09:33:21.077 [15391.15391] INFO QobuzConnect: [0xaac708e0]: Client initialized! Oct 12 09:33:21 rivo qobuz-connect[15391]: 20261012 09:33:21.077 [15391.15391] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 12 09:33:21 rivo qobuz-connect[15391]: 20261012 09:33:21.099 [15391.15391] INFO LocalConfigManager: [0xaac6fa98]: Starting Local Configuration server Oct 12 09:33:21 rivo qobuz-connect[15391]: 20261012 09:33:21.100 [15391.15391] INFO SampleApp: Starting Local configuration server Oct 12 09:33:21 rivo qobuz-connect[15391]: 20261012 09:33:21.100 [15391.15391] INFO SampleApp: Connected to UNIX socket client 0xaac5a908 Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:33:21 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 12 09:33:21 rivo sudo[15409]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:21 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 09:33:21 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 09:33:21 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 09:33:21 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 09:33:21 rivo sudo[15410]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:21 rivo qobuz-connect[15391]: 20261012 09:33:21.222 [15391.15391] INFO SampleApp: Playback volume changed: 75 Oct 12 09:33:21 rivo volumio[3567]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 12 09:33:21 rivo volumio[3567]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 12 09:33:21 rivo volumio[3567]: 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 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:21 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:21 rivo sudo[15425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:33:21 rivo sudo[15425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:21 rivo volumio[3567]: info: Raat Daemon started successfully Oct 12 09:33:21 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:33:21 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:21 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:21 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:21 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:21 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:21 rivo volumio[3567]: info: Asound.conf file unchanged, so no further update is needed Oct 12 09:33:21 rivo volumio[3567]: info: Output device has changed, restarting MPD Oct 12 09:33:21 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:21.281+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:33:21 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:21.282+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:33:21 rivo sudo[15425]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:21 rivo sudo[15437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 09:33:21 rivo volumio[3567]: info: Output device has changed, restarting Shairport Sync Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:21 rivo sudo[15437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:21 rivo sudo[15437]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:21 rivo sudo[15439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:33:21 rivo sudo[15439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:21 rivo sudo[15440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 09:33:21 rivo sudo[15440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:21 rivo volumio[3567]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 09:33:21 rivo volumio[3567]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:21 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:21 rivo volumio[3567]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 09:33:21 rivo sudo[15450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:21 rivo sudo[15450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:21 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 09:33:21 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 09:33:21 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 09:33:21 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 09:33:21 rivo sudo[15452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:21 rivo sudo[15452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:21 rivo volumio[3567]: info: MPD Permissions set Oct 12 09:33:21 rivo volumio[3567]: info: Executing endpoint restartRAATSocket Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 09:33:21 rivo sudo[15459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:33:21 rivo sudo[15459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 09:33:21 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 09:33:21 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 09:33:21 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:21 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:21 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 09:33:21 rivo volumio[3567]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 12 09:33:21 rivo volumio[3567]: info: Preparing to save Alsa Options, stopping services first Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:21 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::volumioPause Oct 12 09:33:21 rivo volumio[3567]: info: CoreStateMachine::pause Oct 12 09:33:21 rivo volumio[3567]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Amanero Combo768"}} Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:21 rivo sudo[15439]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:21 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 09:33:21 rivo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 12 09:33:21 rivo systemd[1]: vtcs.service: Deactivated successfully. Oct 12 09:33:21 rivo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:21 rivo sudo[15450]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:21 rivo volumio[3567]: info: Setting mixer PCM for card Amanero Combo768 Oct 12 09:33:21 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:21 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:21 rivo volumio[3567]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 12 09:33:21 rivo sudo[15459]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:21 rivo sudo[15452]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:21 rivo sudo[15503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:21 rivo sudo[15503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:21 rivo sudo[15504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:33:21 rivo sudo[15504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 09:33:21 rivo volumio[3567]: info: Updating Volume Controller Parameters: Device: 5 Name: Amanero Combo768 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 12 09:33:21 rivo volumio[3567]: info: Disabling external Volume Control Oct 12 09:33:21 rivo volumio[3567]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 09:33:22 rivo volumio[3567]: info: Preparing to generate the ALSA configuration file Oct 12 09:33:22 rivo qobuz-connect[15391]: 20261012 09:33:22.047 [15391.15391] INFO SampleApp: Stopping Local configuration server Oct 12 09:33:22 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 09:33:22 rivo sudo[15512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:22 rivo sudo[15512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo sudo[15503]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:22 rivo sudo[15519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:33:22 rivo sudo[15519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: Not Reporting Auto name since its the default one Oct 12 09:33:22 rivo volumio[3567]: info: Starting Shairport Sync Oct 12 09:33:22 rivo volumio[3567]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 12 09:33:22 rivo volumio[3567]: info: Reading ALSA contributions from plugins. Oct 12 09:33:22 rivo volumio[3567]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 12 09:33:22 rivo volumio[3567]: info: VolumeController:: Starting alsactl monitor Oct 12 09:33:22 rivo sudo[15512]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:22 rivo sudo[15526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 09:33:22 rivo sudo[15526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo volumio[3567]: info: Raat Daemon started successfully Oct 12 09:33:22 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:33:22 rivo sudo[15519]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:22 rivo volumio[3567]: info: Executing endpoint restartRAATSocket Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 09:33:22 rivo volumio[3567]: info: VolumeController:: Volume=43 Mute =false Oct 12 09:33:22 rivo volumio[3567]: info: CoreStateMachine::pushState Oct 12 09:33:22 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::volumioPushState Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:22 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:22 rivo volumio[3567]: info: MRS: Pushing multiroomSync output update for this device Oct 12 09:33:22 rivo volumio[3567]: info: MRS: Pushing multiroomSync output Oct 12 09:33:22 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:22.264+11:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" state=STATUS_STOPPED positionMs=0 volume=43 Oct 12 09:33:22 rivo volumio5-onboarding[3950]: time=2026-10-12T09:33:22.265+11:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.20.16:45174 @ 0x315a2d0" id= title= Oct 12 09:33:22 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 12 09:33:22 rivo sudo[15486]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 09:33:22 rivo sudo[15486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:33:22 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 12 09:33:22 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 09:33:22 rivo sudo[15531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:33:22 rivo sudo[15531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 12 09:33:22 rivo sudo[15486]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:22 rivo sudo[15526]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:33:22 rivo sudo[15558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:33:22 rivo sudo[15558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: Not Reporting Auto name since its the default one Oct 12 09:33:22 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:22 rivo volumio[3567]: info: Shairport-Sync Started Oct 12 09:33:22 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:33:22 rivo sudo[15558]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:22 rivo volumio[3567]: 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 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 12 09:33:22 rivo sudo[15568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:33:22 rivo sudo[15568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 09:33:22 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 09:33:22 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 09:33:22 rivo volumio[3567]: info: Asound.conf file unchanged, so no further update is needed Oct 12 09:33:22 rivo volumio[3567]: info: Output device has changed, restarting MPD Oct 12 09:33:22 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 09:33:22 rivo sudo[15568]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:22 rivo sudo[15573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 12 09:33:22 rivo volumio[3567]: info: Output device has changed, restarting Shairport Sync Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:22 rivo sudo[15573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo sudo[15589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 09:33:22 rivo sudo[15589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo volumio[3567]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 09:33:22 rivo volumio[3567]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo volumio[3567]: info: QobuzConnect: setDeactiveState invoked Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::volumioGetState Oct 12 09:33:22 rivo sudo[15586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 09:33:22 rivo sudo[15586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:22 rivo volumio[3567]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:22 rivo sudo[15573]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:22 rivo sudo[15586]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:22 rivo sudo[15603]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 12 09:33:22 rivo sudo[15603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo sudo[15605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:22 rivo sudo[15605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo sudo[15606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 12 09:33:22 rivo sudo[15606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 12 09:33:22 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 12 09:33:22 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 12 09:33:22 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 12 09:33:22 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 12 09:33:22 rivo volumio[3567]: info: RAAT: Requesting Headphone Status Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 12 09:33:22 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 12 09:33:22 rivo volumio[3567]: info: MPD Permissions set Oct 12 09:33:22 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:33:22 rivo volumio[3567]: info: TidalConnect service stoped! Oct 12 09:33:22 rivo volumio[3567]: info: Raat Daemon started successfully Oct 12 09:33:22 rivo volumio[3567]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:33:22 rivo volumio[3567]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 12 09:33:22 rivo sudo[15614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 12 09:33:22 rivo sudo[15614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:22 rivo volumio[3567]: info: Executing endpoint restartRAATSocket Oct 12 09:33:22 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 12 09:33:22 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 12 09:33:22 rivo volumio[3567]: info: TidalConnect service started! Oct 12 09:33:23 rivo volumio[3567]: info: MCU Signalled Headphone Mode Disabled Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 12 09:33:23 rivo volumio[3567]: info: Reporting Headphone State: false Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: Updating RAAT Signal Path Oct 12 09:33:23 rivo sudo[15622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 12 09:33:23 rivo sudo[15622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 09:33:23 rivo qobuz-connect[15391]: 20261012 09:33:23.128 [15391.15391] INFO SampleApp: shat down connection on UNIX socket Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 09:33:23 rivo volumio[3567]: info: CorePlayQueue::getTrack 0 Oct 12 09:33:23 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 09:33:23 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 12 09:33:23 rivo sudo[15614]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:23 rivo volumio[3567]: info: CoreStateMachine::getcurrentVolume Oct 12 09:33:23 rivo volumio[3567]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 09:33:23 rivo sudo[15605]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:23 rivo sudo[15606]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:23 rivo sudo[15639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 12 09:33:23 rivo sudo[15639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 09:33:23 rivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 12 09:33:23 rivo volumio[3567]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 12 09:33:23 rivo volumio[3567]: Job for vtcs.service canceled. Oct 12 09:33:23 rivo volumio[3567]: {"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 12 09:33:23 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:33:23 rivo sudo[15504]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:23 rivo volumio[3567]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 09:33:23 rivo volumio[3567]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 12 09:33:23 rivo volumio[3567]: Job for vtcs.service canceled. Oct 12 09:33:23 rivo volumio[3567]: at ChildProcess.exithandler (node:child_process:421:12) Oct 12 09:33:23 rivo volumio[3567]: at ChildProcess.emit (node:events:514:28) Oct 12 09:33:23 rivo volumio[3567]: at maybeClose (node:internal/child_process:1105:16) Oct 12 09:33:23 rivo volumio[3567]: at Socket. (node:internal/child_process:457:11) Oct 12 09:33:23 rivo volumio[3567]: at Socket.emit (node:events:514:28) Oct 12 09:33:23 rivo volumio[3567]: at Pipe. (node:net:337:12) { Oct 12 09:33:23 rivo volumio[3567]: code: 1, Oct 12 09:33:23 rivo volumio[3567]: killed: false, Oct 12 09:33:23 rivo volumio[3567]: signal: null, Oct 12 09:33:23 rivo volumio[3567]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 12 09:33:23 rivo volumio[3567]: stdout: '', Oct 12 09:33:23 rivo volumio[3567]: stderr: 'Job for vtcs.service canceled.\n' Oct 12 09:33:23 rivo volumio[3567]: } Oct 12 09:33:23 rivo volumio[3567]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 09:33:23 rivo sudo[15531]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:23 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 12 09:33:23 rivo sudo[15622]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:23 rivo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 12 09:33:23 rivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 12 09:33:23 rivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 12 09:33:23 rivo sudo[15603]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:23 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 12 09:33:23 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 12 09:33:23 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:33:23 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 12 09:33:23 rivo sudo[15639]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:23 rivo sudo[15623]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 09:33:23 rivo sudo[15623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 12 09:33:23 rivo sudo[15623]: pam_unix(sudo:session): session closed for user root Oct 12 09:33:24 rivo sudo[15667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 09:32' Oct 12 09:33:24 rivo sudo[15667]: 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"