-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2026-01-10 16:21:32 CET. -- Jan 10 16:20:52 rivoplus volumio5-onboarding[4114]: time=2026-01-10T15:20:52.796Z level=INFO msg="test audio output" component=server type=REQUEST_TYPE_TEST_AUDIO_OUTPUT peer="192.168.178.110:55202,00:00:00:00:00:00%1c,192.168.178.110:55216 @ 0x2728840" latency=11.290277ms timeout=10s id=5 Jan 10 16:21:00 rivoplus volumio5-onboarding[4114]: time=2026-01-10T15:21:00.883Z level=INFO msg="test audio output" component=server type=REQUEST_TYPE_TEST_AUDIO_OUTPUT peer="192.168.178.110:55202,00:00:00:00:00:00%1c,192.168.178.110:55216 @ 0x2728840" latency=10.979323ms timeout=10s id=5 Jan 10 16:21:08 rivoplus volumio5-onboarding[4114]: time=2026-01-10T15:21:08.261Z level=INFO msg="set audio output" component=server type=REQUEST_TYPE_SET_AUDIO_OUTPUT peer="192.168.178.110:55202,00:00:00:00:00:00%1c,192.168.178.110:55216 @ 0x2728840" latency=11.73441ms timeout=10s id=5 Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Jan 10 16:21:08 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:08 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 10 16:21:08 rivoplus volumio[3216]: info: Preparing to save Alsa Options, stopping services first Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:08 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::volumioPause Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreStateMachine::pause Jan 10 16:21:08 rivoplus volumio[3216]: info: Saving Audio Output to: {"i2s":false,"i2sid":{"value":"","label":""},"output_device":{"value":"5","label":"S/PDIF + AES/EBU + I2S"},"disallowPush":true} Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 16:21:08 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:08 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:08 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:08 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 16:21:08 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:08 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:08 rivoplus volumio[3216]: info: Setting mixer PCM for card S/PDIF + AES/EBU + I2S Jan 10 16:21:08 rivoplus volumio[3216]: info: QobuzConnect: setDeactiveState invoked Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:08 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:08 rivoplus volumio[3216]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Jan 10 16:21:08 rivoplus vtcs[4806]: [2026-01-10 16:21:08.586] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jan 10 16:21:08 rivoplus vtcs[4806]: [2026-01-10 16:21:08.589] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 10 16:21:08 rivoplus sudo[7072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 10 16:21:08 rivoplus sudo[7072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:08 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Jan 10 16:21:08 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 10 16:21:08 rivoplus systemd[1]: vtcs.service: Succeeded. Jan 10 16:21:08 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Jan 10 16:21:08 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:08 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 10 16:21:08 rivoplus volumio[3216]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 10 16:21:08 rivoplus volumio[3216]: info: Disabling external Volume Control Jan 10 16:21:08 rivoplus sudo[7072]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 16:21:08 rivoplus volumio[3216]: info: Preparing to generate the ALSA configuration file Jan 10 16:21:08 rivoplus sudo[7080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 10 16:21:08 rivoplus sudo[7080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:08 rivoplus sudo[7080]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Jan 10 16:21:08 rivoplus sudo[7087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 10 16:21:08 rivoplus sudo[7087]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:08 rivoplus sudo[7087]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:08 rivoplus sudo[7091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 10 16:21:08 rivoplus sudo[7091]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:08 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:08 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 16:21:08 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 16:21:08 rivoplus volumio[3216]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Jan 10 16:21:08 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 10 16:21:08 rivoplus qobuz-connect[4817]: 20260110 16:21:08.936 [4817.4817] INFO SampleApp: Stopping Local configuration server Jan 10 16:21:08 rivoplus qobuz-connect[4817]: 20260110 16:21:08.947 [4817.4817] INFO SampleApp: shat down connection on UNIX socket Jan 10 16:21:08 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jan 10 16:21:08 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 10 16:21:08 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jan 10 16:21:08 rivoplus sudo[7091]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:08 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:08 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:08 rivoplus volumio[3216]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Jan 10 16:21:08 rivoplus volumio[3216]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Jan 10 16:21:09 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:09 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:09 rivoplus volumio[3216]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Jan 10 16:21:09 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 16:21:09 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 16:21:09 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 10 16:21:09 rivoplus volumio[3216]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 10 16:21:09 rivoplus volumio[3216]: info: Reading ALSA contributions from plugins. Jan 10 16:21:09 rivoplus volumio[3216]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 10 16:21:09 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: Not Reporting Auto name since its the default one Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Jan 10 16:21:09 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:09 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 16:21:09 rivoplus volumio[3216]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Jan 10 16:21:09 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:09 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:09 rivoplus volumio[3216]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Jan 10 16:21:09 rivoplus volumio[3216]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Jan 10 16:21:09 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:09 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:09 rivoplus volumio[3216]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Jan 10 16:21:09 rivoplus volumio[3216]: info: VolumeController:: Volume=undefined Mute =false Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreStateMachine::pushState Jan 10 16:21:09 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::volumioPushState Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:09 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:09 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output update for this device Jan 10 16:21:09 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output Jan 10 16:21:09 rivoplus volumio[3216]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 10 16:21:09 rivoplus volumio[3216]: info: QobuzConnect: setDeactiveState invoked Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:09 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:09 rivoplus volumio[3216]: info: Updating RAAT Signal Path Jan 10 16:21:09 rivoplus volumio[3216]: info: Executing endpoint qc_getconfig Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 10 16:21:09 rivoplus volumio5-onboarding[4114]: time=2026-01-10T15:21:09.468Z level=INFO msg="continue navigation request" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.178.110:55202,00:00:00:00:00:00%1c,192.168.178.110:55216 @ 0x2728840" latency=11.611577ms timeout=10s from=APP_PAGE_SETUP_V1_AUDIO_OUTPUTS Jan 10 16:21:09 rivoplus qobuz-connect[7096]: 20260110 16:21:09.503 [7096.7096] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 10 16:21:09 rivoplus qobuz-connect[7096]: 20260110 16:21:09.511 [7096.7096] INFO VolumeManager: [0xacd899b8]: Setting new playback volume: 75 Jan 10 16:21:09 rivoplus qobuz-connect[7096]: 20260110 16:21:09.512 [7096.7096] INFO VolumeManager: [0xacd899b8]: Setting new mute state: 0 Jan 10 16:21:09 rivoplus qobuz-connect[7096]: 20260110 16:21:09.513 [7096.7096] INFO QobuzConnect: [0xacd8a388]: Client initialized! Jan 10 16:21:09 rivoplus qobuz-connect[7096]: 20260110 16:21:09.513 [7096.7096] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 10 16:21:09 rivoplus qobuz-connect[7096]: 20260110 16:21:09.527 [7096.7096] INFO LocalConfigManager: [0xacd89298]: Starting Local Configuration server Jan 10 16:21:09 rivoplus qobuz-connect[7096]: 20260110 16:21:09.528 [7096.7096] INFO SampleApp: Starting Local configuration server Jan 10 16:21:09 rivoplus qobuz-connect[7096]: 20260110 16:21:09.528 [7096.7096] INFO SampleApp: Connected to UNIX socket client 0xacd7eed8 Jan 10 16:21:09 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:09 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 16:21:09 rivoplus qobuz-connect[7096]: 20260110 16:21:09.653 [7096.7096] INFO SampleApp: Playback volume changed: 75 Jan 10 16:21:09 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:09 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 16:21:09 rivoplus volumio[3216]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 10 16:21:09 rivoplus volumio[3216]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 10 16:21:09 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:09 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:09 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:09 rivoplus sudo[7132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 10 16:21:09 rivoplus sudo[7132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:09 rivoplus sudo[7132]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:09 rivoplus volumio[3216]: info: Asound.conf file unchanged, so no further update is needed Jan 10 16:21:09 rivoplus volumio[3216]: info: Output device has changed, restarting MPD Jan 10 16:21:09 rivoplus sudo[7134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 10 16:21:09 rivoplus sudo[7134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:09 rivoplus systemd[1]: Stopping RAAT DAEMON... Jan 10 16:21:09 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 10 16:21:09 rivoplus sudo[7137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 10 16:21:09 rivoplus sudo[7137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:09 rivoplus volumio[3216]: info: Output device has changed, restarting Shairport Sync Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:09 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 16:21:09 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jan 10 16:21:09 rivoplus systemd[1]: Stopped RAAT DAEMON. Jan 10 16:21:09 rivoplus sudo[7137]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:09 rivoplus systemd[1]: Started RAAT DAEMON. Jan 10 16:21:09 rivoplus sudo[7134]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:09 rivoplus sudo[7145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 10 16:21:09 rivoplus sudo[7145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:09 rivoplus systemd[1]: Stopping Music Player Daemon... Jan 10 16:21:10 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:10 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:10 rivoplus volumio[3216]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 10 16:21:10 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:10 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:10 rivoplus volumio[3216]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: QobuzConnect: setDeactiveState invoked Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:10 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:10 rivoplus volumio[3216]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus systemd[1]: mpd.service: Succeeded. Jan 10 16:21:10 rivoplus systemd[1]: Stopped Music Player Daemon. Jan 10 16:21:10 rivoplus systemd[1]: Starting Music Player Daemon... Jan 10 16:21:10 rivoplus sudo[7177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 10 16:21:10 rivoplus sudo[7177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:10 rivoplus sudo[7179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 10 16:21:10 rivoplus sudo[7179]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:10 rivoplus sudo[7177]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:10 rivoplus sudo[7179]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:10 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 16:21:10 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 10 16:21:10 rivoplus sudo[7187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 10 16:21:10 rivoplus sudo[7187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:10 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 16:21:10 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 10 16:21:10 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 16:21:10 rivoplus volumio[3216]: info: MPD Permissions set Jan 10 16:21:10 rivoplus volumio[3216]: info: Raat Daemon started successfully Jan 10 16:21:10 rivoplus sudo[7187]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:10 rivoplus volumio[3216]: info: Executing endpoint restartRAATSocket Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 10 16:21:10 rivoplus sudo[7176]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 10 16:21:10 rivoplus sudo[7190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 10 16:21:10 rivoplus sudo[7176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:10 rivoplus sudo[7190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:10 rivoplus sudo[7176]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus qobuz-connect[7096]: 20260110 16:21:10.326 [7096.7096] INFO SampleApp: Stopping Local configuration server Jan 10 16:21:10 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 10 16:21:10 rivoplus qobuz-connect[7096]: 20260110 16:21:10.336 [7096.7096] INFO SampleApp: shat down connection on UNIX socket Jan 10 16:21:10 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jan 10 16:21:10 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 10 16:21:10 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jan 10 16:21:10 rivoplus sudo[7190]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:10 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 16:21:10 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 10 16:21:10 rivoplus volumio[3216]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 10 16:21:10 rivoplus volumio[3216]: info: QobuzConnect: setDeactiveState invoked Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:10 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:10 rivoplus volumio[3216]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 10 16:21:10 rivoplus volumio[3216]: info: Starting Shairport Sync Jan 10 16:21:10 rivoplus sudo[7205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 10 16:21:10 rivoplus sudo[7205]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:10 rivoplus volumio[3216]: info: Executing endpoint qc_getconfig Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 10 16:21:10 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 10 16:21:10 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jan 10 16:21:10 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 10 16:21:10 rivoplus qobuz-connect[7197]: 20260110 16:21:10.559 [7197.7197] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 10 16:21:10 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 10 16:21:10 rivoplus qobuz-connect[7197]: 20260110 16:21:10.568 [7197.7197] INFO VolumeManager: [0xac13a9b8]: Setting new playback volume: 75 Jan 10 16:21:10 rivoplus qobuz-connect[7197]: 20260110 16:21:10.569 [7197.7197] INFO VolumeManager: [0xac13a9b8]: Setting new mute state: 0 Jan 10 16:21:10 rivoplus qobuz-connect[7197]: 20260110 16:21:10.570 [7197.7197] INFO QobuzConnect: [0xac13b388]: Client initialized! Jan 10 16:21:10 rivoplus qobuz-connect[7197]: 20260110 16:21:10.570 [7197.7197] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 10 16:21:10 rivoplus sudo[7205]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:10 rivoplus qobuz-connect[7197]: 20260110 16:21:10.611 [7197.7197] INFO LocalConfigManager: [0xac13a298]: Starting Local Configuration server Jan 10 16:21:10 rivoplus qobuz-connect[7197]: 20260110 16:21:10.611 [7197.7197] INFO SampleApp: Starting Local configuration server Jan 10 16:21:10 rivoplus qobuz-connect[7197]: 20260110 16:21:10.612 [7197.7197] INFO SampleApp: Connected to UNIX socket client 0xac12fed8 Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 10 16:21:10 rivoplus volumio[3216]: info: Not Reporting Auto name since its the default one Jan 10 16:21:10 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 16:21:10 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 10 16:21:10 rivoplus volumio[3216]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 10 16:21:10 rivoplus volumio[3216]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 10 16:21:10 rivoplus volumio[3216]: info: Shairport-Sync Started Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 10 16:21:10 rivoplus qobuz-connect[7197]: 20260110 16:21:10.709 [7197.7197] INFO SampleApp: Playback volume changed: 75 Jan 10 16:21:10 rivoplus volumio[3216]: No protocol specified Jan 10 16:21:10 rivoplus volumio[3216]: xcb_connection_has_error() returned true Jan 10 16:21:10 rivoplus sudo[7222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 10 16:21:10 rivoplus sudo[7222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:10 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:10 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:10 rivoplus sudo[7222]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:10 rivoplus sudo[7224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 10 16:21:10 rivoplus sudo[7224]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:10 rivoplus systemd[1]: Stopping RAAT DAEMON... Jan 10 16:21:10 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 10 16:21:10 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jan 10 16:21:10 rivoplus systemd[1]: Stopped RAAT DAEMON. Jan 10 16:21:10 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 16:21:10 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 10 16:21:10 rivoplus systemd[1]: Started RAAT DAEMON. Jan 10 16:21:10 rivoplus sudo[7224]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:10 rivoplus volumio[3216]: info: Raat Daemon started successfully Jan 10 16:21:11 rivoplus volumio[3216]: info: Executing endpoint restartRAATSocket Jan 10 16:21:11 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 10 16:21:11 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:11 rivoplus volumio[3216]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 10 16:21:11 rivoplus volumio[3216]: info: TidalConnect service stoped! Jan 10 16:21:11 rivoplus volumio[3216]: info: TidalConnect service stoped! Jan 10 16:21:11 rivoplus volumio[3216]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 10 16:21:11 rivoplus volumio[3216]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 10 16:21:11 rivoplus sudo[7246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 10 16:21:11 rivoplus sudo[7246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:11 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Jan 10 16:21:11 rivoplus sudo[7246]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:12 rivoplus mpd[7196]: Jan 10 16:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 10 16:21:12 rivoplus systemd[1]: Started Music Player Daemon. Jan 10 16:21:12 rivoplus sudo[7145]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:12 rivoplus volumio[3216]: error: updateQueue error: null Jan 10 16:21:12 rivoplus volumio[3216]: info: Executing endpoint tc_getconfig Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 10 16:21:12 rivoplus vtcs[7248]: STARTING TidalConnect services, version: 1.5.2.56 Jan 10 16:21:12 rivoplus vtcs[7248]: STARTED TidalConnect services. Jan 10 16:21:12 rivoplus volumio[3216]: info: Executing endpoint tc_connect Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 10 16:21:12 rivoplus volumio[3216]: info: Connecting to TidalConnect Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::servicePushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::pushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioPushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output update for this device Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: 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} Jan 10 16:21:12 rivoplus volumio[3216]: verbose: CURRENT POSITION 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::syncState stateService stop Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::syncState currentStatus stop Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::pushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioPushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output update for this device Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output Jan 10 16:21:12 rivoplus volumio[3216]: info: No code Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::pushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioPushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output update for this device Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::servicePushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::pushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioPushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output update for this device Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: 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} Jan 10 16:21:12 rivoplus volumio[3216]: verbose: CURRENT POSITION 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::syncState stateService stop Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::syncState currentStatus stop Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::pushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioPushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output update for this device Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output Jan 10 16:21:12 rivoplus volumio[3216]: info: No code Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::pushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioPushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output update for this device Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output Jan 10 16:21:12 rivoplus volumio[3216]: info: Updating RAAT Signal Path Jan 10 16:21:12 rivoplus volumio[3216]: info: Updating RAAT Signal Path Jan 10 16:21:12 rivoplus volumio[3216]: info: Updating RAAT Signal Path Jan 10 16:21:12 rivoplus volumio[3216]: info: Updating RAAT Signal Path Jan 10 16:21:12 rivoplus volumio[3216]: info: Updating RAAT Signal Path Jan 10 16:21:12 rivoplus volumio[3216]: info: Updating RAAT Signal Path Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::getcurrentVolume Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 16:21:12 rivoplus volumio[3216]: info: RAAT: Requesting Headphone Status Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 10 16:21:12 rivoplus volumio[3216]: info: VolumeController:: Volume=41 Mute =false Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::pushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioPushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output update for this device Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::updateTrackBlock Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrackBlock Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioRetrievevolume Jan 10 16:21:12 rivoplus volumio[3216]: info: Updating RAAT Signal Path Jan 10 16:21:12 rivoplus volumio[3216]: info: MCU Signalled Headphone Mode Disabled Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 10 16:21:12 rivoplus volumio[3216]: info: Reporting Headphone State: false Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:12 rivoplus volumio[3216]: info: Updating RAAT Signal Path Jan 10 16:21:12 rivoplus volumio[3216]: info: VolumeController:: Volume=41 Mute =false Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreStateMachine::pushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioPushState Jan 10 16:21:12 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:12 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output update for this device Jan 10 16:21:12 rivoplus volumio[3216]: info: MRS: Pushing multiroomSync output Jan 10 16:21:12 rivoplus volumio[3216]: info: Updating RAAT Signal Path Jan 10 16:21:13 rivoplus volumio[3216]: info: TidalConnect service stoped! Jan 10 16:21:13 rivoplus volumio[3216]: info: TidalConnect service stoped! Jan 10 16:21:13 rivoplus volumio[3216]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 10 16:21:13 rivoplus volumio[3216]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 10 16:21:13 rivoplus sudo[7284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 10 16:21:13 rivoplus sudo[7284]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:13 rivoplus sudo[7284]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:13 rivoplus volumio[3216]: info: RAAT: Requesting Headphone Status Jan 10 16:21:13 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 10 16:21:13 rivoplus volumio[3216]: info: MCU Signalled Headphone Mode Disabled Jan 10 16:21:13 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 10 16:21:13 rivoplus volumio[3216]: info: Reporting Headphone State: false Jan 10 16:21:13 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:13 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 16:21:13 rivoplus volumio[3216]: info: Updating RAAT Signal Path Jan 10 16:21:14 rivoplus volumio5-onboarding[4114]: time=2026-01-10T15:21:14.726Z level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=28 Jan 10 16:21:15 rivoplus volumio[3216]: info: TidalConnect service started! Jan 10 16:21:15 rivoplus volumio5-onboarding[4114]: time=2026-01-10T15:21:15.025Z level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=1c00 addr=28 Jan 10 16:21:15 rivoplus sudo[7288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 10 16:21:15 rivoplus sudo[7288]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 16:21:15 rivoplus systemd[1]: Stopping MPD Monitor Service... Jan 10 16:21:15 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 10 16:21:15 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Jan 10 16:21:15 rivoplus systemd[1]: Stopped MPD Monitor Service. Jan 10 16:21:15 rivoplus systemd[1]: Started MPD Monitor Service. Jan 10 16:21:15 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 10 16:21:15 rivoplus volumio[3216]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 10 16:21:15 rivoplus sudo[7288]: pam_unix(sudo:session): session closed for user root Jan 10 16:21:15 rivoplus volumio[3216]: info: Successfully started MPD Monitor Jan 10 16:21:15 rivoplus mpd_monitor.sh[7290]: MPD Monitor Service: Starting MPD Monitor Service Jan 10 16:21:16 rivoplus volumio[3216]: info: TidalConnect service started! Jan 10 16:21:28 rivoplus volumio5-onboarding[4114]: time=2026-01-10T15:21:28.590Z level=INFO msg="enable music provider" component=server type=REQUEST_TYPE_ENABLE_MUSIC_PROVIDER peer="192.168.178.110:55202,00:00:00:00:00:00%1c,192.168.178.110:55216 @ 0x2728840" latency=15.963003ms timeout=10s provider=soundcloud Jan 10 16:21:28 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 16:21:28 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 16:21:28 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 10 16:21:29 rivoplus volumio[3216]: info: Received Get System Info Jan 10 16:21:29 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 16:21:29 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 16:21:29 rivoplus volumio[3216]: info: Discovery: Getting this device information Jan 10 16:21:29 rivoplus volumio[3216]: info: CoreCommandRouter::volumioGetState Jan 10 16:21:29 rivoplus volumio[3216]: info: CorePlayQueue::getTrack 0 Jan 10 16:21:29 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 16:21:29 rivoplus volumio5-onboarding[4114]: time=2026-01-10T15:21:29.367Z level=INFO msg="installing required plugin for music provider" component=server type=REQUEST_TYPE_ENABLE_MUSIC_PROVIDER peer="192.168.178.110:55202,00:00:00:00:00:00%1c,192.168.178.110:55216 @ 0x2728840" latency=15.963003ms timeout=10s provider=soundcloud plugin=soundcloud Jan 10 16:21:29 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 16:21:29 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 16:21:29 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 10 16:21:30 rivoplus volumio[3216]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/rivoplus/buster/armhf Jan 10 16:21:31 rivoplus volumio[3216]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/rivoplus/buster/armhf Jan 10 16:21:31 rivoplus volumio[3216]: info: Folder /tmp/plugins removed Jan 10 16:21:31 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 16:21:31 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 16:21:31 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 10 16:21:31 rivoplus volumio[3216]: info: Check plugin dependencies Jan 10 16:21:31 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 16:21:31 rivoplus volumio[3216]: info: Checking if plugin already exists Jan 10 16:21:31 rivoplus volumio[3216]: info: Rename folder Jan 10 16:21:31 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 16:21:31 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 16:21:31 rivoplus volumio[3216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 16:21:31 rivoplus volumio[3216]: info: Folder /tmp/downloaded_plugin.zip removed Jan 10 16:21:31 rivoplus volumio[3216]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 16:21:31 rivoplus volumio[3216]: TypeError: Cannot read property 'plugin_type' of undefined Jan 10 16:21:31 rivoplus volumio[3216]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Jan 10 16:21:31 rivoplus volumio[3216]: at PluginManager.getAvailablePlugins (/volumio/app/pluginmanager.js:1691:24) Jan 10 16:21:31 rivoplus volumio[3216]: at CoreCommandRouter.getAvailablePlugins (/volumio/app/index.js:1544:29) Jan 10 16:21:31 rivoplus volumio[3216]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1365:45) Jan 10 16:21:31 rivoplus volumio[3216]: at Socket.emit (events.js:400:28) Jan 10 16:21:31 rivoplus volumio[3216]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 10 16:21:31 rivoplus volumio[3216]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jan 10 16:21:31 rivoplus volumio[3216]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 16:21:32 rivoplus sudo[7346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 16:20 Jan 10 16:21:32 rivoplus sudo[7346]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:47:33 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="9e8aa5d6afb5e5d87a87bafae7aa5647"