-- Logs begin at Thu 2025-05-22 22:51:57 CEST, end at Fri 2025-05-23 08:24:28 CEST. -- May 23 08:23:00 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:00 rivoplus volumio[3194]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 23 08:23:00 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 23 08:23:00 rivoplus volumio[3194]: info: Preparing to save Alsa Options, stopping services first May 23 08:23:00 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:00 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:00 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPause May 23 08:23:00 rivoplus volumio[3194]: info: CoreStateMachine::pause May 23 08:23:00 rivoplus volumio[3194]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"S/PDIF + AES/EBU + I2S"}} May 23 08:23:00 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 08:23:00 rivoplus volumio[3194]: No protocol specified May 23 08:23:00 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:00 rivoplus volumio[3194]: No protocol specified May 23 08:23:00 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:00 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 08:23:00 rivoplus volumio[3194]: No protocol specified May 23 08:23:00 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:00 rivoplus volumio[3194]: info: Setting mixer Hegel USB for card Hegel USB May 23 08:23:00 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:00 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:00 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:00 rivoplus volumio[3194]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes May 23 08:23:00 rivoplus vtcs[5342]: [2025-05-23 08:23:00.713] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 23 08:23:00 rivoplus vtcs[5342]: [2025-05-23 08:23:00.714] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 23 08:23:00 rivoplus sudo[5397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:00 rivoplus sudo[5397]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:00 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... May 23 08:23:00 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 23 08:23:00 rivoplus systemd[1]: vtcs.service: Succeeded. May 23 08:23:00 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. May 23 08:23:00 rivoplus sudo[5397]: pam_unix(sudo:session): session closed for user root May 23 08:23:00 rivoplus volumio[3194]: No protocol specified May 23 08:23:00 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:00 rivoplus volumio[3194]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 23 08:23:00 rivoplus volumio[3194]: info: Updating Volume Controller Parameters: Device: 1 Name: S/PDIF + AES/EBU + I2S Mixer: Hegel USB Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 23 08:23:00 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 23 08:23:00 rivoplus volumio[3194]: info: Disabling external Volume Control May 23 08:23:00 rivoplus volumio[3194]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 08:23:00 rivoplus volumio[3194]: info: Preparing to generate the ALSA configuration file May 23 08:23:00 rivoplus sudo[5405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:00 rivoplus sudo[5405]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:00 rivoplus sudo[5405]: pam_unix(sudo:session): session closed for user root May 23 08:23:01 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:01 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:01 rivoplus volumio[3194]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 23 08:23:01 rivoplus volumio[3194]: info: Reading ALSA contributions from plugins. May 23 08:23:01 rivoplus volumio[3194]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified May 23 08:23:01 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:01 rivoplus sudo[5412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 23 08:23:01 rivoplus sudo[5412]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:01 rivoplus sudo[5412]: pam_unix(sudo:session): session closed for user root May 23 08:23:01 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:01 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:01 rivoplus sudo[5415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 23 08:23:01 rivoplus sudo[5415]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:01 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:01 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:01 rivoplus qobuz-connect[5274]: 20250523 08:23:01.121 [5274.5274] INFO SampleApp: Stopping Local configuration server May 23 08:23:01 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 23 08:23:01 rivoplus qobuz-connect[5274]: 20250523 08:23:01.132 [5274.5274] INFO SampleApp: shat down connection on UNIX socket May 23 08:23:01 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 23 08:23:01 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 23 08:23:01 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 23 08:23:01 rivoplus sudo[5415]: pam_unix(sudo:session): session closed for user root May 23 08:23:01 rivoplus volumio[3194]: No protocol specified May 23 08:23:01 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 08:23:01 rivoplus volumio[3194]: No protocol specified May 23 08:23:01 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection May 23 08:23:01 rivoplus volumio[3194]: info: VolumeController:: Volume=undefined Mute =false May 23 08:23:01 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:01 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:01 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:01 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:01 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: Not Reporting Auto name since its the default one May 23 08:23:01 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:01 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:01 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:01 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 23 08:23:01 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:01 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:01 rivoplus volumio[3194]: info: Executing endpoint qc_getconfig May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 23 08:23:01 rivoplus qobuz-connect[5423]: 20250523 08:23:01.416 [5423.5423] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 23 08:23:01 rivoplus volumio[3194]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:01 rivoplus volumio[3194]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:01 rivoplus qobuz-connect[5423]: 20250523 08:23:01.423 [5423.5423] INFO VolumeManager: [0xacd138f0]: Setting new playback volume: 75 May 23 08:23:01 rivoplus qobuz-connect[5423]: 20250523 08:23:01.423 [5423.5423] INFO VolumeManager: [0xacd138f0]: Setting new mute state: 0 May 23 08:23:01 rivoplus qobuz-connect[5423]: 20250523 08:23:01.423 [5423.5423] INFO QobuzConnect: [0xacd142c0]: Client initialized! May 23 08:23:01 rivoplus qobuz-connect[5423]: 20250523 08:23:01.423 [5423.5423] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp May 23 08:23:01 rivoplus qobuz-connect[5423]: 20250523 08:23:01.440 [5423.5423] INFO LocalConfigManager: [0xacd131d0]: Starting Local Configuration server May 23 08:23:01 rivoplus qobuz-connect[5423]: 20250523 08:23:01.440 [5423.5423] INFO SampleApp: Starting Local configuration server May 23 08:23:01 rivoplus qobuz-connect[5423]: 20250523 08:23:01.441 [5423.5423] INFO SampleApp: Connected to UNIX socket client 0xacd08ed8 May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 23 08:23:01 rivoplus sudo[5437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 23 08:23:01 rivoplus sudo[5437]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:01 rivoplus systemd[1]: Started Volumio Tidal Connect Service. May 23 08:23:01 rivoplus sudo[5437]: pam_unix(sudo:session): session closed for user root May 23 08:23:01 rivoplus volumio[3194]: No protocol specified May 23 08:23:01 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:01 rivoplus qobuz-connect[5423]: 20250523 08:23:01.565 [5423.5423] INFO SampleApp: Playback volume changed: 75 May 23 08:23:01 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 23 08:23:01 rivoplus volumio[3194]: info: QobuzConnect: QOBUZ Connect daemon connected May 23 08:23:01 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:01 rivoplus sudo[5445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 23 08:23:01 rivoplus sudo[5445]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:01 rivoplus volumio[3194]: info: Asound.conf file unchanged, so no further update is needed May 23 08:23:01 rivoplus volumio[3194]: info: Output device has changed, restarting MPD May 23 08:23:01 rivoplus sudo[5445]: pam_unix(sudo:session): session closed for user root May 23 08:23:01 rivoplus sudo[5448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 23 08:23:01 rivoplus sudo[5448]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:01 rivoplus volumio[3194]: info: Output device has changed, restarting Shairport Sync May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:01 rivoplus sudo[5449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 08:23:01 rivoplus sudo[5449]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:01 rivoplus sudo[5449]: pam_unix(sudo:session): session closed for user root May 23 08:23:01 rivoplus systemd[1]: Stopping RAAT DAEMON... May 23 08:23:01 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 23 08:23:01 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 23 08:23:01 rivoplus systemd[1]: Stopped RAAT DAEMON. May 23 08:23:01 rivoplus sudo[5452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 08:23:01 rivoplus sudo[5452]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:01 rivoplus systemd[1]: Started RAAT DAEMON. May 23 08:23:01 rivoplus sudo[5448]: pam_unix(sudo:session): session closed for user root May 23 08:23:01 rivoplus volumio[3194]: No protocol specified May 23 08:23:01 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:01 rivoplus volumio[3194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 08:23:01 rivoplus volumio[3194]: No protocol specified May 23 08:23:01 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:01 rivoplus volumio[3194]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:01 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:01 rivoplus volumio[3194]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 23 08:23:01 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:01 rivoplus sudo[5471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:01 rivoplus sudo[5471]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:01 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... May 23 08:23:01 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 23 08:23:01 rivoplus systemd[1]: vtcs.service: Succeeded. May 23 08:23:01 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. May 23 08:23:01 rivoplus sudo[5471]: pam_unix(sudo:session): session closed for user root May 23 08:23:01 rivoplus sudo[5476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:01 rivoplus sudo[5476]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:02 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:02 rivoplus sudo[5476]: pam_unix(sudo:session): session closed for user root May 23 08:23:02 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:02 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:02 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:02 rivoplus sudo[5483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 23 08:23:02 rivoplus sudo[5483]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:02 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:02 rivoplus volumio[3194]: info: MPD Permissions set May 23 08:23:02 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:02 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:02 rivoplus volumio[3194]: info: Raat Daemon started successfully May 23 08:23:02 rivoplus sudo[5483]: pam_unix(sudo:session): session closed for user root May 23 08:23:02 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:02 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:02 rivoplus volumio[3194]: info: Executing endpoint restartRAATSocket May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 23 08:23:02 rivoplus volumio[3194]: info: Executing endpoint tc_getconfig May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 23 08:23:02 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:02 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:02 rivoplus sudo[5486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 23 08:23:02 rivoplus sudo[5486]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:02 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:02 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:02 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus qobuz-connect[5423]: 20250523 08:23:02.134 [5423.5423] INFO SampleApp: Stopping Local configuration server May 23 08:23:02 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 23 08:23:02 rivoplus qobuz-connect[5423]: 20250523 08:23:02.144 [5423.5423] INFO SampleApp: shat down connection on UNIX socket May 23 08:23:02 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 23 08:23:02 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 23 08:23:02 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 23 08:23:02 rivoplus sudo[5486]: pam_unix(sudo:session): session closed for user root May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:02 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:02 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:02 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 23 08:23:02 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:02 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:02 rivoplus volumio[3194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 23 08:23:02 rivoplus volumio[3194]: info: Executing endpoint qc_getconfig May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 23 08:23:02 rivoplus volumio[3194]: info: Starting Shairport Sync May 23 08:23:02 rivoplus qobuz-connect[5493]: 20250523 08:23:02.274 [5493.5493] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 23 08:23:02 rivoplus qobuz-connect[5493]: 20250523 08:23:02.281 [5493.5493] INFO VolumeManager: [0xab6e08f0]: Setting new playback volume: 75 May 23 08:23:02 rivoplus qobuz-connect[5493]: 20250523 08:23:02.281 [5493.5493] INFO VolumeManager: [0xab6e08f0]: Setting new mute state: 0 May 23 08:23:02 rivoplus qobuz-connect[5493]: 20250523 08:23:02.281 [5493.5493] INFO QobuzConnect: [0xab6e12c0]: Client initialized! May 23 08:23:02 rivoplus qobuz-connect[5493]: 20250523 08:23:02.281 [5493.5493] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp May 23 08:23:02 rivoplus qobuz-connect[5493]: 20250523 08:23:02.295 [5493.5493] INFO LocalConfigManager: [0xab6e01d0]: Starting Local Configuration server May 23 08:23:02 rivoplus qobuz-connect[5493]: 20250523 08:23:02.295 [5493.5493] INFO SampleApp: Starting Local configuration server May 23 08:23:02 rivoplus qobuz-connect[5493]: 20250523 08:23:02.296 [5493.5493] INFO SampleApp: Connected to UNIX socket client 0xab6d5ed8 May 23 08:23:02 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 23 08:23:02 rivoplus volumio[3194]: info: QobuzConnect: QOBUZ Connect daemon connected May 23 08:23:02 rivoplus sudo[5499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 08:23:02 rivoplus sudo[5499]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:02 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 08:23:02 rivoplus systemd[1]: shairport-sync.service: Succeeded. May 23 08:23:02 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:02 rivoplus volumio[3194]: info: Not Reporting Auto name since its the default one May 23 08:23:02 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:02 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:02 rivoplus sudo[5499]: pam_unix(sudo:session): session closed for user root May 23 08:23:02 rivoplus volumio[3194]: info: Shairport-Sync Started May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 23 08:23:02 rivoplus qobuz-connect[5493]: 20250523 08:23:02.424 [5493.5493] INFO SampleApp: Playback volume changed: 75 May 23 08:23:02 rivoplus volumio[3194]: No protocol specified May 23 08:23:02 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:02 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:02 rivoplus sudo[5511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 23 08:23:02 rivoplus sudo[5511]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:02 rivoplus sudo[5511]: pam_unix(sudo:session): session closed for user root May 23 08:23:02 rivoplus sudo[5513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 23 08:23:02 rivoplus sudo[5513]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:02 rivoplus systemd[1]: Stopping RAAT DAEMON... May 23 08:23:02 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 23 08:23:02 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 23 08:23:02 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:02 rivoplus systemd[1]: Stopped RAAT DAEMON. May 23 08:23:02 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:02 rivoplus systemd[1]: Started RAAT DAEMON. May 23 08:23:02 rivoplus sudo[5513]: pam_unix(sudo:session): session closed for user root May 23 08:23:02 rivoplus volumio[3194]: info: Raat Daemon started successfully May 23 08:23:02 rivoplus volumio[3194]: info: Executing endpoint restartRAATSocket May 23 08:23:02 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 23 08:23:02 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:02 rivoplus volumio[3194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 23 08:23:02 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:03 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:04 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:04 rivoplus volumio[3194]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:04 rivoplus volumio[3194]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:04 rivoplus sudo[5535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 23 08:23:04 rivoplus sudo[5535]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:04 rivoplus systemd[1]: Started Volumio Tidal Connect Service. May 23 08:23:04 rivoplus sudo[5535]: pam_unix(sudo:session): session closed for user root May 23 08:23:04 rivoplus volumio[3194]: info: Executing endpoint tc_getconfig May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 23 08:23:04 rivoplus vtcs[5537]: STARTING TidalConnect services, version: 1.4.0.34 May 23 08:23:04 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:04 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:04 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:04 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:04 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:04 rivoplus vtcs[5537]: STARTED TidalConnect services. May 23 08:23:04 rivoplus volumio[3194]: info: Executing endpoint tc_connect May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 23 08:23:04 rivoplus volumio[3194]: info: Connecting to TidalConnect May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::servicePushState May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: 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} May 23 08:23:04 rivoplus volumio[3194]: verbose: CURRENT POSITION 0 May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::syncState stateService stop May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::syncState currentStatus stop May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:04 rivoplus volumio[3194]: info: No code May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::servicePushState May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: 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} May 23 08:23:04 rivoplus volumio[3194]: verbose: CURRENT POSITION 0 May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::syncState stateService stop May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::syncState currentStatus stop May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:04 rivoplus volumio[3194]: info: No code May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::getcurrentVolume May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioRetrievevolume May 23 08:23:04 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:04 rivoplus volumio[3194]: info: VolumeController:: Volume=0 Mute =false May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:04 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:04 rivoplus volumio[3194]: info: CoreStateMachine::updateTrackBlock May 23 08:23:04 rivoplus volumio[3194]: info: CorePlayQueue::getTrackBlock May 23 08:23:04 rivoplus volumio[3194]: info: CoreCommandRouter::volumioRetrievevolume May 23 08:23:05 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:05 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:05 rivoplus volumio[3194]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified May 23 08:23:05 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:05 rivoplus volumio[3194]: info: VolumeController:: Volume=undefined Mute =false May 23 08:23:05 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:05 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:05 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:05 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:05 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:05 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:05 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:05 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:05 rivoplus volumio[3194]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:05 rivoplus volumio[3194]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:05 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:05 rivoplus sudo[5570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 23 08:23:05 rivoplus sudo[5570]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:05 rivoplus sudo[5570]: pam_unix(sudo:session): session closed for user root May 23 08:23:05 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:05 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:05 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:05 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:05 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:05 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:05 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:05 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:07 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:08 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:15 rivoplus volumio[3194]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 23 08:23:15 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 23 08:23:15 rivoplus volumio[3194]: info: Preparing to save Alsa Options, stopping services first May 23 08:23:15 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:15 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:15 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPause May 23 08:23:15 rivoplus volumio[3194]: info: CoreStateMachine::pause May 23 08:23:15 rivoplus volumio[3194]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"S/PDIF + AES/EBU + I2S"}} May 23 08:23:15 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 08:23:15 rivoplus volumio[3194]: No protocol specified May 23 08:23:15 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:15 rivoplus volumio[3194]: No protocol specified May 23 08:23:15 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:15 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 08:23:15 rivoplus volumio[3194]: No protocol specified May 23 08:23:15 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:15 rivoplus volumio[3194]: info: Setting mixer Hegel USB for card Hegel USB May 23 08:23:15 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:15 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:15 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:15 rivoplus volumio[3194]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes May 23 08:23:15 rivoplus vtcs[5537]: [2025-05-23 08:23:15.856] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 23 08:23:15 rivoplus vtcs[5537]: [2025-05-23 08:23:15.856] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 23 08:23:15 rivoplus sudo[5638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:15 rivoplus sudo[5638]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:15 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... May 23 08:23:15 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 23 08:23:15 rivoplus systemd[1]: vtcs.service: Succeeded. May 23 08:23:15 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. May 23 08:23:15 rivoplus volumio[3194]: No protocol specified May 23 08:23:15 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:15 rivoplus volumio[3194]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 23 08:23:15 rivoplus volumio[3194]: info: Updating Volume Controller Parameters: Device: 1 Name: S/PDIF + AES/EBU + I2S Mixer: Hegel USB Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 23 08:23:15 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 23 08:23:15 rivoplus volumio[3194]: info: Disabling external Volume Control May 23 08:23:15 rivoplus sudo[5638]: pam_unix(sudo:session): session closed for user root May 23 08:23:15 rivoplus volumio[3194]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 08:23:15 rivoplus volumio[3194]: info: Preparing to generate the ALSA configuration file May 23 08:23:16 rivoplus sudo[5646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:16 rivoplus sudo[5646]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:16 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:16 rivoplus sudo[5646]: pam_unix(sudo:session): session closed for user root May 23 08:23:16 rivoplus volumio[3194]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 23 08:23:16 rivoplus volumio[3194]: info: Reading ALSA contributions from plugins. May 23 08:23:16 rivoplus volumio[3194]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified May 23 08:23:16 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:16 rivoplus sudo[5652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 23 08:23:16 rivoplus sudo[5652]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:16 rivoplus sudo[5652]: pam_unix(sudo:session): session closed for user root May 23 08:23:16 rivoplus sudo[5656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 23 08:23:16 rivoplus sudo[5656]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:16 rivoplus qobuz-connect[5493]: 20250523 08:23:16.230 [5493.5493] INFO SampleApp: Stopping Local configuration server May 23 08:23:16 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 23 08:23:16 rivoplus qobuz-connect[5493]: 20250523 08:23:16.240 [5493.5493] INFO SampleApp: shat down connection on UNIX socket May 23 08:23:16 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 23 08:23:16 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 23 08:23:16 rivoplus volumio[3194]: No protocol specified May 23 08:23:16 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 08:23:16 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 23 08:23:16 rivoplus sudo[5656]: pam_unix(sudo:session): session closed for user root May 23 08:23:16 rivoplus volumio[3194]: No protocol specified May 23 08:23:16 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: Not Reporting Auto name since its the default one May 23 08:23:16 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:16 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:16 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 23 08:23:16 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:16 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:16 rivoplus volumio[3194]: info: VolumeController:: Volume=undefined Mute =false May 23 08:23:16 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:16 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:16 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:16 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:16 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:16 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:16 rivoplus volumio[3194]: info: Executing endpoint qc_getconfig May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 23 08:23:16 rivoplus qobuz-connect[5660]: 20250523 08:23:16.476 [5660.5660] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 23 08:23:16 rivoplus qobuz-connect[5660]: 20250523 08:23:16.483 [5660.5660] INFO VolumeManager: [0xabddf8f0]: Setting new playback volume: 75 May 23 08:23:16 rivoplus qobuz-connect[5660]: 20250523 08:23:16.484 [5660.5660] INFO VolumeManager: [0xabddf8f0]: Setting new mute state: 0 May 23 08:23:16 rivoplus qobuz-connect[5660]: 20250523 08:23:16.484 [5660.5660] INFO QobuzConnect: [0xabde02c0]: Client initialized! May 23 08:23:16 rivoplus qobuz-connect[5660]: 20250523 08:23:16.484 [5660.5660] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus qobuz-connect[5660]: 20250523 08:23:16.499 [5660.5660] INFO LocalConfigManager: [0xabddf1d0]: Starting Local Configuration server May 23 08:23:16 rivoplus qobuz-connect[5660]: 20250523 08:23:16.499 [5660.5660] INFO SampleApp: Starting Local configuration server May 23 08:23:16 rivoplus qobuz-connect[5660]: 20250523 08:23:16.500 [5660.5660] INFO SampleApp: Connected to UNIX socket client 0xabdd4ed8 May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 23 08:23:16 rivoplus volumio[3194]: No protocol specified May 23 08:23:16 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:16 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 23 08:23:16 rivoplus volumio[3194]: info: QobuzConnect: QOBUZ Connect daemon connected May 23 08:23:16 rivoplus volumio[3194]: info: Asound.conf file unchanged, so no further update is needed May 23 08:23:16 rivoplus volumio[3194]: info: Output device has changed, restarting MPD May 23 08:23:16 rivoplus sudo[5681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 23 08:23:16 rivoplus sudo[5681]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:16 rivoplus qobuz-connect[5660]: 20250523 08:23:16.626 [5660.5660] INFO SampleApp: Playback volume changed: 75 May 23 08:23:16 rivoplus sudo[5681]: pam_unix(sudo:session): session closed for user root May 23 08:23:16 rivoplus sudo[5688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 08:23:16 rivoplus sudo[5688]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:16 rivoplus volumio[3194]: info: Output device has changed, restarting Shairport Sync May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:16 rivoplus sudo[5688]: pam_unix(sudo:session): session closed for user root May 23 08:23:16 rivoplus sudo[5693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 08:23:16 rivoplus sudo[5693]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:16 rivoplus sudo[5691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 23 08:23:16 rivoplus sudo[5691]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:16 rivoplus systemd[1]: Stopping RAAT DAEMON... May 23 08:23:16 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 23 08:23:16 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 23 08:23:16 rivoplus systemd[1]: Stopped RAAT DAEMON. May 23 08:23:16 rivoplus systemd[1]: Started RAAT DAEMON. May 23 08:23:16 rivoplus volumio[3194]: No protocol specified May 23 08:23:16 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:16 rivoplus volumio[3194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 08:23:16 rivoplus volumio[3194]: No protocol specified May 23 08:23:16 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:16 rivoplus sudo[5691]: pam_unix(sudo:session): session closed for user root May 23 08:23:16 rivoplus volumio[3194]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:16 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:16 rivoplus volumio[3194]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:16 rivoplus sudo[5725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:16 rivoplus sudo[5725]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:16 rivoplus sudo[5729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:16 rivoplus sudo[5729]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:16 rivoplus sudo[5725]: pam_unix(sudo:session): session closed for user root May 23 08:23:16 rivoplus sudo[5729]: pam_unix(sudo:session): session closed for user root May 23 08:23:16 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:16 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:16 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:16 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:16 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:17 rivoplus sudo[5736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 23 08:23:17 rivoplus sudo[5736]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:17 rivoplus volumio[3194]: info: MPD Permissions set May 23 08:23:17 rivoplus volumio[3194]: info: Raat Daemon started successfully May 23 08:23:17 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:17 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:17 rivoplus sudo[5736]: pam_unix(sudo:session): session closed for user root May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:17 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:17 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:17 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:17 rivoplus sudo[5739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 23 08:23:17 rivoplus sudo[5739]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:17 rivoplus volumio[3194]: info: Executing endpoint restartRAATSocket May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 23 08:23:17 rivoplus qobuz-connect[5660]: 20250523 08:23:17.094 [5660.5660] INFO SampleApp: Stopping Local configuration server May 23 08:23:17 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 23 08:23:17 rivoplus qobuz-connect[5660]: 20250523 08:23:17.104 [5660.5660] INFO SampleApp: shat down connection on UNIX socket May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 23 08:23:17 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 23 08:23:17 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 23 08:23:17 rivoplus sudo[5739]: pam_unix(sudo:session): session closed for user root May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:17 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:17 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:17 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 23 08:23:17 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:17 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:17 rivoplus volumio[3194]: info: Executing endpoint qc_getconfig May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 23 08:23:17 rivoplus qobuz-connect[5744]: 20250523 08:23:17.256 [5744.5744] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 23 08:23:17 rivoplus volumio[3194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 23 08:23:17 rivoplus qobuz-connect[5744]: 20250523 08:23:17.262 [5744.5744] INFO VolumeManager: [0xab9a48f0]: Setting new playback volume: 75 May 23 08:23:17 rivoplus qobuz-connect[5744]: 20250523 08:23:17.262 [5744.5744] INFO VolumeManager: [0xab9a48f0]: Setting new mute state: 0 May 23 08:23:17 rivoplus qobuz-connect[5744]: 20250523 08:23:17.262 [5744.5744] INFO QobuzConnect: [0xab9a52c0]: Client initialized! May 23 08:23:17 rivoplus qobuz-connect[5744]: 20250523 08:23:17.262 [5744.5744] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp May 23 08:23:17 rivoplus volumio[3194]: info: Starting Shairport Sync May 23 08:23:17 rivoplus qobuz-connect[5744]: 20250523 08:23:17.274 [5744.5744] INFO LocalConfigManager: [0xab9a41d0]: Starting Local Configuration server May 23 08:23:17 rivoplus qobuz-connect[5744]: 20250523 08:23:17.275 [5744.5744] INFO SampleApp: Starting Local configuration server May 23 08:23:17 rivoplus qobuz-connect[5744]: 20250523 08:23:17.275 [5744.5744] INFO SampleApp: Connected to UNIX socket client 0xab999ed8 May 23 08:23:17 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 23 08:23:17 rivoplus volumio[3194]: info: QobuzConnect: QOBUZ Connect daemon connected May 23 08:23:17 rivoplus sudo[5754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 08:23:17 rivoplus sudo[5754]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:17 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 08:23:17 rivoplus systemd[1]: shairport-sync.service: Succeeded. May 23 08:23:17 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 08:23:17 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:17 rivoplus volumio[3194]: info: Not Reporting Auto name since its the default one May 23 08:23:17 rivoplus sudo[5754]: pam_unix(sudo:session): session closed for user root May 23 08:23:17 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:17 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:17 rivoplus qobuz-connect[5744]: 20250523 08:23:17.404 [5744.5744] INFO SampleApp: Playback volume changed: 75 May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:17 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:17 rivoplus volumio[3194]: info: Shairport-Sync Started May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 23 08:23:17 rivoplus volumio[3194]: No protocol specified May 23 08:23:17 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:17 rivoplus sudo[5766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 23 08:23:17 rivoplus sudo[5766]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:17 rivoplus sudo[5766]: pam_unix(sudo:session): session closed for user root May 23 08:23:17 rivoplus sudo[5768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 23 08:23:17 rivoplus sudo[5768]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:17 rivoplus systemd[1]: Stopping RAAT DAEMON... May 23 08:23:17 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 23 08:23:17 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 23 08:23:17 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:17 rivoplus systemd[1]: Stopped RAAT DAEMON. May 23 08:23:17 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:17 rivoplus systemd[1]: Started RAAT DAEMON. May 23 08:23:17 rivoplus sudo[5768]: pam_unix(sudo:session): session closed for user root May 23 08:23:17 rivoplus volumio[3194]: info: Raat Daemon started successfully May 23 08:23:17 rivoplus volumio[3194]: info: Executing endpoint restartRAATSocket May 23 08:23:17 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 23 08:23:17 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:17 rivoplus volumio[3194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 23 08:23:18 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:19 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:19 rivoplus volumio[3194]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:19 rivoplus volumio[3194]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:19 rivoplus sudo[5790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 23 08:23:19 rivoplus sudo[5790]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:19 rivoplus systemd[1]: Started Volumio Tidal Connect Service. May 23 08:23:19 rivoplus sudo[5790]: pam_unix(sudo:session): session closed for user root May 23 08:23:19 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:19 rivoplus volumio[3194]: info: Executing endpoint tc_getconfig May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 23 08:23:19 rivoplus vtcs[5792]: STARTING TidalConnect services, version: 1.4.0.34 May 23 08:23:19 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:19 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:19 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:19 rivoplus vtcs[5792]: STARTED TidalConnect services. May 23 08:23:19 rivoplus volumio[3194]: info: Executing endpoint tc_connect May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 23 08:23:19 rivoplus volumio[3194]: info: Connecting to TidalConnect May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::servicePushState May 23 08:23:19 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: 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} May 23 08:23:19 rivoplus volumio[3194]: verbose: CURRENT POSITION 0 May 23 08:23:19 rivoplus volumio[3194]: info: CoreStateMachine::syncState stateService stop May 23 08:23:19 rivoplus volumio[3194]: info: CoreStateMachine::syncState currentStatus stop May 23 08:23:19 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:19 rivoplus volumio[3194]: info: No code May 23 08:23:19 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::servicePushState May 23 08:23:19 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: 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} May 23 08:23:19 rivoplus volumio[3194]: verbose: CURRENT POSITION 0 May 23 08:23:19 rivoplus volumio[3194]: info: CoreStateMachine::syncState stateService stop May 23 08:23:19 rivoplus volumio[3194]: info: CoreStateMachine::syncState currentStatus stop May 23 08:23:19 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:19 rivoplus volumio[3194]: info: No code May 23 08:23:19 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:19 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:19 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:19 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:19 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:19 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 23 08:23:20 rivoplus volumio[3194]: info: CoreStateMachine::getcurrentVolume May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::volumioRetrievevolume May 23 08:23:20 rivoplus volumio[3194]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:20 rivoplus volumio[3194]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:20 rivoplus volumio[3194]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified May 23 08:23:20 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:20 rivoplus sudo[5823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 23 08:23:20 rivoplus sudo[5823]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:20 rivoplus sudo[5823]: pam_unix(sudo:session): session closed for user root May 23 08:23:20 rivoplus volumio[3194]: info: VolumeController:: Volume=undefined Mute =false May 23 08:23:20 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:20 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:20 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:20 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:20 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:20 rivoplus volumio[3194]: info: CoreStateMachine::updateTrackBlock May 23 08:23:20 rivoplus volumio[3194]: info: CorePlayQueue::getTrackBlock May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::volumioRetrievevolume May 23 08:23:20 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:20 rivoplus volumio[3194]: info: VolumeController:: Volume=0 Mute =false May 23 08:23:20 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:20 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:20 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:20 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:20 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:20 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:20 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:20 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:20 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:20 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:20 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:22 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:23 rivoplus volumio[3194]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 23 08:23:23 rivoplus volumio[3194]: info: Preparing to save Alsa Options, stopping services first May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:23 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPause May 23 08:23:23 rivoplus volumio[3194]: info: CoreStateMachine::pause May 23 08:23:23 rivoplus volumio[3194]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"S/PDIF + AES/EBU + I2S"}} May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 08:23:23 rivoplus volumio[3194]: No protocol specified May 23 08:23:23 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:23 rivoplus volumio[3194]: No protocol specified May 23 08:23:23 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 08:23:23 rivoplus volumio[3194]: info: Found match in Cards Database for ignoring default Mixer May 23 08:23:23 rivoplus volumio[3194]: No protocol specified May 23 08:23:23 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:23 rivoplus volumio[3194]: info: Setting mixer PCM for card S/PDIF + AES/EBU + I2S May 23 08:23:23 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:23 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:23 rivoplus volumio[3194]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes May 23 08:23:23 rivoplus vtcs[5792]: [2025-05-23 08:23:23.274] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 23 08:23:23 rivoplus vtcs[5792]: [2025-05-23 08:23:23.282] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer May 23 08:23:23 rivoplus vtcs[5792]: [2025-05-23 08:23:23.282] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 23 08:23:23 rivoplus volumio[3194]: info: Ignoring Mixers Options May 23 08:23:23 rivoplus volumio[3194]: info: Device 5 does not have any Mixer Control Available May 23 08:23:23 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:23 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:23 rivoplus volumio[3194]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes May 23 08:23:23 rivoplus sudo[5837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:23 rivoplus sudo[5837]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:23 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... May 23 08:23:23 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 23 08:23:23 rivoplus systemd[1]: vtcs.service: Succeeded. May 23 08:23:23 rivoplus sudo[5840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:23 rivoplus sudo[5840]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:23 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. May 23 08:23:23 rivoplus sudo[5837]: pam_unix(sudo:session): session closed for user root May 23 08:23:23 rivoplus volumio[3194]: No protocol specified May 23 08:23:23 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 23 08:23:23 rivoplus volumio[3194]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 23 08:23:23 rivoplus sudo[5840]: pam_unix(sudo:session): session closed for user root May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 23 08:23:23 rivoplus volumio[3194]: info: Disabling external Volume Control May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 08:23:23 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:23 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:23 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:23 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:23 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:23 rivoplus volumio[3194]: info: Preparing to generate the ALSA configuration file May 23 08:23:23 rivoplus sudo[5848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:23 rivoplus sudo[5848]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:23 rivoplus sudo[5851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:23 rivoplus sudo[5851]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:23 rivoplus sudo[5848]: pam_unix(sudo:session): session closed for user root May 23 08:23:23 rivoplus sudo[5851]: pam_unix(sudo:session): session closed for user root May 23 08:23:23 rivoplus sudo[5858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 23 08:23:23 rivoplus sudo[5858]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:23 rivoplus sudo[5858]: pam_unix(sudo:session): session closed for user root May 23 08:23:23 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:23 rivoplus sudo[5865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 23 08:23:23 rivoplus sudo[5865]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:23 rivoplus volumio[3194]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 23 08:23:23 rivoplus volumio[3194]: info: Reading ALSA contributions from plugins. May 23 08:23:23 rivoplus sudo[5866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 23 08:23:23 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:23 rivoplus sudo[5866]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:23 rivoplus volumio[3194]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified May 23 08:23:23 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:23 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:23 rivoplus qobuz-connect[5744]: 20250523 08:23:23.762 [5744.5744] INFO SampleApp: Stopping Local configuration server May 23 08:23:23 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 23 08:23:23 rivoplus qobuz-connect[5744]: 20250523 08:23:23.772 [5744.5744] INFO SampleApp: shat down connection on UNIX socket May 23 08:23:23 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 23 08:23:23 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 23 08:23:23 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 23 08:23:23 rivoplus sudo[5865]: pam_unix(sudo:session): session closed for user root May 23 08:23:23 rivoplus sudo[5866]: pam_unix(sudo:session): session closed for user root May 23 08:23:23 rivoplus volumio[3194]: No protocol specified May 23 08:23:23 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 08:23:23 rivoplus sudo[5873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 23 08:23:23 rivoplus sudo[5873]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:23 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 23 08:23:23 rivoplus systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT May 23 08:23:23 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 23 08:23:23 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 23 08:23:23 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 23 08:23:23 rivoplus volumio[3194]: No protocol specified May 23 08:23:23 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection May 23 08:23:23 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection May 23 08:23:23 rivoplus sudo[5873]: pam_unix(sudo:session): session closed for user root May 23 08:23:23 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:23 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:23 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:23 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: Not Reporting Auto name since its the default one May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: Not Reporting Auto name since its the default one May 23 08:23:24 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 23 08:23:24 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:24 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:24 rivoplus volumio[3194]: info: Executing endpoint qc_getconfig May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 23 08:23:24 rivoplus volumio[3194]: info: Executing endpoint qc_getconfig May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 23 08:23:24 rivoplus qobuz-connect[5885]: 20250523 08:23:24.099 [5885.5885] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus qobuz-connect[5885]: 20250523 08:23:24.106 [5885.5885] INFO VolumeManager: [0xabcb98f0]: Setting new playback volume: 75 May 23 08:23:24 rivoplus qobuz-connect[5885]: 20250523 08:23:24.106 [5885.5885] INFO VolumeManager: [0xabcb98f0]: Setting new mute state: 0 May 23 08:23:24 rivoplus qobuz-connect[5885]: 20250523 08:23:24.106 [5885.5885] INFO QobuzConnect: [0xabcba2c0]: Client initialized! May 23 08:23:24 rivoplus qobuz-connect[5885]: 20250523 08:23:24.106 [5885.5885] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp May 23 08:23:24 rivoplus qobuz-connect[5885]: 20250523 08:23:24.120 [5885.5885] INFO LocalConfigManager: [0xabcb91d0]: Starting Local Configuration server May 23 08:23:24 rivoplus qobuz-connect[5885]: 20250523 08:23:24.120 [5885.5885] INFO SampleApp: Starting Local configuration server May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 23 08:23:24 rivoplus qobuz-connect[5885]: 20250523 08:23:24.121 [5885.5885] INFO SampleApp: Connected to UNIX socket client 0xabcaeed8 May 23 08:23:24 rivoplus volumio[3194]: No protocol specified May 23 08:23:24 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 23 08:23:24 rivoplus sudo[5899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 23 08:23:24 rivoplus sudo[5899]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:24 rivoplus qobuz-connect[5885]: 20250523 08:23:24.248 [5885.5885] INFO SampleApp: Playback volume changed: 75 May 23 08:23:24 rivoplus sudo[5899]: pam_unix(sudo:session): session closed for user root May 23 08:23:24 rivoplus sudo[5903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 23 08:23:24 rivoplus volumio[3194]: No protocol specified May 23 08:23:24 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:24 rivoplus sudo[5903]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:24 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 23 08:23:24 rivoplus volumio[3194]: info: QobuzConnect: QOBUZ Connect daemon connected May 23 08:23:24 rivoplus systemd[1]: Stopping RAAT DAEMON... May 23 08:23:24 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 23 08:23:24 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 23 08:23:24 rivoplus systemd[1]: Stopped RAAT DAEMON. May 23 08:23:24 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:24 rivoplus systemd[1]: Started RAAT DAEMON. May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:24 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:24 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:24 rivoplus sudo[5906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 23 08:23:24 rivoplus sudo[5906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:24 rivoplus volumio[3194]: info: Asound.conf file written May 23 08:23:24 rivoplus sudo[5903]: pam_unix(sudo:session): session closed for user root May 23 08:23:24 rivoplus sudo[5906]: pam_unix(sudo:session): session closed for user root May 23 08:23:24 rivoplus sudo[5914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 23 08:23:24 rivoplus sudo[5914]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:24 rivoplus systemd[1]: Stopping RAAT DAEMON... May 23 08:23:24 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 23 08:23:24 rivoplus sudo[5916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 23 08:23:24 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 23 08:23:24 rivoplus sudo[5916]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:24 rivoplus systemd[1]: Stopped RAAT DAEMON. May 23 08:23:24 rivoplus sudo[5916]: pam_unix(sudo:session): session closed for user root May 23 08:23:24 rivoplus systemd[1]: Started RAAT DAEMON. May 23 08:23:24 rivoplus sudo[5914]: pam_unix(sudo:session): session closed for user root May 23 08:23:24 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 May 23 08:23:24 rivoplus kernel: Err: can not set rate to 4274967296! May 23 08:23:24 rivoplus kernel: Range[3920000 - 500000000] May 23 08:23:24 rivoplus kernel: out of value, fixed it May 23 08:23:24 rivoplus kernel: id=0 set inskew=0 May 23 08:23:24 rivoplus volumio[3194]: No protocol specified May 23 08:23:24 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:24 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted May 23 08:23:24 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted May 23 08:23:24 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted May 23 08:23:24 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted May 23 08:23:24 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Hegel Music Systems Internal Clock Validity:0' : Operation not permitted May 23 08:23:24 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted May 23 08:23:24 rivoplus volumio[3194]: info: Output device has changed, restarting MPD May 23 08:23:24 rivoplus sudo[5934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 08:23:24 rivoplus sudo[5934]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:24 rivoplus volumio[3194]: info: Output device has changed, restarting Shairport Sync May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:24 rivoplus sudo[5934]: pam_unix(sudo:session): session closed for user root May 23 08:23:24 rivoplus sudo[5937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 08:23:24 rivoplus sudo[5937]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:24 rivoplus volumio[3194]: No protocol specified May 23 08:23:24 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:24 rivoplus volumio[3194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 08:23:24 rivoplus volumio[3194]: No protocol specified May 23 08:23:24 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:24 rivoplus volumio[3194]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:24 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:24 rivoplus volumio[3194]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: Output device has changed, restarting MPD May 23 08:23:24 rivoplus sudo[5947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:24 rivoplus sudo[5947]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:24 rivoplus sudo[5949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 08:23:24 rivoplus sudo[5949]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:24 rivoplus volumio[3194]: info: Output device has changed, restarting Shairport Sync May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:24 rivoplus sudo[5949]: pam_unix(sudo:session): session closed for user root May 23 08:23:24 rivoplus sudo[5947]: pam_unix(sudo:session): session closed for user root May 23 08:23:24 rivoplus sudo[5953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 08:23:24 rivoplus sudo[5953]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:24 rivoplus volumio[3194]: No protocol specified May 23 08:23:24 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:24 rivoplus volumio[3194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 08:23:24 rivoplus volumio[3194]: No protocol specified May 23 08:23:24 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:24 rivoplus volumio[3194]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:24 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:24 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:24 rivoplus volumio[3194]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 23 08:23:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus sudo[5963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:25 rivoplus sudo[5963]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus sudo[5963]: pam_unix(sudo:session): session closed for user root May 23 08:23:25 rivoplus sudo[5967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:25 rivoplus sudo[5967]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus sudo[5971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:25 rivoplus sudo[5971]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus sudo[5967]: pam_unix(sudo:session): session closed for user root May 23 08:23:25 rivoplus sudo[5971]: pam_unix(sudo:session): session closed for user root May 23 08:23:25 rivoplus sudo[5978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 23 08:23:25 rivoplus sudo[5978]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus sudo[5978]: pam_unix(sudo:session): session closed for user root May 23 08:23:25 rivoplus volumio[3194]: info: Raat Daemon started successfully May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:25 rivoplus sudo[5985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 23 08:23:25 rivoplus sudo[5985]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:25 rivoplus sudo[5986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 23 08:23:25 rivoplus sudo[5986]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus volumio[3194]: info: MPD Permissions set May 23 08:23:25 rivoplus volumio[3194]: info: MPD Permissions set May 23 08:23:25 rivoplus volumio[3194]: info: Raat Daemon started successfully May 23 08:23:25 rivoplus volumio[3194]: info: Executing endpoint restartRAATSocket May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 23 08:23:25 rivoplus qobuz-connect[5885]: 20250523 08:23:25.261 [5885.5885] INFO SampleApp: Stopping Local configuration server May 23 08:23:25 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 23 08:23:25 rivoplus sudo[5986]: pam_unix(sudo:session): session closed for user root May 23 08:23:25 rivoplus qobuz-connect[5885]: 20250523 08:23:25.272 [5885.5885] INFO SampleApp: shat down connection on UNIX socket May 23 08:23:25 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 23 08:23:25 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 23 08:23:25 rivoplus sudo[5991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 23 08:23:25 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 23 08:23:25 rivoplus sudo[5991]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus sudo[5985]: pam_unix(sudo:session): session closed for user root May 23 08:23:25 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: Not Reporting Auto name since its the default one May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 08:23:25 rivoplus volumio[3194]: info: Discovery: Getting this device information May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:25 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:25 rivoplus systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT May 23 08:23:25 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 23 08:23:25 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 23 08:23:25 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 23 08:23:25 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:25 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 23 08:23:25 rivoplus sudo[5991]: pam_unix(sudo:session): session closed for user root May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:25 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:25 rivoplus volumio[3194]: info: Not Reporting Auto name since its the default one May 23 08:23:25 rivoplus volumio[3194]: info: Executing endpoint qc_getconfig May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 23 08:23:25 rivoplus qobuz-connect[5997]: 20250523 08:23:25.607 [5997.5997] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 23 08:23:25 rivoplus volumio[3194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 23 08:23:25 rivoplus qobuz-connect[5997]: 20250523 08:23:25.614 [5997.5997] INFO VolumeManager: [0xab2a88f0]: Setting new playback volume: 75 May 23 08:23:25 rivoplus qobuz-connect[5997]: 20250523 08:23:25.614 [5997.5997] INFO VolumeManager: [0xab2a88f0]: Setting new mute state: 0 May 23 08:23:25 rivoplus qobuz-connect[5997]: 20250523 08:23:25.614 [5997.5997] INFO QobuzConnect: [0xab2a92c0]: Client initialized! May 23 08:23:25 rivoplus qobuz-connect[5997]: 20250523 08:23:25.614 [5997.5997] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 23 08:23:25 rivoplus qobuz-connect[5997]: 20250523 08:23:25.627 [5997.5997] INFO LocalConfigManager: [0xab2a81d0]: Starting Local Configuration server May 23 08:23:25 rivoplus qobuz-connect[5997]: 20250523 08:23:25.627 [5997.5997] INFO SampleApp: Starting Local configuration server May 23 08:23:25 rivoplus qobuz-connect[5997]: 20250523 08:23:25.628 [5997.5997] INFO SampleApp: Connected to UNIX socket client 0xab29ded8 May 23 08:23:25 rivoplus volumio[3194]: No protocol specified May 23 08:23:25 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:25 rivoplus volumio[3194]: info: Starting Shairport Sync May 23 08:23:25 rivoplus sudo[6020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 23 08:23:25 rivoplus sudo[6020]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus volumio[3194]: info: Starting Shairport Sync May 23 08:23:25 rivoplus sudo[6020]: pam_unix(sudo:session): session closed for user root May 23 08:23:25 rivoplus qobuz-connect[5997]: 20250523 08:23:25.757 [5997.5997] INFO SampleApp: Playback volume changed: 75 May 23 08:23:25 rivoplus sudo[6023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 08:23:25 rivoplus sudo[6023]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 23 08:23:25 rivoplus volumio[3194]: info: QobuzConnect: QOBUZ Connect daemon connected May 23 08:23:25 rivoplus sudo[6025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 23 08:23:25 rivoplus sudo[6025]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus volumio[3194]: info: Preparing to generate the ALSA configuration file May 23 08:23:25 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 08:23:25 rivoplus systemd[1]: shairport-sync.service: Succeeded. May 23 08:23:25 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 08:23:25 rivoplus sudo[6027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 08:23:25 rivoplus sudo[6027]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 23 08:23:25 rivoplus systemd[1]: Stopping RAAT DAEMON... May 23 08:23:25 rivoplus volumio[3194]: No protocol specified May 23 08:23:25 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:25 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 08:23:25 rivoplus sudo[6023]: pam_unix(sudo:session): session closed for user root May 23 08:23:25 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 23 08:23:25 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 23 08:23:25 rivoplus systemd[1]: Stopped RAAT DAEMON. May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:25 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:25 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 08:23:25 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:25 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:25 rivoplus sudo[6034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 23 08:23:25 rivoplus sudo[6034]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:25 rivoplus volumio[3194]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 23 08:23:25 rivoplus volumio[3194]: info: Reading ALSA contributions from plugins. May 23 08:23:25 rivoplus volumio[3194]: info: Shairport-Sync Started May 23 08:23:25 rivoplus systemd[1]: Started RAAT DAEMON. May 23 08:23:25 rivoplus sudo[6025]: pam_unix(sudo:session): session closed for user root May 23 08:23:26 rivoplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 23 08:23:26 rivoplus systemd[1]: shairport-sync.service: Succeeded. May 23 08:23:26 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 08:23:26 rivoplus volumio[3194]: info: Raat Daemon started successfully May 23 08:23:26 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:26 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:26 rivoplus volumio[3194]: info: Asound.conf file written May 23 08:23:26 rivoplus sudo[6034]: pam_unix(sudo:session): session closed for user root May 23 08:23:26 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 08:23:26 rivoplus sudo[6027]: pam_unix(sudo:session): session closed for user root May 23 08:23:26 rivoplus sudo[6042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 23 08:23:26 rivoplus sudo[6042]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:26 rivoplus sudo[6048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 23 08:23:26 rivoplus sudo[6048]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:26 rivoplus systemd[1]: Stopping RAAT DAEMON... May 23 08:23:26 rivoplus sudo[6048]: pam_unix(sudo:session): session closed for user root May 23 08:23:26 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 23 08:23:26 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 23 08:23:26 rivoplus systemd[1]: Stopped RAAT DAEMON. May 23 08:23:26 rivoplus systemd[1]: Started RAAT DAEMON. May 23 08:23:26 rivoplus sudo[6042]: pam_unix(sudo:session): session closed for user root May 23 08:23:26 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 May 23 08:23:26 rivoplus kernel: Err: can not set rate to 4270967296! May 23 08:23:26 rivoplus kernel: Range[3920000 - 500000000] May 23 08:23:26 rivoplus kernel: out of value, fixed it May 23 08:23:26 rivoplus kernel: id=0 set inskew=0 May 23 08:23:26 rivoplus volumio[3194]: No protocol specified May 23 08:23:26 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:26 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted May 23 08:23:26 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted May 23 08:23:26 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted May 23 08:23:26 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted May 23 08:23:26 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Hegel Music Systems Internal Clock Validity:0' : Operation not permitted May 23 08:23:26 rivoplus volumio[3194]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted May 23 08:23:26 rivoplus volumio[3194]: info: Output device has changed, restarting MPD May 23 08:23:26 rivoplus sudo[6068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 08:23:26 rivoplus sudo[6068]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:26 rivoplus volumio[3194]: info: Output device has changed, restarting Shairport Sync May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:26 rivoplus sudo[6068]: pam_unix(sudo:session): session closed for user root May 23 08:23:26 rivoplus sudo[6070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 08:23:26 rivoplus sudo[6070]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:26 rivoplus volumio[3194]: No protocol specified May 23 08:23:26 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:26 rivoplus volumio[3194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 08:23:26 rivoplus volumio[3194]: No protocol specified May 23 08:23:26 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:26 rivoplus volumio[3194]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:26 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:26 rivoplus volumio[3194]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: Preparing to generate the ALSA configuration file May 23 08:23:26 rivoplus sudo[6080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:26 rivoplus sudo[6080]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:26 rivoplus sudo[6080]: pam_unix(sudo:session): session closed for user root May 23 08:23:26 rivoplus sudo[6083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:26 rivoplus sudo[6083]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:26 rivoplus volumio[3194]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 23 08:23:26 rivoplus volumio[3194]: info: Reading ALSA contributions from plugins. May 23 08:23:26 rivoplus sudo[6083]: pam_unix(sudo:session): session closed for user root May 23 08:23:26 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:26 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:26 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:26 rivoplus sudo[6090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 23 08:23:26 rivoplus sudo[6090]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:26 rivoplus volumio[3194]: info: MPD Permissions set May 23 08:23:26 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:26 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:26 rivoplus volumio[3194]: info: Raat Daemon started successfully May 23 08:23:26 rivoplus volumio[3194]: info: Shairport-Sync Started May 23 08:23:26 rivoplus sudo[6090]: pam_unix(sudo:session): session closed for user root May 23 08:23:26 rivoplus volumio[3194]: (node:3194) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 23 08:23:26 rivoplus volumio[3194]: (node:3194) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 23 08:23:26 rivoplus volumio[3194]: (node:3194) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 23 08:23:26 rivoplus volumio[3194]: (node:3194) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 23 08:23:26 rivoplus volumio[3194]: (node:3194) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 23 08:23:26 rivoplus volumio[3194]: info: Executing endpoint restartRAATSocket May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 23 08:23:26 rivoplus volumio[3194]: info: Executing endpoint restartRAATSocket May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 23 08:23:26 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:26 rivoplus sudo[6093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 23 08:23:26 rivoplus sudo[6093]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:26 rivoplus qobuz-connect[5997]: 20250523 08:23:26.713 [5997.5997] INFO SampleApp: Stopping Local configuration server May 23 08:23:26 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 23 08:23:26 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:26 rivoplus qobuz-connect[5997]: 20250523 08:23:26.723 [5997.5997] INFO SampleApp: shat down connection on UNIX socket May 23 08:23:26 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 23 08:23:26 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 23 08:23:26 rivoplus sudo[6093]: pam_unix(sudo:session): session closed for user root May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:26 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:26 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:26 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:26 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 23 08:23:26 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:26 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:26 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: Not Reporting Auto name since its the default one May 23 08:23:27 rivoplus volumio[3194]: info: Executing endpoint qc_getconfig May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 23 08:23:27 rivoplus qobuz-connect[6105]: 20250523 08:23:27.048 [6105.6105] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 23 08:23:27 rivoplus volumio[3194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 23 08:23:27 rivoplus qobuz-connect[6105]: 20250523 08:23:27.054 [6105.6105] INFO VolumeManager: [0xaccc98f0]: Setting new playback volume: 75 May 23 08:23:27 rivoplus qobuz-connect[6105]: 20250523 08:23:27.054 [6105.6105] INFO VolumeManager: [0xaccc98f0]: Setting new mute state: 0 May 23 08:23:27 rivoplus qobuz-connect[6105]: 20250523 08:23:27.054 [6105.6105] INFO QobuzConnect: [0xaccca2c0]: Client initialized! May 23 08:23:27 rivoplus qobuz-connect[6105]: 20250523 08:23:27.054 [6105.6105] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp May 23 08:23:27 rivoplus volumio[3194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 23 08:23:27 rivoplus volumio[3194]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:27 rivoplus volumio[3194]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:27 rivoplus qobuz-connect[6105]: 20250523 08:23:27.068 [6105.6105] INFO LocalConfigManager: [0xaccc91d0]: Starting Local Configuration server May 23 08:23:27 rivoplus qobuz-connect[6105]: 20250523 08:23:27.068 [6105.6105] INFO SampleApp: Starting Local configuration server May 23 08:23:27 rivoplus qobuz-connect[6105]: 20250523 08:23:27.068 [6105.6105] INFO SampleApp: Connected to UNIX socket client 0xaccbeed8 May 23 08:23:27 rivoplus volumio[3194]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:27 rivoplus volumio[3194]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:27 rivoplus sudo[6146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 23 08:23:27 rivoplus sudo[6146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:27 rivoplus volumio[3194]: info: Starting Shairport Sync May 23 08:23:27 rivoplus sudo[6149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 23 08:23:27 rivoplus sudo[6149]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:27 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 23 08:23:27 rivoplus volumio[3194]: info: QobuzConnect: QOBUZ Connect daemon connected May 23 08:23:27 rivoplus systemd[1]: Started Volumio Tidal Connect Service. May 23 08:23:27 rivoplus sudo[6151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 08:23:27 rivoplus sudo[6151]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:27 rivoplus qobuz-connect[6105]: 20250523 08:23:27.198 [6105.6105] INFO SampleApp: Playback volume changed: 75 May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:27 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:27 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:27 rivoplus volumio[3194]: info: Asound.conf file unchanged, so no further update is needed May 23 08:23:27 rivoplus volumio[3194]: info: Output device has changed, restarting MPD May 23 08:23:27 rivoplus sudo[6146]: pam_unix(sudo:session): session closed for user root May 23 08:23:27 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 08:23:27 rivoplus systemd[1]: shairport-sync.service: Succeeded. May 23 08:23:27 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 08:23:27 rivoplus sudo[6158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 23 08:23:27 rivoplus sudo[6158]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:27 rivoplus volumio[3194]: info: Output device has changed, restarting Shairport Sync May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:27 rivoplus sudo[6149]: pam_unix(sudo:session): session closed for user root May 23 08:23:27 rivoplus sudo[6158]: pam_unix(sudo:session): session closed for user root May 23 08:23:27 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 08:23:27 rivoplus sudo[6151]: pam_unix(sudo:session): session closed for user root May 23 08:23:27 rivoplus sudo[6161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 23 08:23:27 rivoplus sudo[6161]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:27 rivoplus volumio[3194]: No protocol specified May 23 08:23:27 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:27 rivoplus volumio[3194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 23 08:23:27 rivoplus volumio[3194]: No protocol specified May 23 08:23:27 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:27 rivoplus volumio[3194]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:27 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:27 rivoplus volumio[3194]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus sudo[6175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:27 rivoplus sudo[6175]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:27 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... May 23 08:23:27 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 23 08:23:27 rivoplus systemd[1]: vtcs.service: Succeeded. May 23 08:23:27 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. May 23 08:23:27 rivoplus sudo[6179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 23 08:23:27 rivoplus sudo[6179]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:27 rivoplus sudo[6175]: pam_unix(sudo:session): session closed for user root May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 23 08:23:27 rivoplus sudo[6179]: pam_unix(sudo:session): session closed for user root May 23 08:23:27 rivoplus sudo[6186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 23 08:23:27 rivoplus sudo[6186]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:27 rivoplus sudo[6186]: pam_unix(sudo:session): session closed for user root May 23 08:23:27 rivoplus sudo[6191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 23 08:23:27 rivoplus sudo[6191]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:27 rivoplus volumio[3194]: No protocol specified May 23 08:23:27 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:27 rivoplus qobuz-connect[6105]: 20250523 08:23:27.726 [6105.6105] INFO SampleApp: Stopping Local configuration server May 23 08:23:27 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... May 23 08:23:27 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 23 08:23:27 rivoplus volumio[3194]: info: CoreStateMachine::getcurrentVolume May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::volumioRetrievevolume May 23 08:23:27 rivoplus qobuz-connect[6105]: 20250523 08:23:27.737 [6105.6105] INFO SampleApp: shat down connection on UNIX socket May 23 08:23:27 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:27 rivoplus systemd[1]: qobuz-connect.service: Succeeded. May 23 08:23:27 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:27 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:27 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:27 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:27 rivoplus sudo[6194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 23 08:23:27 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:27 rivoplus sudo[6194]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:27 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. May 23 08:23:27 rivoplus sudo[6191]: pam_unix(sudo:session): session closed for user root May 23 08:23:27 rivoplus volumio[3194]: info: MRS: Found cast device: H600-de2e1d930f2917c0c7820327aab363f3 May 23 08:23:27 rivoplus volumio[3194]: info: Adding audio output: May 23 08:23:27 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:27 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:27 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:27 rivoplus sudo[6194]: pam_unix(sudo:session): session closed for user root May 23 08:23:27 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:27 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:27 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:27 rivoplus sudo[6197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 23 08:23:27 rivoplus sudo[6197]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:27 rivoplus volumio[3194]: info: MPD Permissions set May 23 08:23:27 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 23 08:23:27 rivoplus volumio[3194]: info: QobuzConnect: setDeactiveState invoked May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:27 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:27 rivoplus volumio[3194]: info: Shairport-Sync Started May 23 08:23:27 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:27 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:27 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:27 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:27 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:27 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:27 rivoplus systemd[1]: Stopping RAAT DAEMON... May 23 08:23:27 rivoplus volumio[3194]: info: Executing endpoint qc_getconfig May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 23 08:23:27 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 23 08:23:27 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 23 08:23:27 rivoplus systemd[1]: Stopped RAAT DAEMON. May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:27 rivoplus qobuz-connect[6195]: 20250523 08:23:27.921 [6195.6195] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 23 08:23:27 rivoplus qobuz-connect[6195]: 20250523 08:23:27.927 [6195.6195] INFO VolumeManager: [0xac0758f0]: Setting new playback volume: 75 May 23 08:23:27 rivoplus qobuz-connect[6195]: 20250523 08:23:27.927 [6195.6195] INFO VolumeManager: [0xac0758f0]: Setting new mute state: 0 May 23 08:23:27 rivoplus qobuz-connect[6195]: 20250523 08:23:27.927 [6195.6195] INFO QobuzConnect: [0xac0762c0]: Client initialized! May 23 08:23:27 rivoplus qobuz-connect[6195]: 20250523 08:23:27.927 [6195.6195] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp May 23 08:23:27 rivoplus systemd[1]: Started RAAT DAEMON. May 23 08:23:27 rivoplus qobuz-connect[6195]: 20250523 08:23:27.958 [6195.6195] INFO LocalConfigManager: [0xac0751d0]: Starting Local Configuration server May 23 08:23:27 rivoplus qobuz-connect[6195]: 20250523 08:23:27.958 [6195.6195] INFO SampleApp: Starting Local configuration server May 23 08:23:27 rivoplus qobuz-connect[6195]: 20250523 08:23:27.959 [6195.6195] INFO SampleApp: Connected to UNIX socket client 0xac06aed8 May 23 08:23:27 rivoplus sudo[6197]: pam_unix(sudo:session): session closed for user root May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:23:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:28 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:28 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:28 rivoplus volumio[3194]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 23 08:23:28 rivoplus volumio[3194]: info: QobuzConnect: QOBUZ Connect daemon connected May 23 08:23:28 rivoplus qobuz-connect[6195]: 20250523 08:23:28.071 [6195.6195] INFO SampleApp: Playback volume changed: 75 May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:23:28 rivoplus volumio[3194]: info: Not Reporting Auto name since its the default one May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:28 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:28 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:28 rivoplus volumio[3194]: info: Raat Daemon started successfully May 23 08:23:28 rivoplus volumio[3194]: info: Executing endpoint restartRAATSocket May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 23 08:23:28 rivoplus volumio[3194]: info: Starting Shairport Sync May 23 08:23:28 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:28 rivoplus sudo[6223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 23 08:23:28 rivoplus sudo[6223]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 23 08:23:28 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 23 08:23:28 rivoplus systemd[1]: shairport-sync.service: Succeeded. May 23 08:23:28 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 23 08:23:28 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 23 08:23:28 rivoplus sudo[6223]: pam_unix(sudo:session): session closed for user root May 23 08:23:28 rivoplus volumio[3194]: No protocol specified May 23 08:23:28 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:23:28 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:28 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:28 rivoplus sudo[6235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 23 08:23:28 rivoplus sudo[6235]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:28 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:28 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:28 rivoplus sudo[6235]: pam_unix(sudo:session): session closed for user root May 23 08:23:28 rivoplus volumio[3194]: info: Shairport-Sync Started May 23 08:23:28 rivoplus sudo[6242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 23 08:23:28 rivoplus sudo[6242]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:28 rivoplus volumio[3194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 23 08:23:28 rivoplus systemd[1]: Stopping RAAT DAEMON... May 23 08:23:28 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 23 08:23:28 rivoplus systemd[1]: raat-daemon.service: Succeeded. May 23 08:23:28 rivoplus systemd[1]: Stopped RAAT DAEMON. May 23 08:23:28 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:28 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:28 rivoplus volumio[3194]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:28 rivoplus volumio[3194]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:28 rivoplus systemd[1]: Started RAAT DAEMON. May 23 08:23:28 rivoplus sudo[6242]: pam_unix(sudo:session): session closed for user root May 23 08:23:28 rivoplus volumio[3194]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:28 rivoplus volumio[3194]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:28 rivoplus sudo[6258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 23 08:23:28 rivoplus sudo[6258]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:28 rivoplus sudo[6263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 23 08:23:28 rivoplus volumio[3194]: info: Raat Daemon started successfully May 23 08:23:28 rivoplus sudo[6263]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:28 rivoplus volumio[3194]: info: Executing endpoint restartRAATSocket May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 23 08:23:28 rivoplus systemd[1]: Started Volumio Tidal Connect Service. May 23 08:23:28 rivoplus sudo[6258]: pam_unix(sudo:session): session closed for user root May 23 08:23:28 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:28 rivoplus volumio[3194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 23 08:23:28 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:28 rivoplus sudo[6263]: pam_unix(sudo:session): session closed for user root May 23 08:23:28 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:28 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:28 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:28 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:28 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:28 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:28 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:28 rivoplus volumio[3194]: info: Executing endpoint tc_getconfig May 23 08:23:28 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 23 08:23:28 rivoplus vtcs[6268]: STARTING TidalConnect services, version: 1.4.0.34 May 23 08:23:29 rivoplus vtcs[6268]: STARTED TidalConnect services. May 23 08:23:29 rivoplus volumio[3194]: info: Executing endpoint tc_connect May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 23 08:23:29 rivoplus volumio[3194]: info: Connecting to TidalConnect May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::servicePushState May 23 08:23:29 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: 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} May 23 08:23:29 rivoplus volumio[3194]: verbose: CURRENT POSITION 0 May 23 08:23:29 rivoplus volumio[3194]: info: CoreStateMachine::syncState stateService stop May 23 08:23:29 rivoplus volumio[3194]: info: CoreStateMachine::syncState currentStatus stop May 23 08:23:29 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:29 rivoplus volumio[3194]: info: No code May 23 08:23:29 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::servicePushState May 23 08:23:29 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: 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} May 23 08:23:29 rivoplus volumio[3194]: verbose: CURRENT POSITION 0 May 23 08:23:29 rivoplus volumio[3194]: info: CoreStateMachine::syncState stateService stop May 23 08:23:29 rivoplus volumio[3194]: info: CoreStateMachine::syncState currentStatus stop May 23 08:23:29 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:29 rivoplus volumio[3194]: info: No code May 23 08:23:29 rivoplus volumio[3194]: info: CoreStateMachine::pushState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioPushState May 23 08:23:29 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:29 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output update for this device May 23 08:23:29 rivoplus volumio[3194]: info: MRS: Pushing multiroomSync output May 23 08:23:29 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:29 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:29 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:29 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:29 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:29 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:29 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:29 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:29 rivoplus volumio[3194]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:29 rivoplus volumio[3194]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:29 rivoplus sudo[6300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 23 08:23:29 rivoplus sudo[6300]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:29 rivoplus sudo[6300]: pam_unix(sudo:session): session closed for user root May 23 08:23:30 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:30 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:30 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:30 rivoplus volumio[3194]: info: TidalConnect service stoped! May 23 08:23:30 rivoplus volumio[3194]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:30 rivoplus volumio[3194]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 23 08:23:30 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:30 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:30 rivoplus sudo[6312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 23 08:23:30 rivoplus sudo[6312]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:30 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:30 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:30 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:30 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:30 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:30 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:30 rivoplus sudo[6312]: pam_unix(sudo:session): session closed for user root May 23 08:23:31 rivoplus volumio[3194]: info: RAAT: Requesting Headphone Status May 23 08:23:31 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus May 23 08:23:31 rivoplus volumio[3194]: info: MCU Signalled Headphone Mode Disabled May 23 08:23:31 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 23 08:23:31 rivoplus volumio[3194]: info: Reporting Headphone State: false May 23 08:23:31 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:31 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:23:31 rivoplus volumio[3194]: info: Updating RAAT Signal Path May 23 08:23:31 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:31 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:32 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:33 rivoplus volumio[3194]: info: TidalConnect service started! May 23 08:23:39 rivoplus volumio[3194]: info: MRS: Found cast device: H600-de2e1d930f2917c0c7820327aab363f3 May 23 08:23:54 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 08:23:54 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 08:23:54 rivoplus volumio[3194]: info: Discovery: Getting this device information May 23 08:23:54 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:23:54 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:23:54 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 08:23:55 rivoplus systemd[1]: mpd.service: State 'stop-sigterm' timed out. Killing. May 23 08:23:55 rivoplus systemd[1]: mpd.service: Killing process 3790 (mpd) with signal SIGKILL. May 23 08:23:55 rivoplus volumio[3194]: error: Upnp client error: Error: read ECONNRESET May 23 08:23:55 rivoplus volumio[3194]: error: MPD error: Error: read ECONNRESET May 23 08:23:55 rivoplus volumio[3194]: error: MPD error: Error: read ECONNRESET May 23 08:23:55 rivoplus volumio[3194]: error: MPD error: Error: read ECONNRESET May 23 08:23:55 rivoplus systemd[1]: mpd.service: Main process exited, code=killed, status=9/KILL May 23 08:23:55 rivoplus systemd[1]: mpd.service: Failed with result 'timeout'. May 23 08:23:55 rivoplus systemd[1]: Stopped Music Player Daemon. May 23 08:23:55 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 23 08:23:55 rivoplus systemd[1]: Starting Music Player Daemon... May 23 08:23:55 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:23:55 rivoplus sudo[6356]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 23 08:23:55 rivoplus sudo[6356]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:23:55 rivoplus sudo[6356]: pam_unix(sudo:session): session closed for user root May 23 08:23:57 rivoplus mpd[6366]: May 23 08:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 23 08:23:57 rivoplus systemd[1]: Started Music Player Daemon. May 23 08:23:57 rivoplus volumio[3194]: Upnp client error: Error: This socket has been ended by the other party May 23 08:23:57 rivoplus sudo[5180]: pam_unix(sudo:session): session closed for user root May 23 08:23:57 rivoplus sudo[5937]: pam_unix(sudo:session): session closed for user root May 23 08:23:57 rivoplus sudo[5452]: pam_unix(sudo:session): session closed for user root May 23 08:23:57 rivoplus sudo[5032]: pam_unix(sudo:session): session closed for user root May 23 08:23:57 rivoplus sudo[6070]: pam_unix(sudo:session): session closed for user root May 23 08:23:57 rivoplus sudo[5049]: pam_unix(sudo:session): session closed for user root May 23 08:23:57 rivoplus sudo[5242]: pam_unix(sudo:session): session closed for user root May 23 08:23:57 rivoplus sudo[6161]: pam_unix(sudo:session): session closed for user root May 23 08:23:57 rivoplus mpd_monitor.sh[2830]: MPD Monitor Service: MPD restarted due to no mpc output. May 23 08:23:57 rivoplus sudo[5693]: pam_unix(sudo:session): session closed for user root May 23 08:23:57 rivoplus sudo[5953]: pam_unix(sudo:session): session closed for user root May 23 08:23:57 rivoplus volumio[3194]: error: MPD error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: MPD error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: MPD error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: MPD error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: MPD error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: MPD error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: MPD error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: MPD error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: MPD error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: The expression evaluated to a falsy value: May 23 08:23:57 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:23:57 rivoplus volumio[3194]: error: updateQueue error: null May 23 08:24:00 rivoplus volumio[3194]: error: MPD error: The expression evaluated to a falsy value: May 23 08:24:00 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:24:00 rivoplus volumio[3194]: error: The expression evaluated to a falsy value: May 23 08:24:00 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:24:00 rivoplus volumio[3194]: error: MPD error: The expression evaluated to a falsy value: May 23 08:24:00 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:24:00 rivoplus volumio[3194]: error: The expression evaluated to a falsy value: May 23 08:24:00 rivoplus volumio[3194]: assert.ok(self.idling) May 23 08:24:00 rivoplus volumio[3194]: error: updateQueue error: null May 23 08:24:05 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:24:05 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 08:24:07 rivoplus volumio[3194]: No protocol specified May 23 08:24:07 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 08:24:07 rivoplus volumio[3194]: No protocol specified May 23 08:24:07 rivoplus volumio[3194]: xcb_connection_has_error() returned true May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 08:24:07 rivoplus volumio[3194]: info: Executing endpoint metavolumio May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 08:24:07 rivoplus volumio[3194]: info: Executing endpoint metavolumio May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 08:24:07 rivoplus volumio[3194]: info: Executing endpoint metavolumio May 23 08:24:07 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 08:24:14 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:24:14 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:24:16 rivoplus volumio[3194]: info: Executing endpoint metavolumio May 23 08:24:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 08:24:16 rivoplus volumio[3194]: info: Executing endpoint metavolumio May 23 08:24:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 08:24:16 rivoplus volumio[3194]: info: Executing endpoint metavolumio May 23 08:24:16 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 08:24:17 rivoplus kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 May 23 08:24:17 rivoplus kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x2 [current] May 23 08:24:17 rivoplus kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x4 ASCQ=0x1 May 23 08:24:17 rivoplus kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 00 00 00 00 08 00 May 23 08:24:17 rivoplus kernel: blk_update_request: I/O error, dev sda, sector 0 May 23 08:24:17 rivoplus kernel: Buffer I/O error on dev sda, logical block 0, async page read May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 08:24:18 rivoplus volumio[3194]: info: Retrieving Cloud Streaming UI May 23 08:24:18 rivoplus volumio[3194]: info: Getting Tidal Cloud Configuration May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 08:24:18 rivoplus volumio[3194]: info: Getting Qobuz Cloud Configuration May 23 08:24:18 rivoplus volumio[3194]: info: Asking plugin for UI Config May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 08:24:18 rivoplus volumio[3194]: info: Getting Spotify Cloud Configuration May 23 08:24:18 rivoplus volumio[3194]: info: Asking plugin for UI Config May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 08:24:18 rivoplus volumio[3194]: info: Saving Spotify Acccount May 23 08:24:18 rivoplus volumio[3194]: info: Got it May 23 08:24:18 rivoplus volumio[3194]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 23 08:24:18 rivoplus volumio[3194]: info: Got Tidal Cloud Configuration May 23 08:24:18 rivoplus volumio[3194]: info: Got it May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetBrowseSources May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetBrowseSources May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetBrowseSources May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 08:24:18 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 23 08:24:21 rivoplus volumio[3194]: info: Disabling MyMusic plugin upnp May 23 08:24:21 rivoplus sudo[6473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 23 08:24:21 rivoplus sudo[6473]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 08:24:21 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... May 23 08:24:21 rivoplus volumio[3194]: ------------------------------------ BT MESSAGE: BT STATUS: running May 23 08:24:22 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 08:24:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 08:24:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 08:24:24 rivoplus volumio[3194]: info: Discovery: Getting this device information May 23 08:24:24 rivoplus volumio[3194]: info: CoreCommandRouter::volumioGetState May 23 08:24:24 rivoplus volumio[3194]: info: CorePlayQueue::getTrack 0 May 23 08:24:24 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 08:24:26 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 23 08:24:27 rivoplus volumio[3194]: info: Enabling MyMusic plugin upnp May 23 08:24:27 rivoplus volumio[3194]: info: Enabling plugin upnp May 23 08:24:27 rivoplus volumio[3194]: info: Loading plugin "upnp"... May 23 08:24:27 rivoplus volumio[3194]: info: [1747981467511] Starting Upmpd Daemon May 23 08:24:27 rivoplus volumio[3194]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 23 08:24:27 rivoplus volumio[3194]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 08:24:27 rivoplus volumio[3194]: Error: listen EADDRINUSE: address already in use :::6599 May 23 08:24:27 rivoplus volumio[3194]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) May 23 08:24:27 rivoplus volumio[3194]: at listenInCluster (net.js:1379:12) May 23 08:24:27 rivoplus volumio[3194]: at Server.listen (net.js:1465:7) May 23 08:24:27 rivoplus volumio[3194]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 23 08:24:27 rivoplus volumio[3194]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 23 08:24:27 rivoplus volumio[3194]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 23 08:24:27 rivoplus volumio[3194]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 23 08:24:27 rivoplus volumio[3194]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { May 23 08:24:27 rivoplus volumio[3194]: code: 'EADDRINUSE', May 23 08:24:27 rivoplus volumio[3194]: errno: -98, May 23 08:24:27 rivoplus volumio[3194]: syscall: 'listen', May 23 08:24:27 rivoplus volumio[3194]: address: '::', May 23 08:24:27 rivoplus volumio[3194]: port: 6599 May 23 08:24:27 rivoplus volumio[3194]: } May 23 08:24:27 rivoplus volumio[3194]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 08:24:28 rivoplus sudo[6501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-23 08:23 May 23 08:24:28 rivoplus sudo[6501]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:04:38 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="0064c1a7ac4363e567dacab062cb5da8"