-- Logs begin at Sun 2026-01-25 16:47:08 CET, end at Sun 2026-01-25 18:09:08 CET. -- Jan 25 18:08:00 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:08:00.397+01:00 level=INFO msg="test audio output" component=server type=REQUEST_TYPE_TEST_AUDIO_OUTPUT peer="192.168.178.37:47630 @ 0x1fa01b0" latency=-11.976097ms timeout=10s id=5 Jan 25 18:08:09 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:08:09.951+01:00 level=INFO msg="set audio output" component=server type=REQUEST_TYPE_SET_AUDIO_OUTPUT peer="192.168.178.37:47630 @ 0x1fa01b0" latency=-8.18128ms timeout=10s id=5 Jan 25 18:08:09 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Jan 25 18:08:10 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 25 18:08:10 rivoplus volumio[3172]: info: Preparing to save Alsa Options, stopping services first Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:10 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::volumioPause Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreStateMachine::pause Jan 25 18:08:10 rivoplus volumio[3172]: info: Saving Audio Output to: {"i2s":false,"i2sid":{"value":"","label":""},"output_device":{"value":"5","label":"S/PDIF + AES/EBU + I2S"},"disallowPush":true} Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 18:08:10 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:10 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 18:08:10 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:10 rivoplus volumio[3172]: info: Setting mixer PCM for card S/PDIF + AES/EBU + I2S Jan 25 18:08:10 rivoplus volumio[3172]: info: QobuzConnect: setDeactiveState invoked Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:10 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:10 rivoplus volumio[3172]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Jan 25 18:08:10 rivoplus vtcs[5102]: [2026-01-25 18:08:10.231] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 25 18:08:10 rivoplus vtcs[5102]: [2026-01-25 18:08:10.237] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=7 errorno=104 error=Connection reset by peer Jan 25 18:08:10 rivoplus vtcs[5102]: [2026-01-25 18:08:10.237] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jan 25 18:08:10 rivoplus sudo[5774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 18:08:10 rivoplus sudo[5774]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:10 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Jan 25 18:08:10 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 25 18:08:10 rivoplus systemd[1]: vtcs.service: Succeeded. Jan 25 18:08:10 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Jan 25 18:08:10 rivoplus sudo[5774]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:10 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 25 18:08:10 rivoplus volumio[3172]: 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 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 25 18:08:10 rivoplus volumio[3172]: info: Disabling external Volume Control Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Jan 25 18:08:10 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 18:08:10 rivoplus volumio[3172]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Jan 25 18:08:10 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:10 rivoplus volumio[3172]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Jan 25 18:08:10 rivoplus volumio[3172]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Jan 25 18:08:10 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:10 rivoplus volumio[3172]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Jan 25 18:08:10 rivoplus volumio[3172]: info: Preparing to generate the ALSA configuration file Jan 25 18:08:10 rivoplus sudo[5789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 18:08:10 rivoplus sudo[5789]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:10 rivoplus sudo[5789]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Jan 25 18:08:10 rivoplus sudo[5796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 25 18:08:10 rivoplus sudo[5796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:10 rivoplus sudo[5796]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:10 rivoplus sudo[5800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 25 18:08:10 rivoplus sudo[5800]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:10 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 18:08:10 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 18:08:10 rivoplus volumio[3172]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Jan 25 18:08:10 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 25 18:08:10 rivoplus qobuz-connect[5052]: 20260125 18:08:10.802 [5052.5052] INFO SampleApp: Stopping Local configuration server Jan 25 18:08:10 rivoplus qobuz-connect[5052]: 20260125 18:08:10.813 [5052.5052] INFO SampleApp: shat down connection on UNIX socket Jan 25 18:08:10 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jan 25 18:08:10 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 25 18:08:10 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jan 25 18:08:10 rivoplus sudo[5800]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:10 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:10 rivoplus volumio[3172]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Jan 25 18:08:10 rivoplus volumio[3172]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Jan 25 18:08:10 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:10 rivoplus volumio[3172]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Jan 25 18:08:10 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 25 18:08:10 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 25 18:08:10 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 25 18:08:10 rivoplus volumio[3172]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 25 18:08:10 rivoplus volumio[3172]: info: Reading ALSA contributions from plugins. Jan 25 18:08:10 rivoplus volumio[3172]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 25 18:08:10 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: Not Reporting Auto name since its the default one Jan 25 18:08:11 rivoplus volumio[3172]: info: VolumeController:: Volume=undefined Mute =false Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreStateMachine::pushState Jan 25 18:08:11 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::volumioPushState Jan 25 18:08:11 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:08:11.056+01:00 level=INFO msg="continue navigation request" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.178.37:47630 @ 0x1fa01b0" latency=-13.702888ms timeout=10s from=APP_PAGE_SETUP_V1_AUDIO_OUTPUTS Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:11 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:11 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:08:11 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output Jan 25 18:08:11 rivoplus volumio[3172]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 25 18:08:11 rivoplus volumio[3172]: info: QobuzConnect: setDeactiveState invoked Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:11 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:11 rivoplus volumio[3172]: info: Updating RAAT Signal Path Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:11 rivoplus volumio[3172]: info: Executing endpoint qc_getconfig Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 25 18:08:11 rivoplus qobuz-connect[5803]: 20260125 18:08:11.135 [5803.5803] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 25 18:08:11 rivoplus qobuz-connect[5803]: 20260125 18:08:11.142 [5803.5803] INFO VolumeManager: [0xac60d800]: Setting new playback volume: 75 Jan 25 18:08:11 rivoplus qobuz-connect[5803]: 20260125 18:08:11.142 [5803.5803] INFO VolumeManager: [0xac60d800]: Setting new mute state: 0 Jan 25 18:08:11 rivoplus qobuz-connect[5803]: 20260125 18:08:11.142 [5803.5803] INFO QobuzConnect: [0xac60e1d0]: Client initialized! Jan 25 18:08:11 rivoplus qobuz-connect[5803]: 20260125 18:08:11.142 [5803.5803] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 25 18:08:11 rivoplus qobuz-connect[5803]: 20260125 18:08:11.156 [5803.5803] INFO LocalConfigManager: [0xac60d0e0]: Starting Local Configuration server Jan 25 18:08:11 rivoplus qobuz-connect[5803]: 20260125 18:08:11.156 [5803.5803] INFO SampleApp: Starting Local configuration server Jan 25 18:08:11 rivoplus qobuz-connect[5803]: 20260125 18:08:11.157 [5803.5803] INFO SampleApp: Connected to UNIX socket client 0xac602ed8 Jan 25 18:08:11 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:11 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 18:08:11 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:11 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:11 rivoplus qobuz-connect[5803]: 20260125 18:08:11.285 [5803.5803] INFO SampleApp: Playback volume changed: 75 Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:11 rivoplus volumio[3172]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 25 18:08:11 rivoplus volumio[3172]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 25 18:08:11 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:11 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:11 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus sudo[5835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 25 18:08:11 rivoplus sudo[5835]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:11 rivoplus volumio[3172]: info: Asound.conf file unchanged, so no further update is needed Jan 25 18:08:11 rivoplus volumio[3172]: info: Output device has changed, restarting MPD Jan 25 18:08:11 rivoplus sudo[5835]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:11 rivoplus sudo[5838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 25 18:08:11 rivoplus sudo[5838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:11 rivoplus sudo[5839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 18:08:11 rivoplus sudo[5839]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:11 rivoplus volumio[3172]: info: Output device has changed, restarting Shairport Sync Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 18:08:11 rivoplus sudo[5839]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:11 rivoplus systemd[1]: Stopping RAAT DAEMON... Jan 25 18:08:11 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 25 18:08:11 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jan 25 18:08:11 rivoplus systemd[1]: Stopped RAAT DAEMON. Jan 25 18:08:11 rivoplus sudo[5847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 18:08:11 rivoplus sudo[5847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:11 rivoplus sudo[5838]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:11 rivoplus systemd[1]: Started RAAT DAEMON. Jan 25 18:08:11 rivoplus systemd[1]: Stopping Music Player Daemon... Jan 25 18:08:11 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:11 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:11 rivoplus volumio[3172]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 18:08:11 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:11 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:11 rivoplus volumio[3172]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus systemd[1]: mpd.service: Succeeded. Jan 25 18:08:11 rivoplus systemd[1]: Stopped Music Player Daemon. Jan 25 18:08:11 rivoplus volumio[3172]: info: QobuzConnect: setDeactiveState invoked Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:11 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:11 rivoplus volumio[3172]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus systemd[1]: Starting Music Player Daemon... Jan 25 18:08:11 rivoplus sudo[5880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 18:08:11 rivoplus sudo[5880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:11 rivoplus sudo[5882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 18:08:11 rivoplus sudo[5882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:11 rivoplus sudo[5880]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:11 rivoplus sudo[5882]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:11 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 25 18:08:11 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 25 18:08:11 rivoplus sudo[5877]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 18:08:11 rivoplus sudo[5877]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:11 rivoplus sudo[5891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 25 18:08:11 rivoplus sudo[5891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:11 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 25 18:08:11 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 25 18:08:11 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 25 18:08:11 rivoplus sudo[5877]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:11 rivoplus volumio[3172]: info: MPD Permissions set Jan 25 18:08:11 rivoplus volumio[3172]: info: Raat Daemon started successfully Jan 25 18:08:11 rivoplus sudo[5891]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:11 rivoplus volumio[3172]: info: Executing endpoint restartRAATSocket Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:11 rivoplus sudo[5895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 25 18:08:11 rivoplus sudo[5895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 18:08:11 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 25 18:08:11 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 25 18:08:11 rivoplus qobuz-connect[5803]: 20260125 18:08:11.974 [5803.5803] INFO SampleApp: Stopping Local configuration server Jan 25 18:08:11 rivoplus qobuz-connect[5803]: 20260125 18:08:11.985 [5803.5803] INFO SampleApp: shat down connection on UNIX socket Jan 25 18:08:11 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jan 25 18:08:11 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:12 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:12 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jan 25 18:08:12 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 25 18:08:12 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 25 18:08:12 rivoplus sudo[5895]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:12 rivoplus volumio[3172]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 25 18:08:12 rivoplus volumio[3172]: info: QobuzConnect: setDeactiveState invoked Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:12 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:12 rivoplus volumio[3172]: 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 25 18:08:12 rivoplus volumio[3172]: info: Starting Shairport Sync Jan 25 18:08:12 rivoplus sudo[5908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 18:08:12 rivoplus sudo[5908]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:12 rivoplus volumio[3172]: info: Executing endpoint qc_getconfig Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 25 18:08:12 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 25 18:08:12 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jan 25 18:08:12 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 25 18:08:12 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 25 18:08:12 rivoplus sudo[5908]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:12 rivoplus qobuz-connect[5905]: 20260125 18:08:12.178 [5905.5905] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 25 18:08:12 rivoplus qobuz-connect[5905]: 20260125 18:08:12.191 [5905.5905] INFO VolumeManager: [0xab076800]: Setting new playback volume: 75 Jan 25 18:08:12 rivoplus qobuz-connect[5905]: 20260125 18:08:12.191 [5905.5905] INFO VolumeManager: [0xab076800]: Setting new mute state: 0 Jan 25 18:08:12 rivoplus qobuz-connect[5905]: 20260125 18:08:12.191 [5905.5905] INFO QobuzConnect: [0xab0771d0]: Client initialized! Jan 25 18:08:12 rivoplus qobuz-connect[5905]: 20260125 18:08:12.191 [5905.5905] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 25 18:08:12 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 25 18:08:12 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 25 18:08:12 rivoplus volumio[3172]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 25 18:08:12 rivoplus volumio[3172]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 25 18:08:12 rivoplus volumio[3172]: info: Shairport-Sync Started Jan 25 18:08:12 rivoplus qobuz-connect[5905]: 20260125 18:08:12.215 [5905.5905] INFO LocalConfigManager: [0xab0760e0]: Starting Local Configuration server Jan 25 18:08:12 rivoplus qobuz-connect[5905]: 20260125 18:08:12.216 [5905.5905] INFO SampleApp: Starting Local configuration server Jan 25 18:08:12 rivoplus qobuz-connect[5905]: 20260125 18:08:12.217 [5905.5905] INFO SampleApp: Connected to UNIX socket client 0xab06bed8 Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 18:08:12 rivoplus volumio[3172]: info: Not Reporting Auto name since its the default one Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 25 18:08:12 rivoplus qobuz-connect[5905]: 20260125 18:08:12.328 [5905.5905] INFO SampleApp: Playback volume changed: 75 Jan 25 18:08:12 rivoplus volumio[3172]: No protocol specified Jan 25 18:08:12 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:12 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:12 rivoplus sudo[5925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 25 18:08:12 rivoplus sudo[5925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:12 rivoplus sudo[5925]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:12 rivoplus sudo[5927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 25 18:08:12 rivoplus sudo[5927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:12 rivoplus systemd[1]: Stopping RAAT DAEMON... Jan 25 18:08:12 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 25 18:08:12 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jan 25 18:08:12 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 25 18:08:12 rivoplus systemd[1]: Stopped RAAT DAEMON. Jan 25 18:08:12 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 25 18:08:12 rivoplus systemd[1]: Started RAAT DAEMON. Jan 25 18:08:12 rivoplus sudo[5927]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:12 rivoplus volumio[3172]: info: Raat Daemon started successfully Jan 25 18:08:12 rivoplus volumio[3172]: info: Executing endpoint restartRAATSocket Jan 25 18:08:12 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 25 18:08:12 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:12 rivoplus volumio[3172]: 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 25 18:08:13 rivoplus volumio[3172]: info: TidalConnect service stoped! Jan 25 18:08:13 rivoplus volumio[3172]: info: TidalConnect service stoped! Jan 25 18:08:13 rivoplus volumio[3172]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 25 18:08:13 rivoplus volumio[3172]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 25 18:08:13 rivoplus mpd[5896]: Jan 25 18:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 25 18:08:13 rivoplus systemd[1]: Started Music Player Daemon. Jan 25 18:08:13 rivoplus sudo[5847]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:13 rivoplus sudo[5951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 25 18:08:13 rivoplus sudo[5951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:13 rivoplus volumio[3172]: error: updateQueue error: null Jan 25 18:08:13 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Jan 25 18:08:13 rivoplus sudo[5951]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:14 rivoplus volumio[3172]: info: Executing endpoint tc_getconfig Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 25 18:08:14 rivoplus vtcs[5956]: STARTING TidalConnect services, version: 1.5.2.56 Jan 25 18:08:14 rivoplus vtcs[5956]: STARTED TidalConnect services. Jan 25 18:08:14 rivoplus volumio[3172]: info: Executing endpoint tc_connect Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 25 18:08:14 rivoplus volumio[3172]: info: Connecting to TidalConnect Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::servicePushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::pushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioPushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: 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 25 18:08:14 rivoplus volumio[3172]: verbose: CURRENT POSITION 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::syncState stateService stop Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::pushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioPushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output Jan 25 18:08:14 rivoplus volumio[3172]: info: No code Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::pushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioPushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::servicePushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::pushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioPushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: 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 25 18:08:14 rivoplus volumio[3172]: verbose: CURRENT POSITION 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::syncState stateService stop Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::syncState currentStatus stop Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::pushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioPushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output Jan 25 18:08:14 rivoplus volumio[3172]: info: No code Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::pushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioPushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output Jan 25 18:08:14 rivoplus volumio[3172]: info: Updating RAAT Signal Path Jan 25 18:08:14 rivoplus volumio[3172]: info: Updating RAAT Signal Path Jan 25 18:08:14 rivoplus volumio[3172]: info: Updating RAAT Signal Path Jan 25 18:08:14 rivoplus volumio[3172]: info: Updating RAAT Signal Path Jan 25 18:08:14 rivoplus volumio[3172]: info: Updating RAAT Signal Path Jan 25 18:08:14 rivoplus volumio[3172]: info: Updating RAAT Signal Path Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::getcurrentVolume Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 18:08:14 rivoplus volumio[3172]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 25 18:08:14 rivoplus volumio[3172]: xcb_connection_has_error() returned true Jan 25 18:08:14 rivoplus volumio[3172]: info: RAAT: Requesting Headphone Status Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 25 18:08:14 rivoplus volumio[3172]: info: MCU Signalled Headphone Mode Disabled Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 25 18:08:14 rivoplus volumio[3172]: info: Reporting Headphone State: false Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:14 rivoplus volumio[3172]: info: Updating RAAT Signal Path Jan 25 18:08:14 rivoplus volumio[3172]: info: VolumeController:: Volume=undefined Mute =false Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::pushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioPushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::updateTrackBlock Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrackBlock Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 18:08:14 rivoplus volumio[3172]: info: Updating RAAT Signal Path Jan 25 18:08:14 rivoplus volumio[3172]: info: VolumeController:: Volume=41 Mute =false Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreStateMachine::pushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioPushState Jan 25 18:08:14 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:14 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:08:14 rivoplus volumio[3172]: info: MRS: Pushing multiroomSync output Jan 25 18:08:14 rivoplus volumio[3172]: info: Updating RAAT Signal Path Jan 25 18:08:14 rivoplus volumio[3172]: info: TidalConnect service stoped! Jan 25 18:08:14 rivoplus volumio[3172]: info: TidalConnect service stoped! Jan 25 18:08:14 rivoplus volumio[3172]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 25 18:08:14 rivoplus volumio[3172]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 25 18:08:14 rivoplus sudo[5988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 25 18:08:14 rivoplus sudo[5988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:14 rivoplus sudo[5988]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:15 rivoplus volumio[3172]: info: RAAT: Requesting Headphone Status Jan 25 18:08:15 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 25 18:08:15 rivoplus volumio[3172]: info: MCU Signalled Headphone Mode Disabled Jan 25 18:08:15 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 25 18:08:15 rivoplus volumio[3172]: info: Reporting Headphone State: false Jan 25 18:08:15 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:15 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 18:08:15 rivoplus volumio[3172]: info: Updating RAAT Signal Path Jan 25 18:08:16 rivoplus sudo[5992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 25 18:08:16 rivoplus sudo[5992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:16 rivoplus systemd[1]: Stopping MPD Monitor Service... Jan 25 18:08:16 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 25 18:08:16 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Jan 25 18:08:16 rivoplus systemd[1]: Stopped MPD Monitor Service. Jan 25 18:08:16 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 25 18:08:16 rivoplus volumio[3172]: info: TidalConnect service started! Jan 25 18:08:16 rivoplus volumio[3172]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 25 18:08:16 rivoplus systemd[1]: Started MPD Monitor Service. Jan 25 18:08:16 rivoplus sudo[5992]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:16 rivoplus volumio[3172]: info: Successfully started MPD Monitor Jan 25 18:08:16 rivoplus mpd_monitor.sh[5994]: MPD Monitor Service: Starting MPD Monitor Service Jan 25 18:08:17 rivoplus volumio[3172]: info: TidalConnect service started! Jan 25 18:08:27 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:08:27.537+01:00 level=INFO msg="enable music provider" component=server type=REQUEST_TYPE_ENABLE_MUSIC_PROVIDER peer="192.168.178.37:47630 @ 0x1fa01b0" latency=-14.039832ms timeout=10s provider=radioparadise Jan 25 18:08:27 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:27 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:27 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:28 rivoplus volumio[3172]: info: Received Get System Info Jan 25 18:08:28 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:08:28 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:08:28 rivoplus volumio[3172]: info: Discovery: Getting this device information Jan 25 18:08:28 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:28 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:28 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:08:28 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:08:28.288+01:00 level=INFO msg="installing required plugin for music provider" component=server type=REQUEST_TYPE_ENABLE_MUSIC_PROVIDER peer="192.168.178.37:47630 @ 0x1fa01b0" latency=-14.039832ms timeout=10s provider=radioparadise plugin=radio_paradise Jan 25 18:08:28 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:28 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:28 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:29 rivoplus volumio[3172]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/radio_paradise/rivoplus/buster/armhf Jan 25 18:08:30 rivoplus volumio[3172]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/radio_paradise/rivoplus/buster/armhf Jan 25 18:08:30 rivoplus volumio[3172]: info: Folder /tmp/plugins removed Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:30 rivoplus volumio[3172]: info: Check plugin dependencies Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 18:08:30 rivoplus volumio[3172]: info: Checking if plugin already exists Jan 25 18:08:30 rivoplus volumio[3172]: info: Rename folder Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: Folder /tmp/downloaded_plugin.zip removed Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:30 rivoplus volumio[3172]: info: Move to category Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:30 rivoplus volumio[3172]: info: Checking if install.sh is present Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: Executing install.sh Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:30 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:31 rivoplus sudo[6031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/radio_paradise/install.sh Jan 25 18:08:31 rivoplus sudo[6031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:31 rivoplus sudo[6031]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:31 rivoplus volumio[3172]: info: Install script completed Jan 25 18:08:31 rivoplus volumio[3172]: info: Adding reference to registry Jan 25 18:08:31 rivoplus volumio[3172]: info: Done installing plugin. Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:31 rivoplus volumio[3172]: info: Installing radio paradise Dependencies Jan 25 18:08:31 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:08:31.053+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 25 18:08:31 rivoplus volumio[3172]: Plugin install end detected on script Jan 25 18:08:31 rivoplus volumio[3172]: info: Folder /tmp/plugins removed Jan 25 18:08:31 rivoplus volumio[3172]: info: Folder /tmp/downloaded_plugin.zip removed Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:31 rivoplus volumio[3172]: info: Folder /data/temp removed Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:31 rivoplus volumio[3172]: info: Enabling plugin radio_paradise Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:31 rivoplus volumio[3172]: info: Loading plugin "radio_paradise"... Jan 25 18:08:31 rivoplus volumio[3172]: info: [1769360911810] [RadioParadise] API delay: 5 Jan 25 18:08:31 rivoplus volumio[3172]: info: PLUGIN START: radio_paradise Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 18:08:31 rivoplus volumio[3172]: info: [1769360911821] CoreMusicLibrary::Adding element Radio Paradise Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 18:08:31 rivoplus volumio[3172]: Cannot find translation for source Radio Paradise Jan 25 18:08:31 rivoplus volumio[3172]: info: Done. Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:31 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:43 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:08:43.712+01:00 level=INFO msg="enable music provider" component=server type=REQUEST_TYPE_ENABLE_MUSIC_PROVIDER peer="192.168.178.37:47630 @ 0x1fa01b0" latency=-12.601604ms timeout=10s provider=8080radio Jan 25 18:08:43 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:43 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:43 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:44 rivoplus volumio[3172]: info: Received Get System Info Jan 25 18:08:44 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:08:44 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:08:44 rivoplus volumio[3172]: info: Discovery: Getting this device information Jan 25 18:08:44 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:44 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:44 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:08:44 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:08:44.424+01:00 level=INFO msg="installing required plugin for music provider" component=server type=REQUEST_TYPE_ENABLE_MUSIC_PROVIDER peer="192.168.178.37:47630 @ 0x1fa01b0" latency=-12.601604ms timeout=10s provider=8080radio plugin=80s80s Jan 25 18:08:44 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:44 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:44 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:45 rivoplus volumio[3172]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/80s80s/rivoplus/buster/armhf Jan 25 18:08:47 rivoplus volumio[3172]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/80s80s/rivoplus/buster/armhf Jan 25 18:08:47 rivoplus volumio[3172]: info: Folder /tmp/plugins removed Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:47 rivoplus volumio[3172]: info: Check plugin dependencies Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 18:08:47 rivoplus volumio[3172]: info: Checking if plugin already exists Jan 25 18:08:47 rivoplus volumio[3172]: info: Rename folder Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:47 rivoplus volumio[3172]: info: Folder /tmp/downloaded_plugin.zip removed Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:47 rivoplus volumio[3172]: info: Move to category Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:47 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:47 rivoplus volumio[3172]: info: Checking if install.sh is present Jan 25 18:08:47 rivoplus volumio[3172]: info: Executing install.sh Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:48 rivoplus sudo[6126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/80s80s/install.sh Jan 25 18:08:48 rivoplus sudo[6126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:48 rivoplus sudo[6126]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:48 rivoplus volumio[3172]: info: Installing 80s80s radio Dependencies Jan 25 18:08:48 rivoplus volumio[3172]: Plugin install end detected on script Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:48 rivoplus volumio[3172]: info: Install script completed Jan 25 18:08:48 rivoplus volumio[3172]: info: Adding reference to registry Jan 25 18:08:48 rivoplus volumio[3172]: info: Done installing plugin. Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:48 rivoplus volumio[3172]: info: Folder /tmp/plugins removed Jan 25 18:08:48 rivoplus volumio[3172]: info: Folder /tmp/downloaded_plugin.zip removed Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:48 rivoplus volumio[3172]: info: Folder /data/temp removed Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:48 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:48 rivoplus volumio[3172]: info: Enabling plugin 80s80s Jan 25 18:08:48 rivoplus volumio[3172]: info: Loading plugin "80s80s"... Jan 25 18:08:49 rivoplus volumio[3172]: info: [1769360929124] [80s80s] API delay: 30 Jan 25 18:08:49 rivoplus volumio[3172]: info: PLUGIN START: 80s80s Jan 25 18:08:49 rivoplus volumio[3172]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 18:08:49 rivoplus volumio[3172]: info: [1769360929134] CoreMusicLibrary::Adding element 80s80s Radio Jan 25 18:08:49 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 18:08:49 rivoplus volumio[3172]: Cannot find translation for source Radio Paradise Jan 25 18:08:49 rivoplus volumio[3172]: Cannot find translation for source 80s80s Radio Jan 25 18:08:49 rivoplus volumio[3172]: info: Done. Jan 25 18:08:49 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:49 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:49 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:49 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:49 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:49 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:54 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:08:54.296+01:00 level=INFO msg="enable music provider" component=server type=REQUEST_TYPE_ENABLE_MUSIC_PROVIDER peer="192.168.178.37:47630 @ 0x1fa01b0" latency=-9.396905ms timeout=10s provider=calmradio Jan 25 18:08:54 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:54 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:54 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:55 rivoplus volumio[3172]: info: Received Get System Info Jan 25 18:08:55 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:08:55 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:08:55 rivoplus volumio[3172]: info: Discovery: Getting this device information Jan 25 18:08:55 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:08:55 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:08:55 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:08:55 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:08:55.130+01:00 level=INFO msg="installing required plugin for music provider" component=server type=REQUEST_TYPE_ENABLE_MUSIC_PROVIDER peer="192.168.178.37:47630 @ 0x1fa01b0" latency=-9.396905ms timeout=10s provider=calmradio plugin=calmradio Jan 25 18:08:55 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:55 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:55 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:55 rivoplus volumio[3172]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/calmradio/rivoplus/buster/armhf Jan 25 18:08:57 rivoplus volumio[3172]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/calmradio/rivoplus/buster/armhf Jan 25 18:08:57 rivoplus volumio[3172]: info: Folder /tmp/plugins removed Jan 25 18:08:57 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:57 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:57 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:57 rivoplus volumio[3172]: info: Check plugin dependencies Jan 25 18:08:57 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 18:08:57 rivoplus volumio[3172]: info: Checking if plugin already exists Jan 25 18:08:57 rivoplus volumio[3172]: info: Rename folder Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:58 rivoplus volumio[3172]: info: Folder /tmp/downloaded_plugin.zip removed Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:58 rivoplus volumio[3172]: info: Move to category Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:58 rivoplus volumio[3172]: info: Checking if install.sh is present Jan 25 18:08:58 rivoplus volumio[3172]: info: Executing install.sh Jan 25 18:08:58 rivoplus sudo[6163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/calmradio/install.sh Jan 25 18:08:58 rivoplus sudo[6163]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:08:58 rivoplus sudo[6163]: pam_unix(sudo:session): session closed for user root Jan 25 18:08:58 rivoplus volumio[3172]: Plugin install end detected on script Jan 25 18:08:58 rivoplus volumio[3172]: info: Install script completed Jan 25 18:08:58 rivoplus volumio[3172]: info: Adding reference to registry Jan 25 18:08:58 rivoplus volumio[3172]: info: Done installing plugin. Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:58 rivoplus volumio[3172]: info: Folder /tmp/plugins removed Jan 25 18:08:58 rivoplus volumio[3172]: info: Folder /tmp/downloaded_plugin.zip removed Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:58 rivoplus volumio[3172]: info: Folder /data/temp removed Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:08:58 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:08:58 rivoplus volumio[3172]: info: Enabling plugin calmradio Jan 25 18:08:58 rivoplus volumio[3172]: info: Loading plugin "calmradio"... Jan 25 18:09:00 rivoplus volumio[3172]: info: PLUGIN START: calmradio Jan 25 18:09:00 rivoplus volumio[3172]: info: Adding Calm Radio to Browse Sources Jan 25 18:09:00 rivoplus volumio[3172]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 18:09:00 rivoplus volumio[3172]: info: [1769360940038] CoreMusicLibrary::Adding element Calm Radio Jan 25 18:09:00 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 18:09:00 rivoplus volumio[3172]: Cannot find translation for source Radio Paradise Jan 25 18:09:00 rivoplus volumio[3172]: Cannot find translation for source 80s80s Radio Jan 25 18:09:00 rivoplus volumio[3172]: Cannot find translation for source Calm Radio Jan 25 18:09:00 rivoplus volumio[3172]: info: Done. Jan 25 18:09:00 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:00 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:00 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:09:00 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:00 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:00 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:09:03 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:09:03.915+01:00 level=INFO msg="enable music provider" component=server type=REQUEST_TYPE_ENABLE_MUSIC_PROVIDER peer="192.168.178.37:47630 @ 0x1fa01b0" latency=-10.356539ms timeout=10s provider=soundcloud Jan 25 18:09:03 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:03 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:03 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:09:04 rivoplus volumio[3172]: info: Received Get System Info Jan 25 18:09:04 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:09:04 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:09:04 rivoplus volumio[3172]: info: Discovery: Getting this device information Jan 25 18:09:04 rivoplus volumio[3172]: info: CoreCommandRouter::volumioGetState Jan 25 18:09:04 rivoplus volumio[3172]: info: CorePlayQueue::getTrack 0 Jan 25 18:09:04 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:09:04 rivoplus volumio5-onboarding[3826]: time=2026-01-25T18:09:04.590+01:00 level=INFO msg="installing required plugin for music provider" component=server type=REQUEST_TYPE_ENABLE_MUSIC_PROVIDER peer="192.168.178.37:47630 @ 0x1fa01b0" latency=-10.356539ms timeout=10s provider=soundcloud plugin=soundcloud Jan 25 18:09:04 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:04 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:04 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:09:05 rivoplus volumio[3172]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/rivoplus/buster/armhf Jan 25 18:09:07 rivoplus volumio[3172]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/rivoplus/buster/armhf Jan 25 18:09:07 rivoplus volumio[3172]: info: Folder /tmp/plugins removed Jan 25 18:09:07 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:07 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:07 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:09:07 rivoplus volumio[3172]: info: Check plugin dependencies Jan 25 18:09:07 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 18:09:07 rivoplus volumio[3172]: info: Checking if plugin already exists Jan 25 18:09:07 rivoplus volumio[3172]: info: Rename folder Jan 25 18:09:07 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:07 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:07 rivoplus volumio[3172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:09:07 rivoplus volumio[3172]: info: Folder /tmp/downloaded_plugin.zip removed Jan 25 18:09:07 rivoplus volumio[3172]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 18:09:07 rivoplus volumio[3172]: TypeError: Cannot read property 'plugin_type' of undefined Jan 25 18:09:07 rivoplus volumio[3172]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Jan 25 18:09:07 rivoplus volumio[3172]: at PluginManager.getAvailablePlugins (/volumio/app/pluginmanager.js:1691:24) Jan 25 18:09:07 rivoplus volumio[3172]: at CoreCommandRouter.getAvailablePlugins (/volumio/app/index.js:1544:29) Jan 25 18:09:07 rivoplus volumio[3172]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1365:45) Jan 25 18:09:07 rivoplus volumio[3172]: at Socket.emit (events.js:400:28) Jan 25 18:09:07 rivoplus volumio[3172]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 25 18:09:07 rivoplus volumio[3172]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jan 25 18:09:07 rivoplus volumio[3172]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 18:09:08 rivoplus sudo[6200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-25 18:08 Jan 25 18:09:08 rivoplus sudo[6200]: 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"