-- Logs begin at Thu 2025-10-30 00:05:40 CET, end at Thu 2025-10-30 01:02:44 CET. -- Oct 30 01:01:04 rivoplus nmbd[3069]: [2025/10/30 01:01:04.536440, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 30 01:01:04 rivoplus nmbd[3069]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Oct 30 01:01:06 rivoplus volumio[10649]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 30 01:01:06 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 30 01:01:06 rivoplus volumio[10649]: info: Preparing to save Alsa Options, stopping services first Oct 30 01:01:06 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:06 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPause Oct 30 01:01:06 rivoplus volumio[10649]: info: CoreStateMachine::pause Oct 30 01:01:06 rivoplus volumio[10649]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Volumio Preciso"}} Oct 30 01:01:06 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 30 01:01:06 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:06 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:06 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:06 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:06 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 30 01:01:06 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:06 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:06 rivoplus volumio[10649]: info: Setting mixer PCM for card Volumio Preciso Oct 30 01:01:06 rivoplus volumio[10649]: info: QobuzConnect: setDeactiveState invoked Oct 30 01:01:06 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:06 rivoplus volumio[10649]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 30 01:01:06 rivoplus vtcs[17336]: [2025-10-30 01:01:06.968] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 30 01:01:06 rivoplus vtcs[17336]: [2025-10-30 01:01:06.968] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Oct 30 01:01:07 rivoplus sudo[17574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 01:01:07 rivoplus sudo[17574]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:07 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Oct 30 01:01:07 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 30 01:01:07 rivoplus systemd[1]: vtcs.service: Succeeded. Oct 30 01:01:07 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Oct 30 01:01:07 rivoplus sudo[17574]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:07 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:07 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 30 01:01:07 rivoplus volumio[10649]: info: Updating Volume Controller Parameters: Device: 5 Name: Volumio Preciso Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 30 01:01:07 rivoplus volumio[10649]: info: Disabling external Volume Control Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 30 01:01:07 rivoplus volumio[10649]: info: Preparing to generate the ALSA configuration file Oct 30 01:01:07 rivoplus sudo[17581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 01:01:07 rivoplus sudo[17581]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:07 rivoplus sudo[17581]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:07 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:07 rivoplus volumio[10649]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 30 01:01:07 rivoplus volumio[10649]: info: Reading ALSA contributions from plugins. Oct 30 01:01:07 rivoplus volumio[10649]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 30 01:01:07 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:07 rivoplus sudo[17588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 30 01:01:07 rivoplus sudo[17588]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:07 rivoplus sudo[17588]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:07 rivoplus sudo[17591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 30 01:01:07 rivoplus sudo[17591]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:07 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 30 01:01:07 rivoplus qobuz-connect[17244]: 20251030 01:01:07.486 [17244.17244] INFO SampleApp: Stopping Local configuration server Oct 30 01:01:07 rivoplus qobuz-connect[17244]: 20251030 01:01:07.497 [17244.17244] INFO SampleApp: shat down connection on UNIX socket Oct 30 01:01:07 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Oct 30 01:01:07 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 30 01:01:07 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Oct 30 01:01:07 rivoplus sudo[17591]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:07 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:07 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 30 01:01:07 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:07 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:07 rivoplus volumio[10649]: info: Not Reporting Auto name since its the default one Oct 30 01:01:07 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:07 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:07 rivoplus volumio[10649]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 30 01:01:07 rivoplus volumio[10649]: info: QobuzConnect: setDeactiveState invoked Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:07 rivoplus volumio[10649]: info: VolumeController:: Volume=undefined Mute =false Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:07 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:07 rivoplus volumio[10649]: info: Executing endpoint qc_getconfig Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 30 01:01:07 rivoplus qobuz-connect[17596]: 20251030 01:01:07.776 [17596.17596] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:07 rivoplus qobuz-connect[17596]: 20251030 01:01:07.782 [17596.17596] INFO VolumeManager: [0xabb638f0]: Setting new playback volume: 75 Oct 30 01:01:07 rivoplus qobuz-connect[17596]: 20251030 01:01:07.782 [17596.17596] INFO VolumeManager: [0xabb638f0]: Setting new mute state: 0 Oct 30 01:01:07 rivoplus qobuz-connect[17596]: 20251030 01:01:07.782 [17596.17596] INFO QobuzConnect: [0xabb642c0]: Client initialized! Oct 30 01:01:07 rivoplus qobuz-connect[17596]: 20251030 01:01:07.783 [17596.17596] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 30 01:01:07 rivoplus qobuz-connect[17596]: 20251030 01:01:07.808 [17596.17596] INFO LocalConfigManager: [0xabb631d0]: Starting Local Configuration server Oct 30 01:01:07 rivoplus qobuz-connect[17596]: 20251030 01:01:07.808 [17596.17596] INFO SampleApp: Starting Local configuration server Oct 30 01:01:07 rivoplus qobuz-connect[17596]: 20251030 01:01:07.809 [17596.17596] INFO SampleApp: Connected to UNIX socket client 0xabb58ed8 Oct 30 01:01:07 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:07 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:07 rivoplus volumio[10649]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 30 01:01:07 rivoplus volumio[10649]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 30 01:01:07 rivoplus qobuz-connect[17596]: 20251030 01:01:07.925 [17596.17596] INFO SampleApp: Playback volume changed: 75 Oct 30 01:01:07 rivoplus sudo[17615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 30 01:01:07 rivoplus sudo[17615]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:07 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:07 rivoplus volumio[10649]: info: Asound.conf file written Oct 30 01:01:07 rivoplus sudo[17615]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:07 rivoplus sudo[17618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 30 01:01:07 rivoplus sudo[17618]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus sudo[17619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 30 01:01:08 rivoplus sudo[17619]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus sudo[17619]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:08 rivoplus systemd[1]: Stopping RAAT DAEMON... Oct 30 01:01:08 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 30 01:01:08 rivoplus systemd[1]: raat-daemon.service: Succeeded. Oct 30 01:01:08 rivoplus systemd[1]: Stopped RAAT DAEMON. Oct 30 01:01:08 rivoplus systemd[1]: Started RAAT DAEMON. Oct 30 01:01:08 rivoplus sudo[17618]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:08 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 30 01:01:08 rivoplus kernel: Fine spdif sysclk setting range(0~2000000), 6143997 Oct 30 01:01:08 rivoplus kernel: out of value, fixed it Oct 30 01:01:08 rivoplus kernel: id=0 set inskew=0 Oct 30 01:01:08 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:08 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:08 rivoplus volumio[10649]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 30 01:01:08 rivoplus volumio[10649]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 30 01:01:08 rivoplus volumio[10649]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 30 01:01:08 rivoplus volumio[10649]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 30 01:01:08 rivoplus volumio[10649]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 30 01:01:08 rivoplus volumio[10649]: info: Output device has changed, restarting MPD Oct 30 01:01:08 rivoplus sudo[17634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 01:01:08 rivoplus sudo[17634]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus sudo[17634]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:08 rivoplus volumio[10649]: info: Output device has changed, restarting Shairport Sync Oct 30 01:01:08 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:08 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 01:01:08 rivoplus sudo[17638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 01:01:08 rivoplus sudo[17638]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus systemd[1]: Stopping Music Player Daemon... Oct 30 01:01:08 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:08 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:08 rivoplus volumio[10649]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 01:01:08 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:08 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:08 rivoplus volumio[10649]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 30 01:01:08 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:08 rivoplus volumio[10649]: info: QobuzConnect: setDeactiveState invoked Oct 30 01:01:08 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:08 rivoplus volumio[10649]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 30 01:01:08 rivoplus systemd[1]: mpd.service: Succeeded. Oct 30 01:01:08 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:08 rivoplus systemd[1]: Stopped Music Player Daemon. Oct 30 01:01:08 rivoplus systemd[1]: Starting Music Player Daemon... Oct 30 01:01:08 rivoplus volumio[10649]: info: Output device has changed, restarting MPD Oct 30 01:01:08 rivoplus sudo[17648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 01:01:08 rivoplus sudo[17648]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus sudo[17649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 01:01:08 rivoplus sudo[17649]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus sudo[17649]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:08 rivoplus volumio[10649]: info: Output device has changed, restarting Shairport Sync Oct 30 01:01:08 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:08 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 01:01:08 rivoplus sudo[17648]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:08 rivoplus sudo[17653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 01:01:08 rivoplus sudo[17653]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus sudo[17645]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 01:01:08 rivoplus sudo[17645]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus sudo[17645]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:08 rivoplus systemd[1]: mpd.service: Succeeded. Oct 30 01:01:08 rivoplus systemd[1]: Stopped Music Player Daemon. Oct 30 01:01:08 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:08 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:08 rivoplus volumio[10649]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 01:01:08 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:08 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:08 rivoplus volumio[10649]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 30 01:01:08 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:08 rivoplus systemd[1]: Starting Music Player Daemon... Oct 30 01:01:08 rivoplus volumio[10649]: info: QobuzConnect: setDeactiveState invoked Oct 30 01:01:08 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:08 rivoplus volumio[10649]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 30 01:01:08 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:08 rivoplus sudo[17667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 01:01:08 rivoplus sudo[17667]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus sudo[17663]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 01:01:08 rivoplus sudo[17663]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus sudo[17663]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:08 rivoplus sudo[17667]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:08 rivoplus sudo[17673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 01:01:08 rivoplus sudo[17673]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus sudo[17677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 01:01:08 rivoplus sudo[17677]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:08 rivoplus sudo[17673]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:08 rivoplus sudo[17677]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:09 rivoplus sudo[17686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 30 01:01:09 rivoplus sudo[17686]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:09 rivoplus sudo[17686]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:09 rivoplus sudo[17691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 30 01:01:09 rivoplus sudo[17691]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:09 rivoplus qobuz-connect[17596]: 20251030 01:01:09.129 [17596.17596] INFO SampleApp: Stopping Local configuration server Oct 30 01:01:09 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 30 01:01:09 rivoplus qobuz-connect[17596]: 20251030 01:01:09.140 [17596.17596] INFO SampleApp: shat down connection on UNIX socket Oct 30 01:01:09 rivoplus sudo[17694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 30 01:01:09 rivoplus sudo[17694]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:09 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Oct 30 01:01:09 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 30 01:01:09 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Oct 30 01:01:09 rivoplus sudo[17691]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus sudo[17694]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: Not Reporting Auto name since its the default one Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:09 rivoplus sudo[17701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:09 rivoplus sudo[17701]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:09 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:09 rivoplus systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Oct 30 01:01:09 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Oct 30 01:01:09 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:09 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Oct 30 01:01:09 rivoplus sudo[17701]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:09 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:09 rivoplus volumio[10649]: info: MPD Permissions set Oct 30 01:01:09 rivoplus volumio[10649]: info: MPD Permissions set Oct 30 01:01:09 rivoplus volumio[10649]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 30 01:01:09 rivoplus volumio[10649]: info: QobuzConnect: setDeactiveState invoked Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:09 rivoplus volumio[10649]: info: Raat Daemon started successfully Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: Not Reporting Auto name since its the default one Oct 30 01:01:09 rivoplus volumio[10649]: info: Executing endpoint restartRAATSocket Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 30 01:01:09 rivoplus volumio[10649]: info: Executing endpoint qc_getconfig Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus qobuz-connect[17704]: 20251030 01:01:09.509 [17704.17704] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 30 01:01:09 rivoplus qobuz-connect[17704]: 20251030 01:01:09.517 [17704.17704] INFO VolumeManager: [0xacafd8f0]: Setting new playback volume: 75 Oct 30 01:01:09 rivoplus qobuz-connect[17704]: 20251030 01:01:09.517 [17704.17704] INFO VolumeManager: [0xacafd8f0]: Setting new mute state: 0 Oct 30 01:01:09 rivoplus qobuz-connect[17704]: 20251030 01:01:09.517 [17704.17704] INFO QobuzConnect: [0xacafe2c0]: Client initialized! Oct 30 01:01:09 rivoplus qobuz-connect[17704]: 20251030 01:01:09.517 [17704.17704] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 30 01:01:09 rivoplus qobuz-connect[17704]: 20251030 01:01:09.541 [17704.17704] INFO LocalConfigManager: [0xacafd1d0]: Starting Local Configuration server Oct 30 01:01:09 rivoplus qobuz-connect[17704]: 20251030 01:01:09.541 [17704.17704] INFO SampleApp: Starting Local configuration server Oct 30 01:01:09 rivoplus qobuz-connect[17704]: 20251030 01:01:09.542 [17704.17704] INFO SampleApp: Connected to UNIX socket client 0xacaf2ed8 Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus qobuz-connect[17704]: 20251030 01:01:09.659 [17704.17704] INFO SampleApp: Playback volume changed: 75 Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 30 01:01:09 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:09 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:09 rivoplus volumio[10649]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 30 01:01:09 rivoplus volumio[10649]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 30 01:01:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 30 01:01:09 rivoplus sudo[17729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 30 01:01:09 rivoplus sudo[17729]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:09 rivoplus sudo[17729]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:09 rivoplus sudo[17733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 30 01:01:09 rivoplus sudo[17733]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:09 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:09 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:10 rivoplus systemd[1]: Stopping RAAT DAEMON... Oct 30 01:01:10 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 30 01:01:10 rivoplus systemd[1]: raat-daemon.service: Succeeded. Oct 30 01:01:10 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:10 rivoplus systemd[1]: Stopped RAAT DAEMON. Oct 30 01:01:10 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:10 rivoplus sudo[17736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 30 01:01:10 rivoplus sudo[17736]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:10 rivoplus volumio[10649]: 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 30 01:01:10 rivoplus systemd[1]: Started RAAT DAEMON. Oct 30 01:01:10 rivoplus volumio[10649]: info: Starting Shairport Sync Oct 30 01:01:10 rivoplus sudo[17733]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:10 rivoplus sudo[17736]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:10 rivoplus sudo[17745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 30 01:01:10 rivoplus volumio[10649]: info: Starting Shairport Sync Oct 30 01:01:10 rivoplus sudo[17747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 01:01:10 rivoplus sudo[17747]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:10 rivoplus sudo[17745]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:10 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 01:01:10 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:10 rivoplus systemd[1]: shairport-sync.service: Succeeded. Oct 30 01:01:10 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 01:01:10 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:10 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:10 rivoplus volumio[10649]: info: TidalConnect service stoped! Oct 30 01:01:10 rivoplus volumio[10649]: info: Raat Daemon started successfully Oct 30 01:01:10 rivoplus sudo[17755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 01:01:10 rivoplus sudo[17755]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:10 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 01:01:10 rivoplus volumio[10649]: info: Executing endpoint restartRAATSocket Oct 30 01:01:10 rivoplus sudo[17747]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:10 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 30 01:01:10 rivoplus systemd[1]: Stopping RAAT DAEMON... Oct 30 01:01:10 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 30 01:01:10 rivoplus systemd[1]: raat-daemon.service: Succeeded. Oct 30 01:01:10 rivoplus systemd[1]: Stopped RAAT DAEMON. Oct 30 01:01:10 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:10 rivoplus volumio[10649]: info: TidalConnect service stoped! Oct 30 01:01:10 rivoplus systemd[1]: Started RAAT DAEMON. Oct 30 01:01:10 rivoplus sudo[17745]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:10 rivoplus volumio[10649]: info: Shairport-Sync Started Oct 30 01:01:10 rivoplus volumio[10649]: (node:10649) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 30 01:01:10 rivoplus volumio[10649]: (node:10649) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 30 01:01:10 rivoplus volumio[10649]: (node:10649) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 30 01:01:10 rivoplus volumio[10649]: (node:10649) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 30 01:01:10 rivoplus volumio[10649]: (node:10649) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 30 01:01:10 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:10 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 01:01:10 rivoplus systemd[1]: shairport-sync.service: Succeeded. Oct 30 01:01:10 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 01:01:10 rivoplus volumio[10649]: info: Raat Daemon started successfully Oct 30 01:01:10 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 01:01:10 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:10 rivoplus sudo[17755]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:10 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:10 rivoplus volumio[10649]: 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 30 01:01:10 rivoplus volumio[10649]: info: Shairport-Sync Started Oct 30 01:01:10 rivoplus volumio[10649]: info: Executing endpoint restartRAATSocket Oct 30 01:01:10 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 30 01:01:10 rivoplus volumio[10649]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 30 01:01:10 rivoplus volumio[10649]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 30 01:01:10 rivoplus sudo[17783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 30 01:01:10 rivoplus sudo[17783]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:10 rivoplus volumio[10649]: 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 30 01:01:10 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Oct 30 01:01:10 rivoplus sudo[17783]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:10 rivoplus volumio[10649]: info: RAAT: Requesting Headphone Status Oct 30 01:01:10 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 30 01:01:10 rivoplus volumio[10649]: info: MCU Signalled Headphone Mode Disabled Oct 30 01:01:10 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 30 01:01:10 rivoplus volumio[10649]: info: Reporting Headphone State: false Oct 30 01:01:10 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:10 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:11 rivoplus volumio[10649]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 30 01:01:11 rivoplus volumio[10649]: info: Preparing to save Alsa Options, stopping services first Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPause Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreStateMachine::pause Oct 30 01:01:11 rivoplus volumio[10649]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Volumio Preciso"}} Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 30 01:01:11 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:11 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:11 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:11 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 30 01:01:11 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:11 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:11 rivoplus volumio[10649]: info: Setting mixer PCM for card Volumio Preciso Oct 30 01:01:11 rivoplus volumio[10649]: info: QobuzConnect: setDeactiveState invoked Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:11 rivoplus volumio[10649]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 30 01:01:11 rivoplus sudo[17813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 01:01:11 rivoplus sudo[17813]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:11 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Oct 30 01:01:11 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 30 01:01:11 rivoplus systemd[1]: vtcs.service: Succeeded. Oct 30 01:01:11 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Oct 30 01:01:11 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:11 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 30 01:01:11 rivoplus volumio[10649]: info: Updating Volume Controller Parameters: Device: 5 Name: Volumio Preciso Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 30 01:01:11 rivoplus volumio[10649]: info: Disabling external Volume Control Oct 30 01:01:11 rivoplus sudo[17813]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 30 01:01:11 rivoplus volumio[10649]: info: Preparing to generate the ALSA configuration file Oct 30 01:01:11 rivoplus mpd[17678]: Oct 30 01:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 30 01:01:11 rivoplus systemd[1]: Started Music Player Daemon. Oct 30 01:01:11 rivoplus sudo[17638]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:11 rivoplus sudo[17653]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:11 rivoplus sudo[17822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 01:01:11 rivoplus sudo[17822]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:11 rivoplus sudo[17822]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreStateMachine::getcurrentVolume Oct 30 01:01:11 rivoplus volumio[10649]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 01:01:11 rivoplus sudo[17829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 30 01:01:11 rivoplus sudo[17829]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:11 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:11 rivoplus volumio[10649]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 30 01:01:11 rivoplus volumio[10649]: info: Reading ALSA contributions from plugins. Oct 30 01:01:11 rivoplus sudo[17829]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:11 rivoplus volumio[10649]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 30 01:01:11 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:11 rivoplus sudo[17832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 30 01:01:11 rivoplus sudo[17832]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:11 rivoplus qobuz-connect[17704]: 20251030 01:01:11.997 [17704.17704] INFO SampleApp: Stopping Local configuration server Oct 30 01:01:11 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 30 01:01:12 rivoplus volumio[10649]: info: TidalConnect service stoped! Oct 30 01:01:12 rivoplus qobuz-connect[17704]: 20251030 01:01:12.008 [17704.17704] INFO SampleApp: shat down connection on UNIX socket Oct 30 01:01:12 rivoplus volumio[10649]: info: TidalConnect service stoped! Oct 30 01:01:12 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Oct 30 01:01:12 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 30 01:01:12 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Oct 30 01:01:12 rivoplus sudo[17832]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: Not Reporting Auto name since its the default one Oct 30 01:01:12 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:12 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:12 rivoplus volumio[10649]: info: Executing endpoint tc_getconfig Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 30 01:01:12 rivoplus volumio[10649]: info: VolumeController:: Volume=undefined Mute =false Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:12 rivoplus volumio[10649]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 30 01:01:12 rivoplus volumio[10649]: info: QobuzConnect: setDeactiveState invoked Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:12 rivoplus volumio[10649]: info: TidalConnect service stoped! Oct 30 01:01:12 rivoplus volumio[10649]: info: TidalConnect service stoped! Oct 30 01:01:12 rivoplus volumio[10649]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 30 01:01:12 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:12 rivoplus volumio[10649]: error: MPD error: The expression evaluated to a falsy value: Oct 30 01:01:12 rivoplus volumio[10649]: assert.ok(self.idling) Oct 30 01:01:12 rivoplus volumio[10649]: error: The expression evaluated to a falsy value: Oct 30 01:01:12 rivoplus volumio[10649]: assert.ok(self.idling) Oct 30 01:01:12 rivoplus volumio[10649]: info: Executing endpoint qc_getconfig Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 30 01:01:12 rivoplus qobuz-connect[17837]: 20251030 01:01:12.445 [17837.17837] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 30 01:01:12 rivoplus qobuz-connect[17837]: 20251030 01:01:12.459 [17837.17837] INFO VolumeManager: [0xac4bd8f0]: Setting new playback volume: 75 Oct 30 01:01:12 rivoplus qobuz-connect[17837]: 20251030 01:01:12.462 [17837.17837] INFO VolumeManager: [0xac4bd8f0]: Setting new mute state: 0 Oct 30 01:01:12 rivoplus qobuz-connect[17837]: 20251030 01:01:12.462 [17837.17837] INFO QobuzConnect: [0xac4be2c0]: Client initialized! Oct 30 01:01:12 rivoplus qobuz-connect[17837]: 20251030 01:01:12.462 [17837.17837] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 30 01:01:12 rivoplus qobuz-connect[17837]: 20251030 01:01:12.482 [17837.17837] INFO LocalConfigManager: [0xac4bd1d0]: Starting Local Configuration server Oct 30 01:01:12 rivoplus qobuz-connect[17837]: 20251030 01:01:12.482 [17837.17837] INFO SampleApp: Starting Local configuration server Oct 30 01:01:12 rivoplus qobuz-connect[17837]: 20251030 01:01:12.483 [17837.17837] INFO SampleApp: Connected to UNIX socket client 0xac4b2ed8 Oct 30 01:01:12 rivoplus qobuz-connect[17837]: 20251030 01:01:12.596 [17837.17837] INFO SampleApp: Playback volume changed: 75 Oct 30 01:01:12 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:12 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 30 01:01:12 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:12 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 30 01:01:12 rivoplus volumio[10649]: error: updateQueue error: null Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::volumioVolatilePlay Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreStateMachine::volatilePlay Oct 30 01:01:12 rivoplus volumio[10649]: info: WARNING: No play method for volatile plugin undefined Oct 30 01:01:12 rivoplus volumio[10649]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 30 01:01:12 rivoplus volumio[10649]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::servicePushState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::servicePushState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:12 rivoplus volumio[10649]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 30 01:01:12 rivoplus volumio[10649]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 30 01:01:12 rivoplus volumio[10649]: info: VolumeController:: Volume=undefined Mute =false Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreStateMachine::updateTrackBlock Oct 30 01:01:12 rivoplus volumio[10649]: info: CorePlayQueue::getTrackBlock Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 01:01:12 rivoplus sudo[17871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 30 01:01:12 rivoplus sudo[17871]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:12 rivoplus volumio[10649]: info: RAAT: Requesting Headphone Status Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 30 01:01:12 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 30 01:01:12 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Oct 30 01:01:12 rivoplus sudo[17871]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:12 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:12 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:13 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:13 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:13 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:13 rivoplus volumio[10649]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 30 01:01:13 rivoplus volumio[10649]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 30 01:01:13 rivoplus sudo[17881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 30 01:01:13 rivoplus sudo[17881]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:13 rivoplus volumio[10649]: info: RAAT: Requesting Headphone Status Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 30 01:01:13 rivoplus sudo[17881]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:13 rivoplus volumio[10649]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 30 01:01:13 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:13 rivoplus sudo[17884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 30 01:01:13 rivoplus sudo[17884]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:13 rivoplus sudo[17885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 30 01:01:13 rivoplus sudo[17885]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:13 rivoplus volumio[10649]: info: MCU Signalled Headphone Mode Disabled Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 30 01:01:13 rivoplus volumio[10649]: info: Reporting Headphone State: false Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:13 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:13 rivoplus volumio[10649]: info: MCU Signalled Headphone Mode Disabled Oct 30 01:01:13 rivoplus systemd[1]: Stopping RAAT DAEMON... Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 30 01:01:13 rivoplus volumio[10649]: info: Reporting Headphone State: false Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:13 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:13 rivoplus sudo[17884]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:13 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 30 01:01:13 rivoplus systemd[1]: raat-daemon.service: Succeeded. Oct 30 01:01:13 rivoplus systemd[1]: Stopped RAAT DAEMON. Oct 30 01:01:13 rivoplus volumio[10649]: info: Asound.conf file unchanged, so no further update is needed Oct 30 01:01:13 rivoplus volumio[10649]: info: Output device has changed, restarting MPD Oct 30 01:01:13 rivoplus systemd[1]: Started RAAT DAEMON. Oct 30 01:01:13 rivoplus sudo[17885]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:13 rivoplus volumio[10649]: info: Output device has changed, restarting Shairport Sync Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 01:01:13 rivoplus sudo[17898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 30 01:01:13 rivoplus sudo[17898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:13 rivoplus sudo[17904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 30 01:01:13 rivoplus sudo[17898]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:13 rivoplus sudo[17904]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:13 rivoplus systemd[1]: Stopping Music Player Daemon... Oct 30 01:01:13 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:13 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:13 rivoplus volumio[10649]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 30 01:01:13 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:13 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:13 rivoplus volumio[10649]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:13 rivoplus volumio[10649]: info: QobuzConnect: setDeactiveState invoked Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:13 rivoplus volumio[10649]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:13 rivoplus systemd[1]: mpd.service: Succeeded. Oct 30 01:01:13 rivoplus systemd[1]: Stopped Music Player Daemon. Oct 30 01:01:13 rivoplus systemd[1]: Starting Music Player Daemon... Oct 30 01:01:13 rivoplus sudo[17916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 01:01:13 rivoplus sudo[17916]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:13 rivoplus sudo[17919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 30 01:01:13 rivoplus sudo[17919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:13 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Oct 30 01:01:13 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 30 01:01:13 rivoplus systemd[1]: vtcs.service: Succeeded. Oct 30 01:01:13 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Oct 30 01:01:13 rivoplus sudo[17916]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:13 rivoplus sudo[17919]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:13 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:13 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:13 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:13 rivoplus sudo[17926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 30 01:01:13 rivoplus sudo[17926]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:13 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:13 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:13 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:13 rivoplus sudo[17915]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 30 01:01:13 rivoplus sudo[17915]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:13 rivoplus sudo[17926]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:13 rivoplus volumio[10649]: info: MPD Permissions set Oct 30 01:01:13 rivoplus volumio[10649]: info: Raat Daemon started successfully Oct 30 01:01:13 rivoplus sudo[17915]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:13 rivoplus volumio[10649]: info: VolumeController:: Volume=undefined Mute =false Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:13 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:13 rivoplus sudo[17930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 30 01:01:13 rivoplus sudo[17930]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:13 rivoplus volumio[10649]: info: Executing endpoint restartRAATSocket Oct 30 01:01:13 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 30 01:01:13 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 30 01:01:13 rivoplus qobuz-connect[17837]: 20251030 01:01:13.986 [17837.17837] INFO SampleApp: Stopping Local configuration server Oct 30 01:01:14 rivoplus qobuz-connect[17837]: 20251030 01:01:13.997 [17837.17837] INFO SampleApp: shat down connection on UNIX socket Oct 30 01:01:14 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Oct 30 01:01:14 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 30 01:01:14 rivoplus volumio[10649]: info: Executing endpoint tc_getconfig Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 30 01:01:14 rivoplus volumio[10649]: info: TidalConnect service started! Oct 30 01:01:14 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:14 rivoplus volumio[10649]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 30 01:01:14 rivoplus volumio[10649]: info: QobuzConnect: setDeactiveState invoked Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Oct 30 01:01:14 rivoplus sudo[17930]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 01:01:14 rivoplus volumio[10649]: info: Not Reporting Auto name since its the default one Oct 30 01:01:14 rivoplus volumio[10649]: info: Executing endpoint qc_getconfig Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 30 01:01:14 rivoplus qobuz-connect[17934]: 20251030 01:01:14.265 [17934.17934] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 30 01:01:14 rivoplus volumio[10649]: 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 30 01:01:14 rivoplus volumio[10649]: info: Starting Shairport Sync Oct 30 01:01:14 rivoplus qobuz-connect[17934]: 20251030 01:01:14.282 [17934.17934] INFO VolumeManager: [0xac72b8f0]: Setting new playback volume: 75 Oct 30 01:01:14 rivoplus qobuz-connect[17934]: 20251030 01:01:14.283 [17934.17934] INFO VolumeManager: [0xac72b8f0]: Setting new mute state: 0 Oct 30 01:01:14 rivoplus qobuz-connect[17934]: 20251030 01:01:14.283 [17934.17934] INFO QobuzConnect: [0xac72c2c0]: Client initialized! Oct 30 01:01:14 rivoplus qobuz-connect[17934]: 20251030 01:01:14.284 [17934.17934] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 30 01:01:14 rivoplus qobuz-connect[17934]: 20251030 01:01:14.310 [17934.17934] INFO LocalConfigManager: [0xac72b1d0]: Starting Local Configuration server Oct 30 01:01:14 rivoplus qobuz-connect[17934]: 20251030 01:01:14.311 [17934.17934] INFO SampleApp: Starting Local configuration server Oct 30 01:01:14 rivoplus qobuz-connect[17934]: 20251030 01:01:14.312 [17934.17934] INFO SampleApp: Connected to UNIX socket client 0xac720ed8 Oct 30 01:01:14 rivoplus volumio[10649]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 30 01:01:14 rivoplus volumio[10649]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 30 01:01:14 rivoplus sudo[17948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 30 01:01:14 rivoplus sudo[17948]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:14 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 30 01:01:14 rivoplus systemd[1]: shairport-sync.service: Succeeded. Oct 30 01:01:14 rivoplus qobuz-connect[17934]: 20251030 01:01:14.414 [17934.17934] INFO SampleApp: Playback volume changed: 75 Oct 30 01:01:14 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 30 01:01:14 rivoplus volumio[10649]: No protocol specified Oct 30 01:01:14 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:14 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 30 01:01:14 rivoplus sudo[17948]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:14 rivoplus sudo[17954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 30 01:01:14 rivoplus sudo[17954]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:14 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:14 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:14 rivoplus volumio[10649]: info: Shairport-Sync Started Oct 30 01:01:14 rivoplus sudo[17954]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:14 rivoplus sudo[17958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 30 01:01:14 rivoplus sudo[17958]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:14 rivoplus systemd[1]: Stopping RAAT DAEMON... Oct 30 01:01:14 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 30 01:01:14 rivoplus systemd[1]: raat-daemon.service: Succeeded. Oct 30 01:01:14 rivoplus systemd[1]: Stopped RAAT DAEMON. Oct 30 01:01:14 rivoplus volumio[10649]: info: TidalConnect service stoped! Oct 30 01:01:14 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 30 01:01:14 rivoplus volumio[10649]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 30 01:01:14 rivoplus systemd[1]: Started RAAT DAEMON. Oct 30 01:01:14 rivoplus sudo[17958]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:14 rivoplus volumio[10649]: info: Raat Daemon started successfully Oct 30 01:01:14 rivoplus volumio[10649]: info: Executing endpoint restartRAATSocket Oct 30 01:01:14 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 30 01:01:14 rivoplus volumio[10649]: info: TidalConnect service stoped! Oct 30 01:01:14 rivoplus volumio[10649]: 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 30 01:01:15 rivoplus volumio[10649]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 30 01:01:15 rivoplus volumio[10649]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 30 01:01:15 rivoplus sudo[17981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 30 01:01:15 rivoplus sudo[17981]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:15 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Oct 30 01:01:15 rivoplus sudo[17981]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:15 rivoplus volumio[10649]: info: Executing endpoint tc_getconfig Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 30 01:01:15 rivoplus vtcs[17983]: STARTING TidalConnect services, version: 1.5.1-beta1 Oct 30 01:01:15 rivoplus vtcs[17983]: STARTED TidalConnect services. Oct 30 01:01:15 rivoplus volumio[10649]: info: Executing endpoint tc_connect Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 30 01:01:15 rivoplus volumio[10649]: info: Connecting to TidalConnect Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::servicePushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:15 rivoplus volumio[10649]: info: CorePlayQueue::getTrack 0 Oct 30 01:01:15 rivoplus volumio[10649]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 30 01:01:15 rivoplus volumio[10649]: verbose: CURRENT POSITION 0 Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::syncState stateService stop Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::syncState currentStatus pause Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::servicePushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:15 rivoplus volumio[10649]: info: CorePlayQueue::getTrack 0 Oct 30 01:01:15 rivoplus volumio[10649]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 30 01:01:15 rivoplus volumio[10649]: verbose: CURRENT POSITION 0 Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::syncState stateService stop Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::syncState currentStatus pause Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:15 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:15 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:15 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:15 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::servicePushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::getcurrentVolume Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 01:01:15 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:15 rivoplus volumio[10649]: info: VolumeController:: Volume=100 Mute =false Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreStateMachine::updateTrackBlock Oct 30 01:01:15 rivoplus volumio[10649]: info: CorePlayQueue::getTrackBlock Oct 30 01:01:15 rivoplus volumio[10649]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 01:01:15 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:15 rivoplus volumio[10649]: info: TidalConnect service started! Oct 30 01:01:15 rivoplus volumio[10649]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 30 01:01:15 rivoplus volumio[10649]: xcb_connection_has_error() returned true Oct 30 01:01:16 rivoplus volumio[10649]: info: RAAT: Requesting Headphone Status Oct 30 01:01:16 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 30 01:01:16 rivoplus volumio[10649]: info: VolumeController:: Volume=undefined Mute =false Oct 30 01:01:16 rivoplus volumio[10649]: info: CoreStateMachine::pushState Oct 30 01:01:16 rivoplus volumio[10649]: info: CoreCommandRouter::volumioPushState Oct 30 01:01:16 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:16 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:16 rivoplus volumio[10649]: info: MCU Signalled Headphone Mode Disabled Oct 30 01:01:16 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 30 01:01:16 rivoplus volumio[10649]: info: Reporting Headphone State: false Oct 30 01:01:16 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:16 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:16 rivoplus volumio[10649]: info: TidalConnect service started! Oct 30 01:01:16 rivoplus mpd[17931]: Oct 30 01:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 30 01:01:16 rivoplus systemd[1]: Started Music Player Daemon. Oct 30 01:01:16 rivoplus sudo[17904]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:16 rivoplus volumio[10649]: error: updateQueue error: null Oct 30 01:01:16 rivoplus volumio[10649]: info: TidalConnect service stoped! Oct 30 01:01:16 rivoplus volumio[10649]: info: TidalConnect service stoped! Oct 30 01:01:16 rivoplus volumio[10649]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 30 01:01:16 rivoplus volumio[10649]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 30 01:01:16 rivoplus sudo[18021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 30 01:01:16 rivoplus sudo[18021]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 01:01:17 rivoplus sudo[18021]: pam_unix(sudo:session): session closed for user root Oct 30 01:01:17 rivoplus volumio[10649]: info: RAAT: Requesting Headphone Status Oct 30 01:01:17 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 30 01:01:17 rivoplus volumio[10649]: info: MCU Signalled Headphone Mode Disabled Oct 30 01:01:17 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 30 01:01:17 rivoplus volumio[10649]: info: Reporting Headphone State: false Oct 30 01:01:17 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 01:01:17 rivoplus volumio[10649]: info: Updating RAAT Signal Path Oct 30 01:01:18 rivoplus volumio[10649]: info: TidalConnect service started! Oct 30 01:01:20 rivoplus volumio[10649]: info: TidalConnect service started! Oct 30 01:01:24 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetState Oct 30 01:01:26 rivoplus volumio[10649]: info: Executing endpoint metavolumio Oct 30 01:01:26 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 30 01:01:26 rivoplus volumio[10649]: info: Executing endpoint metavolumio Oct 30 01:01:26 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 30 01:01:26 rivoplus volumio[10649]: info: Executing endpoint metavolumio Oct 30 01:01:26 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 30 01:01:31 rivoplus volumio[10649]: info: CoreCommandRouter::volumioGetQueue Oct 30 01:01:31 rivoplus volumio[10649]: info: CoreStateMachine::getQueue Oct 30 01:01:31 rivoplus volumio[10649]: info: CorePlayQueue::getQueue Oct 30 01:01:45 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 01:01:45 rivoplus volumio[10649]: info: Preload queue cleared Oct 30 01:01:47 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 01:01:47 rivoplus volumio[10649]: info: Preload queue cleared Oct 30 01:01:49 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 01:01:50 rivoplus volumio[10649]: info: Preload queue cleared Oct 30 01:01:52 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 01:01:52 rivoplus volumio[10649]: info: Preload queue cleared Oct 30 01:02:09 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 01:02:09 rivoplus volumio[10649]: info: Preload queue cleared Oct 30 01:02:32 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 01:02:32 rivoplus volumio[10649]: info: Preload queue cleared Oct 30 01:02:44 rivoplus volumio[10649]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 01:02:44 rivoplus volumio[10649]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 01:02:44 rivoplus volumio[10649]: TypeError: Cannot read property 'length' of undefined Oct 30 01:02:44 rivoplus volumio[10649]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 30 01:02:44 rivoplus volumio[10649]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 30 01:02:44 rivoplus volumio[10649]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 30 01:02:44 rivoplus volumio[10649]: at Parser.emit (events.js:400:28) Oct 30 01:02:44 rivoplus volumio[10649]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 30 01:02:44 rivoplus volumio[10649]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 30 01:02:44 rivoplus volumio[10649]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 30 01:02:44 rivoplus volumio[10649]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 30 01:02:44 rivoplus volumio[10649]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 30 01:02:44 rivoplus volumio[10649]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 30 01:02:44 rivoplus volumio[10649]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 30 01:02:44 rivoplus volumio[10649]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 30 01:02:44 rivoplus volumio[10649]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 30 01:02:44 rivoplus volumio[10649]: at IncomingMessage.emit (events.js:412:35) Oct 30 01:02:44 rivoplus volumio[10649]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 30 01:02:44 rivoplus volumio[10649]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 30 01:02:44 rivoplus volumio[10649]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 01:02:44 rivoplus sudo[18259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-30 01:01 Oct 30 01:02:44 rivoplus sudo[18259]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:23:19 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="ca35d8983c8e34dfa9cca80071cf9105"