Oct 02 21:05:00 rivoplus kernel: usb 1-1.4.2: new high-speed USB device number 7 using xhci-hcd Oct 02 21:05:00 rivoplus kernel: usb 1-1.4.2: New USB device found, idVendor=16d0, idProduct=071a Oct 02 21:05:00 rivoplus kernel: usb 1-1.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 02 21:05:00 rivoplus kernel: usb 1-1.4.2: Product: Combo384 Amanero Oct 02 21:05:00 rivoplus kernel: usb 1-1.4.2: Manufacturer: Amanero Technologies Oct 02 21:05:00 rivoplus kernel: usb 1-1.4.2: SerialNumber: 413-001 Oct 02 21:05:00 rivoplus kernel: usb 1-1.4.2: Unsupported device Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:31 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:35 rivoplus volumio[3597]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 02 21:05:35 rivoplus volumio[3597]: info: Preparing to save Alsa Options, stopping services first Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:35 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPause Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreStateMachine::pause Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreStateMachine::stPlaybackTimer Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreStateMachine::servicePause Oct 02 21:05:35 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::servicePause Oct 02 21:05:35 rivoplus volumio[3597]: info: ControllerMpd::pause Oct 02 21:05:35 rivoplus volumio[3597]: verbose: ControllerMpd::sendMpdCommand pause Oct 02 21:05:35 rivoplus volumio[3597]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Combo384 Amanero"}} Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:05:35 rivoplus volumio[3597]: info: Setting mixer PCM for card Combo384 Amanero Oct 02 21:05:35 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:35 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:35 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 02 21:05:35 rivoplus vtcs[6343]: [2026-10-02 21:05:35.569] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 02 21:05:35 rivoplus vtcs[6343]: [2026-10-02 21:05:35.570] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 02 21:05:35 rivoplus sudo[7584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:05:35 rivoplus sudo[7584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 02 21:05:35 rivoplus volumio[3597]: info: Updating Volume Controller Parameters: Device: 1 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 02 21:05:35 rivoplus volumio[3597]: info: Disabling external Volume Control Oct 02 21:05:35 rivoplus volumio[3597]: info: VolumeController:: Starting alsactl monitor Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 21:05:35 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 02 21:05:35 rivoplus volumio[3597]: info: Preparing to generate the ALSA configuration file Oct 02 21:05:35 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 02 21:05:35 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 02 21:05:35 rivoplus sudo[7584]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:35 rivoplus sudo[7590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:05:35 rivoplus sudo[7590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:35 rivoplus volumio[3597]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 21:05:35 rivoplus volumio[3597]: info: Reading ALSA contributions from plugins. Oct 02 21:05:35 rivoplus volumio[3597]: info: Oct 02 21:05:35 rivoplus volumio[3597]: ---------------------------- MPD announces state update: player Oct 02 21:05:35 rivoplus volumio[3597]: info: sendMpdCommand pause took 296 milliseconds Oct 02 21:05:35 rivoplus volumio[3597]: info: ControllerMpd::getState Oct 02 21:05:35 rivoplus volumio[3597]: verbose: ControllerMpd::sendMpdCommand status Oct 02 21:05:35 rivoplus sudo[7597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:05:35 rivoplus sudo[7597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:35 rivoplus volumio[3597]: info: sendMpdCommand status took 23 milliseconds Oct 02 21:05:35 rivoplus volumio[3597]: verbose: ControllerMpd::parseState Oct 02 21:05:35 rivoplus volumio[3597]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 21:05:35 rivoplus volumio[3597]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 02 21:05:35 rivoplus volumio[3597]: verbose: ControllerMpd::parseTrackInfo Oct 02 21:05:35 rivoplus volumio[3597]: info: ControllerMpd::pushState Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::servicePushState Oct 02 21:05:35 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:35 rivoplus volumio[3597]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":68271,"duration":155,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Chopin: 24 Preludes, Op. 28: No. 2 in A Minor","artist":"Aimi Kobayashi","album":"Chopin: Preludes, Polonaise & Fantaisie","uri":"USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf","trackType":"dsf"} Oct 02 21:05:35 rivoplus volumio[3597]: verbose: CURRENT POSITION 1 Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreStateMachine::syncState stateService pause Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreStateMachine::syncState currentStatus pause Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:35 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:35 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:35 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:35 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreStateMachine::stPlaybackTimer Oct 02 21:05:35 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:35.851+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume=100 Oct 02 21:05:35 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:35.853+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:35 rivoplus volumio[3597]: info: ------------------------------ 91ms Oct 02 21:05:35 rivoplus sudo[7590]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:35 rivoplus sudo[7597]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:35 rivoplus sudo[7608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:05:35 rivoplus sudo[7608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:35 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 02 21:05:36 rivoplus qobuz-connect[6325]: 20261002 21:05:36.011 [6325.6325] INFO SampleApp: Stopping Local configuration server Oct 02 21:05:36 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 02 21:05:36 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:36 rivoplus volumio[3597]: info: VolumeController:: Volume=0 Mute =false Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:36 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:36.060+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume= Oct 02 21:05:36 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:36.060+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:05:36 rivoplus volumio[3597]: info: Asound.conf file written Oct 02 21:05:36 rivoplus sudo[7624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:05:36 rivoplus sudo[7624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus sudo[7626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 21:05:36 rivoplus sudo[7626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus sudo[7624]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:36 rivoplus sudo[7626]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:36 rivoplus sudo[7629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:05:36 rivoplus sudo[7629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 02 21:05:36 rivoplus kernel: Err: can not set rate to 4282967296! Oct 02 21:05:36 rivoplus kernel: Range[3920000 - 500000000] Oct 02 21:05:36 rivoplus kernel: out of value, fixed it Oct 02 21:05:36 rivoplus kernel: id=0 set inskew=0 Oct 02 21:05:36 rivoplus volumio[3597]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 21:05:36 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 02 21:05:36 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 02 21:05:36 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 02 21:05:36 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 02 21:05:36 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Combo384 Amanero Validity:0' : Operation not permitted Oct 02 21:05:36 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 02 21:05:36 rivoplus volumio[3597]: info: Output device has changed, restarting MPD Oct 02 21:05:36 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 21:05:36 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 21:05:36 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 21:05:36 rivoplus volumio[3597]: info: Output device has changed, restarting Shairport Sync Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:05:36 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 21:05:36 rivoplus sudo[7634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:05:36 rivoplus sudo[7634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus sudo[7629]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:36 rivoplus sudo[7634]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:36 rivoplus sudo[7636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:05:36 rivoplus sudo[7636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus volumio[3597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: Output device has changed, restarting MPD Oct 02 21:05:36 rivoplus systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 02 21:05:36 rivoplus sudo[7661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:05:36 rivoplus sudo[7661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus sudo[7662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:05:36 rivoplus sudo[7662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus volumio[3597]: info: Output device has changed, restarting Shairport Sync Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:05:36 rivoplus sudo[7662]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:36 rivoplus sudo[7666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:05:36 rivoplus sudo[7666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus volumio[3597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:05:36 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:05:36 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:36 rivoplus sudo[7661]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:36 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:05:36 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:05:36 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:05:36 rivoplus sudo[7674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:05:36 rivoplus sudo[7674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:05:36 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:05:36 rivoplus sudo[7678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:05:36 rivoplus sudo[7678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus sudo[7681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:05:36 rivoplus sudo[7681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus sudo[7690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:05:36 rivoplus sudo[7690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus volumio[3597]: info: VolumeController::SetAlsaVolume0 Oct 02 21:05:36 rivoplus sudo[7674]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:36 rivoplus sudo[7696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:05:36 rivoplus sudo[7696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:36 rivoplus sudo[7690]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:36 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:36.780+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume=0 Oct 02 21:05:36 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:36.781+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:36 rivoplus volumio[3597]: info: VolumeController::SetAlsaVolume0 Oct 02 21:05:36 rivoplus sudo[7700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:05:36 rivoplus sudo[7700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:36 rivoplus sudo[7678]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:36 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:36.843+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume=0 Oct 02 21:05:36 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:36.845+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:36 rivoplus sudo[7681]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:36 rivoplus volumio[3597]: info: MPD Permissions set Oct 02 21:05:36 rivoplus volumio[3597]: info: MPD Permissions set Oct 02 21:05:36 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:05:36 rivoplus volumio[3597]: info: MCU Signalled Playback Inactive Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:36 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:36 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:36 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:36 rivoplus sudo[7696]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:36 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:36.932+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume=100 Oct 02 21:05:36 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:36.933+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:36 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:36.933+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume=100 Oct 02 21:05:36 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:36.934+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:36 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:36 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:36 rivoplus sudo[7716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:05:36 rivoplus sudo[7716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:05:37 rivoplus volumio[3597]: info: Executing endpoint restartRAATSocket Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:05:37 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:05:37 rivoplus sudo[7683]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 21:05:37 rivoplus sudo[7683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 21:05:37 rivoplus sudo[7683]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:37 rivoplus volumio[3597]: info: Starting Shairport Sync Oct 02 21:05:37 rivoplus volumio[3597]: info: Starting Shairport Sync Oct 02 21:05:37 rivoplus volumio[3597]: 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 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:05:37 rivoplus sudo[7750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:05:37 rivoplus sudo[7750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus sudo[7752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:05:37 rivoplus sudo[7752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus sudo[7754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:05:37 rivoplus sudo[7754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus volumio[3597]: info: Preparing to generate the ALSA configuration file Oct 02 21:05:37 rivoplus sudo[7750]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:37 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:05:37 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:05:37 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:05:37 rivoplus sudo[7761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:05:37 rivoplus sudo[7761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus volumio[3597]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 21:05:37 rivoplus volumio[3597]: info: Reading ALSA contributions from plugins. Oct 02 21:05:37 rivoplus sudo[7762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:05:37 rivoplus sudo[7762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus volumio[3597]: info: Asound.conf file written Oct 02 21:05:37 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:05:37 rivoplus sudo[7752]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:37 rivoplus sudo[7767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 21:05:37 rivoplus sudo[7767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus sudo[7767]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:37 rivoplus sudo[7761]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:37 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 21:05:37 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:05:37 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 02 21:05:37 rivoplus kernel: Err: can not set rate to 4278967296! Oct 02 21:05:37 rivoplus kernel: Range[3920000 - 500000000] Oct 02 21:05:37 rivoplus kernel: out of value, fixed it Oct 02 21:05:37 rivoplus kernel: id=0 set inskew=0 Oct 02 21:05:37 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 21:05:37 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 21:05:37 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:05:37 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:05:37 rivoplus sudo[7770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:05:37 rivoplus sudo[7770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus volumio[3597]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 21:05:37 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 02 21:05:37 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 02 21:05:37 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 02 21:05:37 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 02 21:05:37 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Combo384 Amanero Validity:0' : Operation not permitted Oct 02 21:05:37 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 02 21:05:37 rivoplus volumio[3597]: info: Output device has changed, restarting MPD Oct 02 21:05:37 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 21:05:37 rivoplus sudo[7762]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:37 rivoplus volumio[3597]: info: Output device has changed, restarting Shairport Sync Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:05:37 rivoplus sudo[7777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:05:37 rivoplus sudo[7777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus sudo[7777]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:37 rivoplus sudo[7779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:05:37 rivoplus sudo[7779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus volumio[3597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:05:37 rivoplus volumio[3597]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:05:37 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:37 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:37 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: Preparing to generate the ALSA configuration file Oct 02 21:05:37 rivoplus sudo[7754]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:37 rivoplus sudo[7796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:05:37 rivoplus sudo[7796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus qobuz-connect[6325]: 20261002 21:05:37.732 [6325.6325] INFO SampleApp: shat down connection on UNIX socket Oct 02 21:05:37 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 21:05:37 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:05:37 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 21:05:37 rivoplus sudo[7813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:05:37 rivoplus sudo[7813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:05:37 rivoplus volumio[3597]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 21:05:37 rivoplus volumio[3597]: info: Reading ALSA contributions from plugins. Oct 02 21:05:37 rivoplus sudo[7700]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:37 rivoplus sudo[7716]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:37 rivoplus sudo[7608]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:37 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:05:37 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:05:37 rivoplus volumio[3597]: info: MPD Permissions set Oct 02 21:05:37 rivoplus volumio[3597]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 02 21:05:37 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:37 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 21:05:37 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:37 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 21:05:37 rivoplus volumio[3597]: info: Shairport-Sync Started Oct 02 21:05:37 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:05:37 rivoplus volumio[3597]: info: Shairport-Sync Started Oct 02 21:05:37 rivoplus sudo[7820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:05:37 rivoplus sudo[7820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:37 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:37.903+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume=100 Oct 02 21:05:37 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:37.917+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:37 rivoplus volumio[3597]: (node:3597) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 02 21:05:37 rivoplus volumio[3597]: (node:3597) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 02 21:05:37 rivoplus volumio[3597]: (node:3597) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 02 21:05:37 rivoplus volumio[3597]: (node:3597) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 02 21:05:37 rivoplus volumio[3597]: (node:3597) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:37 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:37 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:37 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:37 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:37 rivoplus volumio[3597]: info: Executing endpoint restartRAATSocket Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:05:37 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus sudo[7820]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:05:38 rivoplus volumio[3597]: info: Executing endpoint qc_getconfig Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 21:05:38 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:05:38 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:05:38 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:05:38 rivoplus sudo[7830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:05:38 rivoplus volumio[3597]: info: Starting Shairport Sync Oct 02 21:05:38 rivoplus volumio[3597]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 02 21:05:38 rivoplus volumio[3597]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 02 21:05:38 rivoplus qobuz-connect[7818]: 20261002 21:05:38.044 [7818.7818] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 21:05:38 rivoplus qobuz-connect[7818]: 20261002 21:05:38.050 [7818.7818] INFO VolumeManager: [0xaaf73ac8]: Setting new playback volume: 75 Oct 02 21:05:38 rivoplus qobuz-connect[7818]: 20261002 21:05:38.050 [7818.7818] INFO VolumeManager: [0xaaf73ac8]: Setting new mute state: 0 Oct 02 21:05:38 rivoplus qobuz-connect[7818]: 20261002 21:05:38.050 [7818.7818] INFO AudioStreamManager: [0xaaf73820]: Setting new audio download buffer size: 1048576 Oct 02 21:05:38 rivoplus qobuz-connect[7818]: 20261002 21:05:38.050 [7818.7818] INFO QobuzConnect: [0xaaf74390]: Client initialized! Oct 02 21:05:38 rivoplus qobuz-connect[7818]: 20261002 21:05:38.050 [7818.7818] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 02 21:05:38 rivoplus sudo[7830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus volumio[3597]: info: Asound.conf file unchanged, so no further update is needed Oct 02 21:05:38 rivoplus volumio[3597]: info: Output device has changed, restarting MPD Oct 02 21:05:38 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 21:05:38 rivoplus sudo[7770]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus qobuz-connect[7818]: 20261002 21:05:38.097 [7818.7818] INFO LocalConfigManager: [0xaaf73548]: Starting Local Configuration server Oct 02 21:05:38 rivoplus qobuz-connect[7818]: 20261002 21:05:38.097 [7818.7818] INFO SampleApp: Starting Local configuration server Oct 02 21:05:38 rivoplus qobuz-connect[7818]: 20261002 21:05:38.097 [7818.7818] INFO SampleApp: Connected to UNIX socket client 0xaaf5e908 Oct 02 21:05:38 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:05:38 rivoplus sudo[7837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:05:38 rivoplus sudo[7837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus sudo[7845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:05:38 rivoplus sudo[7845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus volumio[3597]: info: Output device has changed, restarting Shairport Sync Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:05:38 rivoplus sudo[7845]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus qobuz-connect[7818]: 20261002 21:05:38.193 [7818.7818] INFO SampleApp: Playback volume changed: 75 Oct 02 21:05:38 rivoplus sudo[7855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:05:38 rivoplus sudo[7855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus volumio[3597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:05:38 rivoplus volumio[3597]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:38 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:38 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus sudo[7796]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus sudo[7813]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus sudo[7864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:05:38 rivoplus sudo[7864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus sudo[7867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:05:38 rivoplus sudo[7867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:05:38 rivoplus sudo[7875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:05:38 rivoplus sudo[7875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:38 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:38 rivoplus volumio[3597]: info: MPD Permissions set Oct 02 21:05:38 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:38 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:38 rivoplus qobuz-connect[7818]: 20261002 21:05:38.435 [7818.7818] INFO SampleApp: Stopping Local configuration server Oct 02 21:05:38 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 21:05:38 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:05:38 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:38.448+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume=100 Oct 02 21:05:38 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:38.448+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:38 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:38 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:38 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:38 rivoplus volumio[3597]: info: Executing endpoint restartRAATSocket Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 21:05:38 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:05:38 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:05:38 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:05:38 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:05:38 rivoplus sudo[7867]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:38 rivoplus sudo[7864]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus volumio[3597]: 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 02 21:05:38 rivoplus volumio[3597]: info: Starting Shairport Sync Oct 02 21:05:38 rivoplus sudo[7891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:05:38 rivoplus sudo[7891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:05:38 rivoplus sudo[7875]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:05:38 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:05:38 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:05:38 rivoplus sudo[7894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:05:38 rivoplus sudo[7894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus sudo[7896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:05:38 rivoplus sudo[7896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:05:38 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:05:38 rivoplus sudo[7837]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus volumio[3597]: info: Shairport-Sync Started Oct 02 21:05:38 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:05:38 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:05:38 rivoplus sudo[7891]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:05:38 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:05:38 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:05:38 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:05:38 rivoplus sudo[7906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:05:38 rivoplus sudo[7906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus sudo[7894]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:05:38 rivoplus volumio[3597]: info: Shairport-Sync Started Oct 02 21:05:38 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 21:05:38 rivoplus sudo[7914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:05:38 rivoplus sudo[7914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:38 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 21:05:38 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 21:05:38 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:05:38 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 21:05:38 rivoplus sudo[7906]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:38 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:05:38 rivoplus sudo[7914]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:39 rivoplus sudo[7938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:05:39 rivoplus sudo[7938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:39 rivoplus volumio[3597]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:05:39 rivoplus volumio[3597]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:05:39 rivoplus volumio[3597]: info: Executing endpoint restartRAATSocket Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 21:05:39 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus sudo[7950]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 21:05:39 rivoplus volumio[3597]: 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 02 21:05:39 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 21:05:39 rivoplus sudo[7950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:39 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 21:05:39 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 21:05:39 rivoplus volumio[3597]: info: RAAT: Requesting Headphone Status Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 21:05:39 rivoplus sudo[7938]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:39 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:05:39 rivoplus volumio[3597]: info: MCU Signalled Headphone Mode Disabled Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 21:05:39 rivoplus volumio[3597]: info: Reporting Headphone State: false Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:39 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:39 rivoplus volumio[3597]: info: Executing endpoint restartRAATSocket Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 21:05:39 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus volumio[3597]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 02 21:05:39 rivoplus sudo[7950]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:39 rivoplus sudo[7900]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 21:05:39 rivoplus sudo[7900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 21:05:39 rivoplus sudo[7900]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:39 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreStateMachine::getcurrentVolume Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 21:05:39 rivoplus volumio[3597]: info: VolumeController:: Volume=0 Mute =false Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:39 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreStateMachine::updateTrackBlock Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrackBlock Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 21:05:39 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:39.759+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume=0 Oct 02 21:05:39 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:39.760+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:39 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:39 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:05:39 rivoplus volumio[3597]: info: Executing endpoint tc_getconfig Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 02 21:05:39 rivoplus vtcs[7964]: STARTING TidalConnect services, version: 1.6.1 Oct 02 21:05:39 rivoplus vtcs[7964]: STARTED TidalConnect services. Oct 02 21:05:39 rivoplus volumio[3597]: info: Executing endpoint tc_connect Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 02 21:05:39 rivoplus volumio[3597]: info: Connecting to TidalConnect Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::servicePushState Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:39 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus volumio[3597]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::servicePushState Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:39 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus volumio[3597]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 02 21:05:39 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:39.873+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume=0 Oct 02 21:05:39 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:39.873+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:39 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:39.877+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume=0 Oct 02 21:05:39 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:39.877+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:39 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:05:39 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:39 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:39 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:05:39 rivoplus volumio[3597]: info: VolumeController:: Volume=0 Mute =false Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:05:39 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:39 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:39 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:05:39 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:05:39 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:39.975+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=70935 volume=0 Oct 02 21:05:39 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:39.976+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:63558,00:00:00:00:00:00%03 @ 0x2400c60" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:05:40 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:40 rivoplus volumio[3597]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:05:40 rivoplus volumio[3597]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:05:40 rivoplus volumio[3597]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:05:40 rivoplus volumio[3597]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:05:40 rivoplus sudo[8004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 21:05:40 rivoplus sudo[8004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:40 rivoplus sudo[8006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 21:05:40 rivoplus sudo[8006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:40 rivoplus qobuz-connect[7818]: 20261002 21:05:40.107 [7818.7818] INFO SampleApp: shat down connection on UNIX socket Oct 02 21:05:40 rivoplus volumio[3597]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 02 21:05:40 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:40 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:40 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 21:05:40 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:05:40 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:05:40 rivoplus sudo[7830]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:40 rivoplus sudo[7896]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:40 rivoplus volumio[3597]: info: RAAT: Requesting Headphone Status Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 21:05:40 rivoplus volumio[3597]: info: MCU Signalled Headphone Mode Disabled Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 21:05:40 rivoplus volumio[3597]: info: Reporting Headphone State: false Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:40 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:40 rivoplus sudo[8004]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:40 rivoplus sudo[8006]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:40 rivoplus volumio[3597]: info: Executing endpoint qc_getconfig Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 21:05:40 rivoplus qobuz-connect[8009]: 20261002 21:05:40.316 [8009.8009] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 21:05:40 rivoplus volumio[3597]: info: RAAT: Requesting Headphone Status Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 21:05:40 rivoplus qobuz-connect[8009]: 20261002 21:05:40.325 [8009.8009] INFO VolumeManager: [0xab467ac8]: Setting new playback volume: 75 Oct 02 21:05:40 rivoplus qobuz-connect[8009]: 20261002 21:05:40.325 [8009.8009] INFO VolumeManager: [0xab467ac8]: Setting new mute state: 0 Oct 02 21:05:40 rivoplus qobuz-connect[8009]: 20261002 21:05:40.325 [8009.8009] INFO AudioStreamManager: [0xab467820]: Setting new audio download buffer size: 1048576 Oct 02 21:05:40 rivoplus qobuz-connect[8009]: 20261002 21:05:40.325 [8009.8009] INFO QobuzConnect: [0xab468390]: Client initialized! Oct 02 21:05:40 rivoplus qobuz-connect[8009]: 20261002 21:05:40.325 [8009.8009] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 02 21:05:40 rivoplus volumio[3597]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 02 21:05:40 rivoplus volumio[3597]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 02 21:05:40 rivoplus qobuz-connect[8009]: 20261002 21:05:40.343 [8009.8009] INFO LocalConfigManager: [0xab467548]: Starting Local Configuration server Oct 02 21:05:40 rivoplus qobuz-connect[8009]: 20261002 21:05:40.343 [8009.8009] INFO SampleApp: Starting Local configuration server Oct 02 21:05:40 rivoplus qobuz-connect[8009]: 20261002 21:05:40.344 [8009.8009] INFO SampleApp: Connected to UNIX socket client 0xab452908 Oct 02 21:05:40 rivoplus volumio[3597]: info: MCU Signalled Headphone Mode Disabled Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 21:05:40 rivoplus volumio[3597]: info: Reporting Headphone State: false Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:40 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:40 rivoplus qobuz-connect[8009]: 20261002 21:05:40.466 [8009.8009] INFO SampleApp: Playback volume changed: 75 Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:40 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:40 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:41 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:05:41 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:05:41 rivoplus volumio[3597]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:05:41 rivoplus volumio[3597]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:05:41 rivoplus sudo[8025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 21:05:41 rivoplus sudo[8025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:41 rivoplus sudo[8025]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:41 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:05:41 rivoplus volumio[3597]: info: RAAT: Requesting Headphone Status Oct 02 21:05:41 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 21:05:41 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:05:41 rivoplus volumio[3597]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:05:41 rivoplus volumio[3597]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:05:41 rivoplus volumio[3597]: info: MCU Signalled Headphone Mode Disabled Oct 02 21:05:41 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 21:05:41 rivoplus volumio[3597]: info: Reporting Headphone State: false Oct 02 21:05:41 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:41 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:41 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:41 rivoplus sudo[8037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 21:05:41 rivoplus sudo[8037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:05:41 rivoplus sudo[8037]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:41 rivoplus volumio[3597]: info: RAAT: Requesting Headphone Status Oct 02 21:05:41 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 21:05:41 rivoplus volumio[3597]: info: MCU Signalled Headphone Mode Disabled Oct 02 21:05:41 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 21:05:41 rivoplus volumio[3597]: info: Reporting Headphone State: false Oct 02 21:05:41 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:41 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:05:41 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:05:42 rivoplus volumio[3597]: info: TidalConnect service started! Oct 02 21:05:42 rivoplus mpd[7967]: 2026-10-02T21:05:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 21:05:42 rivoplus systemd[1]: Started mpd.service - Music Player Daemon. Oct 02 21:05:42 rivoplus sudo[7855]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:42 rivoplus sudo[7666]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:42 rivoplus sudo[7779]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:42 rivoplus sudo[7636]: pam_unix(sudo:session): session closed for user root Oct 02 21:05:42 rivoplus volumio[3597]: error: MPD error: The expression evaluated to a falsy value: Oct 02 21:05:42 rivoplus volumio[3597]: assert.ok(self.idling) Oct 02 21:05:42 rivoplus volumio[3597]: error: The expression evaluated to a falsy value: Oct 02 21:05:42 rivoplus volumio[3597]: assert.ok(self.idling) Oct 02 21:05:42 rivoplus volumio[3597]: error: MPD error: The expression evaluated to a falsy value: Oct 02 21:05:42 rivoplus volumio[3597]: assert.ok(self.idling) Oct 02 21:05:42 rivoplus volumio[3597]: error: The expression evaluated to a falsy value: Oct 02 21:05:42 rivoplus volumio[3597]: assert.ok(self.idling) Oct 02 21:05:42 rivoplus volumio[3597]: error: MPD error: The expression evaluated to a falsy value: Oct 02 21:05:42 rivoplus volumio[3597]: assert.ok(self.idling) Oct 02 21:05:42 rivoplus volumio[3597]: error: The expression evaluated to a falsy value: Oct 02 21:05:42 rivoplus volumio[3597]: assert.ok(self.idling) Oct 02 21:05:42 rivoplus volumio[3597]: error: updateQueue error: null Oct 02 21:05:43 rivoplus volumio[3597]: info: TidalConnect service started! Oct 02 21:05:43 rivoplus volumio[3597]: info: TidalConnect service started! Oct 02 21:05:44 rivoplus volumio[3597]: info: TidalConnect service started! Oct 02 21:05:44 rivoplus volumio[3597]: info: TidalConnect service started! Oct 02 21:05:49 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:49 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:49 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 02 21:05:49 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPlay Oct 02 21:05:49 rivoplus volumio[3597]: info: CoreStateMachine::play index undefined Oct 02 21:05:49 rivoplus volumio[3597]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 21:05:49 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:49 rivoplus volumio[3597]: info: CoreStateMachine::startPlaybackTimer Oct 02 21:05:49 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:49 rivoplus volumio[3597]: info: ControllerMpd::resume Oct 02 21:05:49 rivoplus volumio[3597]: verbose: ControllerMpd::sendMpdCommand play Oct 02 21:05:49 rivoplus volumio[3597]: info: sendMpdCommand play took 3 milliseconds Oct 02 21:05:51 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:51 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:51 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 02 21:05:51 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPlay Oct 02 21:05:51 rivoplus volumio[3597]: info: CoreStateMachine::play index undefined Oct 02 21:05:51 rivoplus volumio[3597]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 21:05:51 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:51 rivoplus volumio[3597]: info: CoreStateMachine::startPlaybackTimer Oct 02 21:05:51 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:51 rivoplus volumio[3597]: info: ControllerMpd::resume Oct 02 21:05:51 rivoplus volumio[3597]: verbose: ControllerMpd::sendMpdCommand play Oct 02 21:05:51 rivoplus volumio[3597]: info: sendMpdCommand play took 4 milliseconds Oct 02 21:05:54 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:54 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:54 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 02 21:05:54 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPlay Oct 02 21:05:54 rivoplus volumio[3597]: info: CoreStateMachine::play index undefined Oct 02 21:05:54 rivoplus volumio[3597]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 21:05:54 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:54 rivoplus volumio[3597]: info: CoreStateMachine::startPlaybackTimer Oct 02 21:05:54 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:54 rivoplus volumio[3597]: info: ControllerMpd::resume Oct 02 21:05:54 rivoplus volumio[3597]: verbose: ControllerMpd::sendMpdCommand play Oct 02 21:05:54 rivoplus volumio[3597]: info: sendMpdCommand play took 3 milliseconds Oct 02 21:05:56 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:56.825+09:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.104.158:63558 error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 02 21:05:56 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:56.826+09:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.104.158:63558 Oct 02 21:05:56 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:56.826+09:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.104.158:63558 Oct 02 21:05:56 rivoplus dbus-daemon[2939]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.33" (uid=0 pid=4761 comm="/usr/bin/volumio5-onboarding" label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3474 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b" label="kernel") Oct 02 21:05:58 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:58.962+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.104.158:64116 Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.002+09:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.104.158:64116 @ 0x2825860" latency=15.596131ms platform=PLATFORM_IOS version=6.260915.0 Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.003+09:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.104.158:64116 @ 0x2825860" latency=15.565849ms timeout=20s included=[] excluded=[] Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.004+09:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" Oct 02 21:05:59 rivoplus volumio[3597]: info: Received Get System Info Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:05:59 rivoplus volumio[3597]: info: Discovery: Getting this device information Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:59 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.016+09:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" name=Rivoplus Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.018+09:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" language=ja Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.020+09:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" timezone=Asia/Tokyo Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.023+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" available=true connected=true macAddress=02:00:00:03:13:01 ip4Address=192.168.104.174/24 ip6Address= Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.031+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.032+09:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" setupComplete=true Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:05:59 rivoplus volumio[3597]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Oct 02 21:05:59 rivoplus volumio[3597]: Card sysdefault:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Oct 02 21:05:59 rivoplus volumio[3597]: amixer -c 1 info | grep "Combo384 Amanero" Oct 02 21:05:59 rivoplus volumio[3597]: Card sysdefault:1 'Amanero'/'Amanero Technologies Combo384 Amanero at usb-xhci-hcd.0.auto-1.4.2, high speed' Oct 02 21:05:59 rivoplus volumio[3597]: amixer -c 1 info | grep "Combo384 Amanero" Oct 02 21:05:59 rivoplus volumio[3597]: Card sysdefault:1 'Amanero'/'Amanero Technologies Combo384 Amanero at usb-xhci-hcd.0.auto-1.4.2, high speed' Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.287+09:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" selectedOutputId=1 Oct 02 21:05:59 rivoplus volumio[3597]: info: Received Get System Info Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:05:59 rivoplus volumio[3597]: info: Discovery: Getting this device information Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:59 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.310+09:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" currentVersion=4.208 latestVersion=4.208 Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.311+09:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.104.158:64116 @ 0x2825860" status=UPDATE_STATUS_NONE progress=0 Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.311+09:00 level=INFO msg="emitting user changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" userId=Iu4KkO3EHDYNDeVEWasQvOfkozf2 Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.311+09:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" providers=9 Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.311+09:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" plugins=25 Oct 02 21:05:59 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:05:59 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.317+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" state=STATUS_PAUSED positionMs=80847 volume=0 Oct 02 21:05:59 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:05:59.317+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:64116 @ 0x2825860" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:06:00 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:06:00 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:06:00 rivoplus volumio[3597]: info: Discovery: Getting this device information Oct 02 21:06:00 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:00 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:00 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:06:00 rivoplus volumio[3597]: verbose: New Socket.io Connection to 192.168.104.174:3000 from 192.168.104.158 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 02 21:06:00 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 21:06:00 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.036+09:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.104.158:64116 @ 0x2825860" latency=20.927838ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.063+09:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.276+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=213.185446ms Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.428+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=https://www.googleapis.com duration=363.038706ms Oct 02 21:06:01 rivoplus sudo[8102]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 21:06:01 rivoplus sudo[8102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:01 rivoplus sudo[8102]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:01 rivoplus sudo[8104]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 21:06:01 rivoplus sudo[8104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:01 rivoplus sudo[8104]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.547+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=481.896504ms Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.553+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=https://securetoken.googleapis.com duration=486.066919ms Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.556+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=https://google.com duration=492.323562ms Oct 02 21:06:01 rivoplus volumio[3597]: verbose: New Socket.io Connection to 192.168.104.174 from 192.168.104.158 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.574+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=https://functions.volumio.cloud duration=508.742759ms Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.575+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=https://functions.volumio.cloud duration=510.223481ms Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.631+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=566.656728ms Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.648+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=http://plugins.volumio.org duration=583.529638ms Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.684+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=http://pushupdates.volumio.org duration=618.622639ms Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.699+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=633.879409ms Oct 02 21:06:01 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:01.714+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=https://database.volumio.cloud duration=649.802894ms Oct 02 21:06:01 rivoplus sudo[8111]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 21:06:01 rivoplus sudo[8111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:01 rivoplus sudo[8112]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 21:06:01 rivoplus sudo[8112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:01 rivoplus sudo[8112]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:01 rivoplus sudo[8111]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:01 rivoplus volumio[3597]: verbose: New Socket.io Connection to 192.168.104.174 from 192.168.104.158 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:01 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 21:06:01 rivoplus volumio[3597]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 02 21:06:01 rivoplus volumio[3597]: info: Received Get System Info Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:06:01 rivoplus volumio[3597]: info: Discovery: Getting this device information Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:01 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:01 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:01 rivoplus volumio[3597]: info: Listing playlists Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 21:06:01 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 21:06:02 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:02.019+09:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.104.158:64116 @ 0x2825860" latency=12.124583ms timeout=10s endpoint=http://cddb.volumio.org duration=954.774922ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:03 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPlay Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreStateMachine::play index undefined Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 21:06:03 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreStateMachine::startPlaybackTimer Oct 02 21:06:03 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:03 rivoplus volumio[3597]: info: ControllerMpd::resume Oct 02 21:06:03 rivoplus volumio[3597]: verbose: ControllerMpd::sendMpdCommand play Oct 02 21:06:03 rivoplus volumio[3597]: info: sendMpdCommand play took 1 milliseconds Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 21:06:03 rivoplus volumio[3597]: info: Received Get System Info Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:06:03 rivoplus volumio[3597]: info: Discovery: Getting this device information Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:03 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:03 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:06:04 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:04 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:04 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 02 21:06:04 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPlay Oct 02 21:06:04 rivoplus volumio[3597]: info: CoreStateMachine::play index undefined Oct 02 21:06:04 rivoplus volumio[3597]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 21:06:04 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:04 rivoplus volumio[3597]: info: CoreStateMachine::startPlaybackTimer Oct 02 21:06:04 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:04 rivoplus volumio[3597]: info: ControllerMpd::resume Oct 02 21:06:04 rivoplus volumio[3597]: verbose: ControllerMpd::sendMpdCommand play Oct 02 21:06:04 rivoplus volumio[3597]: info: sendMpdCommand play took 0 milliseconds Oct 02 21:06:05 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 21:06:05 rivoplus volumio[3597]: info: Received Get System Info Oct 02 21:06:05 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:06:05 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:06:05 rivoplus volumio[3597]: info: Discovery: Getting this device information Oct 02 21:06:05 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:05 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:05 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:06 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPlay Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreStateMachine::play index undefined Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 21:06:06 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreStateMachine::startPlaybackTimer Oct 02 21:06:06 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:06 rivoplus volumio[3597]: info: ControllerMpd::resume Oct 02 21:06:06 rivoplus volumio[3597]: verbose: ControllerMpd::sendMpdCommand play Oct 02 21:06:06 rivoplus volumio[3597]: info: sendMpdCommand play took 3 milliseconds Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:06 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPlay Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreStateMachine::play index undefined Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 21:06:06 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:06 rivoplus volumio[3597]: info: CoreStateMachine::startPlaybackTimer Oct 02 21:06:06 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:06 rivoplus volumio[3597]: info: ControllerMpd::resume Oct 02 21:06:06 rivoplus volumio[3597]: verbose: ControllerMpd::sendMpdCommand play Oct 02 21:06:06 rivoplus volumio[3597]: info: sendMpdCommand play took 1 milliseconds Oct 02 21:06:07 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:07.785+09:00 level=INFO msg="new address was allocated" component=ble/conn old=4 new=5 Oct 02 21:06:08 rivoplus dbus-daemon[2939]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.33" (uid=0 pid=4761 comm="/usr/bin/volumio5-onboarding" label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3474 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b" label="kernel") Oct 02 21:06:11 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:13 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:20 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 21:06:20 rivoplus volumio[3597]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 02 21:06:20 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 02 21:06:20 rivoplus volumio[3597]: info: Received Get System Version Oct 02 21:06:20 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 21:06:20 rivoplus volumio[3597]: info: Received Get System Info Oct 02 21:06:20 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 21:06:20 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 21:06:20 rivoplus volumio[3597]: info: Discovery: Getting this device information Oct 02 21:06:20 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:20 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:20 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 21:06:24 rivoplus volumio[3597]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 02 21:06:24 rivoplus volumio[3597]: info: Preparing to save Alsa Options, stopping services first Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:24 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPause Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreStateMachine::pause Oct 02 21:06:24 rivoplus volumio[3597]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"S/PDIF + AES/EBU + I2S"}} Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:06:24 rivoplus volumio[3597]: info: Found match in Cards Database for ignoring default Mixer Oct 02 21:06:24 rivoplus volumio[3597]: info: Setting mixer PCM for card S/PDIF + AES/EBU + I2S Oct 02 21:06:24 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:24 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:24 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 02 21:06:24 rivoplus vtcs[7964]: [2026-10-02 21:06:24.909] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 02 21:06:24 rivoplus vtcs[7964]: [2026-10-02 21:06:24.910] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 02 21:06:24 rivoplus volumio[3597]: info: Ignoring Mixers Options Oct 02 21:06:24 rivoplus volumio[3597]: info: Device 5 does not have any Mixer Control Available Oct 02 21:06:24 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:24 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:24 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 02 21:06:24 rivoplus sudo[8167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:24 rivoplus sudo[8166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:24 rivoplus sudo[8166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:24 rivoplus sudo[8167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 02 21:06:24 rivoplus volumio[3597]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 02 21:06:24 rivoplus volumio[3597]: info: Disabling external Volume Control Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:06:24 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:06:24 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:25 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:25 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:06:25 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:06:25 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 02 21:06:25 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:25.020+09:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03 @ 0x2400c60" state=STATUS_PAUSED positionMs=106018 volume=100 Oct 02 21:06:25 rivoplus volumio[3597]: info: Preparing to generate the ALSA configuration file Oct 02 21:06:25 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 02 21:06:25 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 02 21:06:25 rivoplus sudo[8167]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:25 rivoplus sudo[8166]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:25 rivoplus sudo[8176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:25 rivoplus sudo[8176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus sudo[8178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:25 rivoplus sudo[8178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus sudo[8186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:06:25 rivoplus sudo[8186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus volumio[3597]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 21:06:25 rivoplus volumio[3597]: info: Reading ALSA contributions from plugins. Oct 02 21:06:25 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:06:25 rivoplus volumio[3597]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 02 21:06:25 rivoplus sudo[8190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:06:25 rivoplus sudo[8190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus sudo[8176]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:25 rivoplus sudo[8178]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:06:25 rivoplus sudo[8186]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:25 rivoplus sudo[8190]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:25 rivoplus sudo[8199]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:06:25 rivoplus sudo[8199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:25 rivoplus sudo[8200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:06:25 rivoplus sudo[8200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus qobuz-connect[8009]: 20261002 21:06:25.398 [8009.8009] INFO SampleApp: Stopping Local configuration server Oct 02 21:06:25 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:06:25 rivoplus volumio[3597]: info: Asound.conf file written Oct 02 21:06:25 rivoplus sudo[8212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 21:06:25 rivoplus sudo[8212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus sudo[8212]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:25 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 02 21:06:25 rivoplus kernel: Err: can not set rate to 4274967296! Oct 02 21:06:25 rivoplus kernel: Range[3920000 - 500000000] Oct 02 21:06:25 rivoplus kernel: out of value, fixed it Oct 02 21:06:25 rivoplus kernel: id=0 set inskew=0 Oct 02 21:06:25 rivoplus volumio[3597]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 21:06:25 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 02 21:06:25 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 02 21:06:25 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 02 21:06:25 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 02 21:06:25 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Combo384 Amanero Validity:0' : Operation not permitted Oct 02 21:06:25 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 02 21:06:25 rivoplus volumio[3597]: info: Output device has changed, restarting MPD Oct 02 21:06:25 rivoplus volumio[3597]: info: Output device has changed, restarting Shairport Sync Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:25 rivoplus sudo[8218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:06:25 rivoplus sudo[8218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus sudo[8218]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:25 rivoplus volumio[3597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:06:25 rivoplus volumio[3597]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus sudo[8220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:06:25 rivoplus sudo[8220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:25 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:25 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: Output device has changed, restarting MPD Oct 02 21:06:25 rivoplus systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 02 21:06:25 rivoplus sudo[8228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:25 rivoplus sudo[8228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus volumio[3597]: info: Output device has changed, restarting Shairport Sync Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:25 rivoplus sudo[8230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:06:25 rivoplus sudo[8230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus sudo[8230]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:25 rivoplus sudo[8233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:06:25 rivoplus sudo[8233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus volumio[3597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:06:25 rivoplus volumio[3597]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:25 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:25 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:25 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:06:25 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:06:25 rivoplus sudo[8242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:25 rivoplus sudo[8242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus sudo[8228]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:25 rivoplus sudo[8244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:25 rivoplus sudo[8244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus sudo[8247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:25 rivoplus sudo[8247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:06:25 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:06:25 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:06:25 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:06:25 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:06:25 rivoplus sudo[8257]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:06:25 rivoplus sudo[8257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:06:25 rivoplus sudo[8271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:06:25 rivoplus sudo[8271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:25 rivoplus volumio[3597]: info: MPD Permissions set Oct 02 21:06:25 rivoplus volumio[3597]: info: MPD Permissions set Oct 02 21:06:25 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:06:25 rivoplus sudo[8275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:06:25 rivoplus sudo[8275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus sudo[8257]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8271]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus sudo[8275]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:06:26 rivoplus sudo[8281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus sudo[8280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:06:26 rivoplus sudo[8280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus sudo[8282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:06:26 rivoplus sudo[8282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus sudo[8284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:06:26 rivoplus sudo[8284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus sudo[8244]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8242]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8247]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:06:26 rivoplus volumio[3597]: info: Starting Shairport Sync Oct 02 21:06:26 rivoplus volumio[3597]: info: Starting Shairport Sync Oct 02 21:06:26 rivoplus volumio[3597]: info: Preparing to generate the ALSA configuration file Oct 02 21:06:26 rivoplus sudo[8281]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:06:26 rivoplus sudo[8308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:06:26 rivoplus sudo[8310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:06:26 rivoplus sudo[8310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus sudo[8311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:06:26 rivoplus sudo[8311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 21:06:26 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:06:26 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:06:26 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:06:26 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 21:06:26 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:06:26 rivoplus sudo[8318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:06:26 rivoplus sudo[8318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus volumio[3597]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 21:06:26 rivoplus volumio[3597]: info: Reading ALSA contributions from plugins. Oct 02 21:06:26 rivoplus volumio[3597]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 02 21:06:26 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:26 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:06:26 rivoplus qobuz-connect[8009]: 20261002 21:06:26.394 [8009.8009] INFO SampleApp: shat down connection on UNIX socket Oct 02 21:06:26 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 21:06:26 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:06:26 rivoplus sudo[8318]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8265]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 21:06:26 rivoplus sudo[8265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 21:06:26 rivoplus volumio[3597]: info: Asound.conf file written Oct 02 21:06:26 rivoplus sudo[8265]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 21:06:26 rivoplus sudo[8327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:06:26 rivoplus sudo[8327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:06:26 rivoplus sudo[8280]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8311]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8199]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8284]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8200]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8282]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:06:26 rivoplus sudo[8328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:06:26 rivoplus sudo[8308]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8310]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 21:06:26 rivoplus sudo[8339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus sudo[8339]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 02 21:06:26 rivoplus kernel: Err: can not set rate to 4270967296! Oct 02 21:06:26 rivoplus kernel: Range[3920000 - 500000000] Oct 02 21:06:26 rivoplus kernel: out of value, fixed it Oct 02 21:06:26 rivoplus kernel: id=0 set inskew=0 Oct 02 21:06:26 rivoplus volumio[3597]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 21:06:26 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 02 21:06:26 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 02 21:06:26 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 02 21:06:26 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 02 21:06:26 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Combo384 Amanero Validity:0' : Operation not permitted Oct 02 21:06:26 rivoplus volumio[3597]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 02 21:06:26 rivoplus volumio[3597]: info: Output device has changed, restarting MPD Oct 02 21:06:26 rivoplus sudo[8327]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 21:06:26 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 21:06:26 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 21:06:26 rivoplus volumio[3597]: info: Output device has changed, restarting Shairport Sync Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:26 rivoplus sudo[8365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:06:26 rivoplus sudo[8366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:06:26 rivoplus sudo[8365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus sudo[8366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus sudo[8366]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 21:06:26 rivoplus sudo[8328]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:26 rivoplus sudo[8369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:06:26 rivoplus sudo[8369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus volumio[3597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:06:26 rivoplus volumio[3597]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:26 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:26 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 21:06:26 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:26 rivoplus volumio[3597]: info: Preparing to generate the ALSA configuration file Oct 02 21:06:26 rivoplus sudo[8389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:26 rivoplus sudo[8389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 21:06:26 rivoplus sudo[8393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:26 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 21:06:26 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 21:06:26 rivoplus sudo[8393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:26 rivoplus volumio[3597]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 21:06:27 rivoplus volumio[3597]: info: Reading ALSA contributions from plugins. Oct 02 21:06:27 rivoplus volumio[3597]: info: MPD Permissions set Oct 02 21:06:27 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:06:27 rivoplus volumio[3597]: info: Shairport-Sync Started Oct 02 21:06:27 rivoplus volumio[3597]: info: Shairport-Sync Started Oct 02 21:06:27 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:06:27 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:06:27 rivoplus volumio[3597]: info: Executing endpoint restartRAATSocket Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 21:06:27 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:06:27 rivoplus sudo[8399]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:06:27 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:06:27 rivoplus sudo[8399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus volumio[3597]: info: Executing endpoint qc_getconfig Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 21:06:27 rivoplus qobuz-connect[8338]: 20261002 21:06:27.044 [8338.8338] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus qobuz-connect[8338]: 20261002 21:06:27.061 [8338.8338] INFO VolumeManager: [0xab5c8ac8]: Setting new playback volume: 75 Oct 02 21:06:27 rivoplus qobuz-connect[8338]: 20261002 21:06:27.061 [8338.8338] INFO VolumeManager: [0xab5c8ac8]: Setting new mute state: 0 Oct 02 21:06:27 rivoplus qobuz-connect[8338]: 20261002 21:06:27.061 [8338.8338] INFO AudioStreamManager: [0xab5c8820]: Setting new audio download buffer size: 1048576 Oct 02 21:06:27 rivoplus qobuz-connect[8338]: 20261002 21:06:27.061 [8338.8338] INFO QobuzConnect: [0xab5c9390]: Client initialized! Oct 02 21:06:27 rivoplus qobuz-connect[8338]: 20261002 21:06:27.061 [8338.8338] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 02 21:06:27 rivoplus sudo[8399]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:27 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:06:27 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:06:27 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:06:27 rivoplus qobuz-connect[8338]: 20261002 21:06:27.086 [8338.8338] INFO LocalConfigManager: [0xab5c8548]: Starting Local Configuration server Oct 02 21:06:27 rivoplus qobuz-connect[8338]: 20261002 21:06:27.086 [8338.8338] INFO SampleApp: Starting Local configuration server Oct 02 21:06:27 rivoplus qobuz-connect[8338]: 20261002 21:06:27.086 [8338.8338] INFO SampleApp: Connected to UNIX socket client 0xab5b3908 Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:06:27 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:06:27 rivoplus sudo[8405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:06:27 rivoplus sudo[8405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 02 21:06:27 rivoplus volumio[3597]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 02 21:06:27 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 21:06:27 rivoplus sudo[8365]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:27 rivoplus volumio[3597]: info: Executing endpoint restartRAATSocket Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 21:06:27 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:06:27 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:06:27 rivoplus volumio[3597]: info: Starting Shairport Sync Oct 02 21:06:27 rivoplus qobuz-connect[8338]: 20261002 21:06:27.194 [8338.8338] INFO SampleApp: Playback volume changed: 75 Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:27 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:27 rivoplus volumio[3597]: info: Asound.conf file unchanged, so no further update is needed Oct 02 21:06:27 rivoplus volumio[3597]: info: Output device has changed, restarting MPD Oct 02 21:06:27 rivoplus sudo[8422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:06:27 rivoplus sudo[8422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus volumio[3597]: info: Output device has changed, restarting Shairport Sync Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:27 rivoplus sudo[8429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:06:27 rivoplus sudo[8429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus sudo[8431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:06:27 rivoplus sudo[8431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus sudo[8429]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:27 rivoplus sudo[8393]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:27 rivoplus volumio[3597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:06:27 rivoplus volumio[3597]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus sudo[8389]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:27 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:27 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:27 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus qobuz-connect[8338]: 20261002 21:06:27.430 [8338.8338] INFO SampleApp: Stopping Local configuration server Oct 02 21:06:27 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 21:06:27 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:06:27 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:06:27 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:06:27 rivoplus sudo[8453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:27 rivoplus sudo[8453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus sudo[8456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:27 rivoplus sudo[8456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:06:27 rivoplus sudo[8422]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:27 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:06:27 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:06:27 rivoplus sudo[8469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:06:27 rivoplus sudo[8469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus volumio[3597]: info: MPD Permissions set Oct 02 21:06:27 rivoplus volumio[3597]: info: Shairport-Sync Started Oct 02 21:06:27 rivoplus volumio[3597]: info: Executing endpoint restartRAATSocket Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:06:27 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:06:27 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:06:27 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:06:27 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:06:27 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:27 rivoplus sudo[8469]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:27 rivoplus volumio[3597]: 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 02 21:06:27 rivoplus volumio[3597]: info: Starting Shairport Sync Oct 02 21:06:27 rivoplus sudo[8453]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:27 rivoplus sudo[8501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:06:27 rivoplus sudo[8501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus sudo[8502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:06:27 rivoplus sudo[8502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus sudo[8506]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:06:27 rivoplus sudo[8506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus sudo[8456]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:27 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:06:27 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:06:27 rivoplus sudo[8501]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:27 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:06:27 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:06:27 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:06:27 rivoplus sudo[8515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:06:27 rivoplus sudo[8515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:27 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:06:27 rivoplus sudo[8506]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:27 rivoplus volumio[3597]: info: Shairport-Sync Started Oct 02 21:06:28 rivoplus sudo[8520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:06:28 rivoplus sudo[8520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:28 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 21:06:28 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 21:06:28 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 21:06:28 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 21:06:28 rivoplus sudo[8520]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:28 rivoplus sudo[8515]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:28 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:06:28 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:06:28 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:06:28 rivoplus sudo[8534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:06:28 rivoplus sudo[8534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:28 rivoplus volumio[3597]: info: Executing endpoint restartRAATSocket Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 21:06:28 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:28 rivoplus volumio[3597]: 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 02 21:06:28 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 21:06:28 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 21:06:28 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 21:06:28 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 21:06:28 rivoplus sudo[8534]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:28 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:06:28 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:28.333+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=3 chunks=1 index=0 tries=11 Oct 02 21:06:28 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:28.334+09:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%03 @ 0x2400c60" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Oct 02 21:06:28 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:28.334+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:64116,00:00:00:00:00:00%04 @ 0x2825860" state=STATUS_PAUSED positionMs=106018 volume=100 Oct 02 21:06:28 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:28.335+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:64116,00:00:00:00:00:00%04 @ 0x2825860" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:06:28 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:06:28 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:06:28 rivoplus sudo[8499]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 21:06:28 rivoplus sudo[8499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 21:06:28 rivoplus volumio[3597]: info: Executing endpoint restartRAATSocket Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 21:06:28 rivoplus sudo[8499]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:28 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:28 rivoplus volumio[3597]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:06:28 rivoplus volumio[3597]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:06:28 rivoplus volumio[3597]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:06:28 rivoplus volumio[3597]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:06:28 rivoplus volumio[3597]: 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 02 21:06:28 rivoplus sudo[8580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 21:06:28 rivoplus sudo[8580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:28 rivoplus sudo[8582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 21:06:28 rivoplus sudo[8582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:28 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 02 21:06:28 rivoplus sudo[8580]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:28 rivoplus sudo[8582]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:28 rivoplus volumio[3597]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 02 21:06:28 rivoplus volumio[3597]: info: Preparing to save Alsa Options, stopping services first Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:28 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPause Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreStateMachine::pause Oct 02 21:06:28 rivoplus volumio[3597]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"S/PDIF + AES/EBU + I2S"}} Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:06:28 rivoplus volumio[3597]: info: Found match in Cards Database for ignoring default Mixer Oct 02 21:06:28 rivoplus volumio[3597]: info: Setting mixer PCM for card S/PDIF + AES/EBU + I2S Oct 02 21:06:28 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:28 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:28 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 02 21:06:28 rivoplus volumio[3597]: info: Ignoring Mixers Options Oct 02 21:06:28 rivoplus volumio[3597]: info: Device 5 does not have any Mixer Control Available Oct 02 21:06:28 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:28 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:28 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 02 21:06:28 rivoplus sudo[8597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:28 rivoplus sudo[8597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:28 rivoplus sudo[8598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:28 rivoplus sudo[8598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 02 21:06:28 rivoplus volumio[3597]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 02 21:06:28 rivoplus volumio[3597]: info: Disabling external Volume Control Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:06:28 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:06:28 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:28 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:28 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:06:28 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:06:28 rivoplus volumio[3597]: info: Preparing to generate the ALSA configuration file Oct 02 21:06:28 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:28.964+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:64116,00:00:00:00:00:00%04 @ 0x2825860" state=STATUS_PAUSED positionMs=109878 volume=100 Oct 02 21:06:28 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:28.965+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:64116,00:00:00:00:00:00%04 @ 0x2825860" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:06:28 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 02 21:06:28 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 02 21:06:28 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 02 21:06:29 rivoplus qobuz-connect[8338]: 20261002 21:06:29.096 [8338.8338] INFO SampleApp: shat down connection on UNIX socket Oct 02 21:06:29 rivoplus sudo[8604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:29 rivoplus sudo[8604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:29 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 21:06:29 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:06:29 rivoplus sudo[8606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:29 rivoplus sudo[8606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:29 rivoplus sudo[8598]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:29 rivoplus sudo[8614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:06:29 rivoplus sudo[8614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:29 rivoplus sudo[8597]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:29 rivoplus volumio[3597]: info: RAAT: Requesting Headphone Status Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 21:06:29 rivoplus volumio[3597]: info: RAAT: Requesting Headphone Status Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreStateMachine::getcurrentVolume Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreStateMachine::pushState Oct 02 21:06:29 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::volumioPushState Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:29 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:29 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output update for this device Oct 02 21:06:29 rivoplus volumio[3597]: info: MRS: Pushing multiroomSync output Oct 02 21:06:29 rivoplus sudo[8621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:06:29 rivoplus sudo[8621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:29 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:29.345+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.158:64116,00:00:00:00:00:00%04 @ 0x2825860" state=STATUS_PAUSED positionMs=110648 volume=100 Oct 02 21:06:29 rivoplus volumio5-onboarding[4761]: time=2026-10-02T21:06:29.345+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.158:64116,00:00:00:00:00:00%04 @ 0x2825860" id="mnt/USB/music_ssd_1/sacd クラシック 一般/Aimi Kobayashi - Chopin Preludes, Polonaise & Fantaisie/02 - Aimi Kobayashi - Chopin 24 Preludes, Op. 28 No. 2 in A Minor.dsf" title="Chopin: 24 Preludes, Op. 28: No. 2 in A Minor" Oct 02 21:06:29 rivoplus sudo[8614]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:29 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:29 rivoplus sudo[8502]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:29 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:06:29 rivoplus sudo[8405]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:29 rivoplus sudo[8627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:06:29 rivoplus sudo[8627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: Not Reporting Auto name since its the default one Oct 02 21:06:29 rivoplus volumio[3597]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 21:06:29 rivoplus volumio[3597]: info: Reading ALSA contributions from plugins. Oct 02 21:06:29 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:06:29 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:06:29 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:06:29 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:06:29 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:06:29 rivoplus volumio[3597]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 02 21:06:29 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:29 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:29 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:06:29 rivoplus volumio[3597]: info: RAAT: Requesting Headphone Status Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 21:06:29 rivoplus volumio[3597]: info: RAAT: Requesting Headphone Status Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 21:06:29 rivoplus volumio[3597]: info: MCU Signalled Headphone Mode Disabled Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 21:06:29 rivoplus volumio[3597]: info: Reporting Headphone State: false Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:06:29 rivoplus volumio[3597]: info: MCU Signalled Headphone Mode Disabled Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 21:06:29 rivoplus volumio[3597]: info: Reporting Headphone State: false Oct 02 21:06:29 rivoplus sudo[8604]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:06:29 rivoplus volumio[3597]: info: Executing endpoint qc_getconfig Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 21:06:29 rivoplus sudo[8606]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:29 rivoplus sudo[8621]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:29 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 21:06:29 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 21:06:29 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:06:29 rivoplus sudo[8646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:06:29 rivoplus sudo[8646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:29 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:06:29 rivoplus sudo[8627]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:29 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 21:06:29 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 21:06:29 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:06:29 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 21:06:29 rivoplus sudo[8646]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 21:06:29 rivoplus sudo[8661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:06:29 rivoplus sudo[8661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:29 rivoplus volumio[3597]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:06:29 rivoplus volumio[3597]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:06:29 rivoplus sudo[8661]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:29 rivoplus sudo[8666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 21:06:29 rivoplus sudo[8666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:29 rivoplus volumio[3597]: info: MCU Signalled Headphone Mode Disabled Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 21:06:29 rivoplus volumio[3597]: info: Reporting Headphone State: false Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:06:29 rivoplus volumio[3597]: info: MCU Signalled Headphone Mode Disabled Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 21:06:29 rivoplus volumio[3597]: info: Reporting Headphone State: false Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:29 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:06:29 rivoplus volumio[3597]: info: Executing endpoint qc_getconfig Oct 02 21:06:29 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 21:06:30 rivoplus sudo[8672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:06:30 rivoplus sudo[8672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus sudo[8674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 21:06:30 rivoplus sudo[8674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus qobuz-connect[8656]: 20261002 21:06:30.046 [8656.8656] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 21:06:30 rivoplus qobuz-connect[8656]: 20261002 21:06:30.053 [8656.8656] INFO VolumeManager: [0xace65ac8]: Setting new playback volume: 75 Oct 02 21:06:30 rivoplus qobuz-connect[8656]: 20261002 21:06:30.053 [8656.8656] INFO VolumeManager: [0xace65ac8]: Setting new mute state: 0 Oct 02 21:06:30 rivoplus qobuz-connect[8656]: 20261002 21:06:30.053 [8656.8656] INFO AudioStreamManager: [0xace65820]: Setting new audio download buffer size: 1048576 Oct 02 21:06:30 rivoplus qobuz-connect[8656]: 20261002 21:06:30.053 [8656.8656] INFO QobuzConnect: [0xace66390]: Client initialized! Oct 02 21:06:30 rivoplus qobuz-connect[8656]: 20261002 21:06:30.053 [8656.8656] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 02 21:06:30 rivoplus sudo[8666]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:30 rivoplus volumio[3597]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 02 21:06:30 rivoplus volumio[3597]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 02 21:06:30 rivoplus volumio[3597]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:06:30 rivoplus volumio[3597]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus qobuz-connect[8656]: 20261002 21:06:30.085 [8656.8656] INFO LocalConfigManager: [0xace65548]: Starting Local Configuration server Oct 02 21:06:30 rivoplus qobuz-connect[8656]: 20261002 21:06:30.085 [8656.8656] INFO SampleApp: Starting Local configuration server Oct 02 21:06:30 rivoplus qobuz-connect[8656]: 20261002 21:06:30.085 [8656.8656] INFO SampleApp: Connected to UNIX socket client 0xace50908 Oct 02 21:06:30 rivoplus sudo[8679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 21:06:30 rivoplus sudo[8679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus sudo[8681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 21:06:30 rivoplus sudo[8681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus volumio[3597]: info: Asound.conf file unchanged, so no further update is needed Oct 02 21:06:30 rivoplus volumio[3597]: info: Output device has changed, restarting MPD Oct 02 21:06:30 rivoplus volumio[3597]: info: Output device has changed, restarting Shairport Sync Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:30 rivoplus sudo[8688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 21:06:30 rivoplus sudo[8688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus qobuz-connect[8656]: 20261002 21:06:30.196 [8656.8656] INFO SampleApp: Playback volume changed: 75 Oct 02 21:06:30 rivoplus sudo[8690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 21:06:30 rivoplus sudo[8688]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:30 rivoplus sudo[8690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 02 21:06:30 rivoplus volumio[3597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 21:06:30 rivoplus volumio[3597]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 02 21:06:30 rivoplus sudo[8674]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:30 rivoplus volumio[3597]: info: QobuzConnect: setDeactiveState invoked Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:30 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:30 rivoplus volumio[3597]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 02 21:06:30 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 02 21:06:30 rivoplus sudo[8681]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:30 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 02 21:06:30 rivoplus sudo[8679]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:30 rivoplus sudo[8672]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:30 rivoplus sudo[8702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:30 rivoplus sudo[8702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus sudo[8705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 21:06:30 rivoplus sudo[8705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::volumioGetState Oct 02 21:06:30 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:30 rivoplus volumio[3597]: info: MPD Permissions set Oct 02 21:06:30 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:06:30 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:06:30 rivoplus volumio[3597]: info: Raat Daemon started successfully Oct 02 21:06:30 rivoplus volumio[3597]: info: TidalConnect service stoped! Oct 02 21:06:30 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 02 21:06:30 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 21:06:30 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 02 21:06:30 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 21:06:30 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 21:06:30 rivoplus sudo[8720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 21:06:30 rivoplus sudo[8720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus volumio[3597]: info: Executing endpoint restartRAATSocket Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 21:06:30 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 21:06:30 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 02 21:06:30 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 02 21:06:30 rivoplus volumio[3597]: info: CorePlayQueue::getTrack 1 Oct 02 21:06:30 rivoplus volumio[3597]: info: Starting Shairport Sync Oct 02 21:06:30 rivoplus volumio[3597]: 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 02 21:06:30 rivoplus volumio[3597]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:06:30 rivoplus volumio[3597]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 21:06:30 rivoplus sudo[8742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 21:06:30 rivoplus sudo[8742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus sudo[8705]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:30 rivoplus sudo[8745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 21:06:30 rivoplus sudo[8745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus volumio[3597]: info: RAAT: Requesting Headphone Status Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 02 21:06:30 rivoplus sudo[8720]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:30 rivoplus sudo[8748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 21:06:30 rivoplus sudo[8702]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:30 rivoplus sudo[8748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 21:06:30 rivoplus volumio[3597]: info: MCU Signalled Headphone Mode Disabled Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 02 21:06:30 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 02 21:06:30 rivoplus volumio[3597]: info: Reporting Headphone State: false Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 21:06:30 rivoplus volumio[3597]: info: Updating RAAT Signal Path Oct 02 21:06:30 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 02 21:06:30 rivoplus volumio[3597]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 21:06:30 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 02 21:06:30 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:06:30 rivoplus volumio[3597]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 02 21:06:30 rivoplus volumio[3597]: Job for vtcs.service canceled. Oct 02 21:06:30 rivoplus volumio[3597]: at ChildProcess.exithandler (node:child_process:421:12) Oct 02 21:06:30 rivoplus volumio[3597]: at ChildProcess.emit (node:events:514:28) Oct 02 21:06:30 rivoplus volumio[3597]: at maybeClose (node:internal/child_process:1105:16) Oct 02 21:06:30 rivoplus volumio[3597]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) { Oct 02 21:06:30 rivoplus volumio[3597]: code: 1, Oct 02 21:06:30 rivoplus volumio[3597]: killed: false, Oct 02 21:06:30 rivoplus volumio[3597]: signal: null, Oct 02 21:06:30 rivoplus volumio[3597]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 02 21:06:30 rivoplus volumio[3597]: stdout: '', Oct 02 21:06:30 rivoplus volumio[3597]: stderr: 'Job for vtcs.service canceled.\n' Oct 02 21:06:30 rivoplus volumio[3597]: } Oct 02 21:06:30 rivoplus volumio[3597]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 21:06:30 rivoplus sudo[8745]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:30 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 02 21:06:30 rivoplus sudo[8742]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:30 rivoplus qobuz-connect[8656]: 20261002 21:06:30.938 [8656.8656] INFO SampleApp: Stopping Local configuration server Oct 02 21:06:30 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 21:06:31 rivoplus sudo[8735]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 21:06:31 rivoplus sudo[8735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 21:06:31 rivoplus sudo[8735]: pam_unix(sudo:session): session closed for user root Oct 02 21:06:31 rivoplus sudo[8779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 21:05' Oct 02 21:06:31 rivoplus sudo[8779]: 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="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:52:40 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="ba37532a86808e19439a6278b2d45f47"