-- Logs begin at Fri 2025-06-27 20:51:59 EEST, end at Fri 2025-06-27 20:57:30 EEST. -- Jun 27 20:56:27 rivoplus volumio[3423]: verbose: New Socket.io Connection to 192.168.1.54 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Jun 27 20:56:27 rivoplus volumio[3423]: verbose: New Socket.io Connection to 192.168.1.53 from 192.168.1.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Jun 27 20:56:27 rivoplus volumio[3423]: info: Received Get System Info Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 20:56:27 rivoplus volumio[3423]: info: Discovery: Getting this device information Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:56:27 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:56:27 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetVisibleSources Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:56:27 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 27 20:56:27 rivoplus volumio[3423]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 27 20:56:27 rivoplus volumio[3423]: info: Received Get System Info Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 20:56:27 rivoplus volumio[3423]: info: Discovery: Getting this device information Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:56:27 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:56:27 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:56:27 rivoplus volumio[3423]: info: Listing playlists Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:56:27 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:56:29 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 27 20:56:29 rivoplus go-librespot[4142]: time="2025-06-27T20:56:29+03:00" level=trace msg="sent dealer ping" Jun 27 20:56:29 rivoplus go-librespot[4142]: time="2025-06-27T20:56:29+03:00" level=trace msg="received dealer pong" Jun 27 20:56:33 rivoplus volumio[3423]: info: VolumeController::SetAlsaVolume25 Jun 27 20:56:33 rivoplus volumio[3423]: info: CoreStateMachine::pushState Jun 27 20:56:33 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:56:33 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 20:56:33 rivoplus volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 27 20:56:33 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:56:33 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:56:33 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 27 20:56:33 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output Jun 27 20:56:33 rivoplus volumio[3423]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 27 20:56:33 rivoplus volumio[3423]: SPOTIFY: SPOTIFY VOLUME 10 Jun 27 20:56:33 rivoplus volumio[3423]: SPOTIFY: VOLUMIO VOLUME 25 Jun 27 20:56:33 rivoplus volumio[3423]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 27 20:56:33 rivoplus volumio[3423]: info: Setting Spotify Volume from Volumio: 25 Jun 27 20:56:33 rivoplus volumio[3423]: info: Updating RAAT Signal Path Jun 27 20:56:33 rivoplus volumio[3423]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jun 27 20:56:33 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:56:33 rivoplus volumio[3423]: info: VolumeController::SetAlsaVolume26 Jun 27 20:56:33 rivoplus volumio[3423]: info: CoreStateMachine::pushState Jun 27 20:56:33 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:56:33 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 20:56:33 rivoplus volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 27 20:56:33 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:56:33 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:56:33 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 27 20:56:33 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output Jun 27 20:56:33 rivoplus volumio[3423]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Jun 27 20:56:33 rivoplus volumio[3423]: SPOTIFY: SPOTIFY VOLUME 25 Jun 27 20:56:33 rivoplus volumio[3423]: SPOTIFY: VOLUMIO VOLUME 26 Jun 27 20:56:33 rivoplus volumio[3423]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 27 20:56:33 rivoplus volumio[3423]: info: Updating RAAT Signal Path Jun 27 20:56:33 rivoplus volumio[3423]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jun 27 20:56:33 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:56:34 rivoplus volumio[3423]: SPOTIFY: SETTING SPOTIFY VOLUME 25 Jun 27 20:56:34 rivoplus volumio[3423]: info: Sending Spotify command with payload to local API: /player/volume Jun 27 20:56:34 rivoplus go-librespot[4142]: time="2025-06-27T20:56:34+03:00" level=debug msg="update volume to 16383/65535" Jun 27 20:56:36 rivoplus go-librespot[4142]: time="2025-06-27T20:56:36+03:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 27 20:56:36 rivoplus go-librespot[4142]: time="2025-06-27T20:56:36+03:00" level=trace msg="emitting websocket event: volume" Jun 27 20:56:36 rivoplus volumio[3423]: SPOTIFY: received: {"type":"volume","data":{"value":25,"max":100}} Jun 27 20:56:36 rivoplus volumio[3423]: SPOTIFY: RECEIVED SPOTIFY VOLUME 25 Jun 27 20:56:37 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 27 20:56:38 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetQueue Jun 27 20:56:38 rivoplus volumio[3423]: info: CoreStateMachine::getQueue Jun 27 20:56:38 rivoplus volumio[3423]: info: CorePlayQueue::getQueue Jun 27 20:56:38 rivoplus volumio[3423]: info: Executing endpoint metavolumio Jun 27 20:56:38 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 27 20:56:38 rivoplus volumio[3423]: info: Executing endpoint metavolumio Jun 27 20:56:38 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 27 20:56:42 rivoplus volumio[3423]: error: Failed request for metavolumio API Jun 27 20:56:49 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:56:49 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 27 20:56:49 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 27 20:56:49 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 27 20:56:49 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 27 20:56:49 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Jun 27 20:56:49 rivoplus volumio[3423]: info: Received Get System Version Jun 27 20:56:49 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 20:56:59 rivoplus go-librespot[4142]: time="2025-06-27T20:56:59+03:00" level=trace msg="received accesspoint ping" Jun 27 20:56:59 rivoplus go-librespot[4142]: time="2025-06-27T20:56:59+03:00" level=trace msg="received accesspoint pong ack" Jun 27 20:56:59 rivoplus go-librespot[4142]: time="2025-06-27T20:56:59+03:00" level=trace msg="sent dealer ping" Jun 27 20:56:59 rivoplus go-librespot[4142]: time="2025-06-27T20:56:59+03:00" level=trace msg="received dealer pong" Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:57:00 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:00 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 27 20:57:00 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:00 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:00 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:01 rivoplus volumio[3423]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 27 20:57:01 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 27 20:57:01 rivoplus volumio[3423]: info: Preparing to save Alsa Options, stopping services first Jun 27 20:57:01 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:01 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:01 rivoplus volumio[3423]: info: CoreCommandRouter::volumioPause Jun 27 20:57:01 rivoplus volumio[3423]: info: CoreStateMachine::pause Jun 27 20:57:01 rivoplus volumio[3423]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"DENAFRIPS USB v3.12 | A12.1.8"}} Jun 27 20:57:01 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 27 20:57:01 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:01 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:01 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:01 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:01 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 27 20:57:02 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:02 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:02 rivoplus volumio[3423]: info: Setting mixer PCM for card DENAFRIPS USB v3.12 | A12.1.8 Jun 27 20:57:02 rivoplus volumio[3423]: info: QobuzConnect: setDeactiveState invoked Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:02 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:02 rivoplus volumio[3423]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Jun 27 20:57:02 rivoplus vtcs[4593]: [2025-06-27 20:57:02.018] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 27 20:57:02 rivoplus vtcs[4593]: [2025-06-27 20:57:02.021] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Jun 27 20:57:02 rivoplus vtcs[4593]: [2025-06-27 20:57:02.021] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 27 20:57:02 rivoplus sudo[5411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 27 20:57:02 rivoplus sudo[5411]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:02 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Jun 27 20:57:02 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 27 20:57:02 rivoplus systemd[1]: vtcs.service: Succeeded. Jun 27 20:57:02 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Jun 27 20:57:02 rivoplus sudo[5411]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:02 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:02 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 27 20:57:02 rivoplus volumio[3423]: info: Updating Volume Controller Parameters: Device: 5 Name: DENAFRIPS USB v3.12 | A12.1.8 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 27 20:57:02 rivoplus volumio[3423]: info: Disabling external Volume Control Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:57:02 rivoplus volumio[3423]: info: Preparing to generate the ALSA configuration file Jun 27 20:57:02 rivoplus sudo[5418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 27 20:57:02 rivoplus sudo[5418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:02 rivoplus sudo[5418]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:02 rivoplus volumio[3423]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 27 20:57:02 rivoplus volumio[3423]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 27 20:57:02 rivoplus volumio[3423]: info: Reading ALSA contributions from plugins. Jun 27 20:57:02 rivoplus sudo[5425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 27 20:57:02 rivoplus sudo[5425]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:02 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 27 20:57:02 rivoplus sudo[5425]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:02 rivoplus volumio[3423]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jun 27 20:57:02 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:02 rivoplus sudo[5427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 27 20:57:02 rivoplus sudo[5427]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:02 rivoplus qobuz-connect[4571]: 20250627 20:57:02.433 [4571.4571] INFO SampleApp: Stopping Local configuration server Jun 27 20:57:02 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 27 20:57:02 rivoplus qobuz-connect[4571]: 20250627 20:57:02.443 [4571.4571] INFO SampleApp: shat down connection on UNIX socket Jun 27 20:57:02 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jun 27 20:57:02 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 27 20:57:02 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jun 27 20:57:02 rivoplus sudo[5427]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:02 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:02 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 27 20:57:02 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:02 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: Not Reporting Auto name since its the default one Jun 27 20:57:02 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 27 20:57:02 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 27 20:57:02 rivoplus volumio[3423]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 27 20:57:02 rivoplus volumio[3423]: info: QobuzConnect: setDeactiveState invoked Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:02 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:02 rivoplus volumio[3423]: info: VolumeController:: Volume=undefined Mute =false Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreStateMachine::pushState Jun 27 20:57:02 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:02 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:02 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 27 20:57:02 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output Jun 27 20:57:02 rivoplus volumio[3423]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Jun 27 20:57:02 rivoplus volumio[3423]: SPOTIFY: SPOTIFY VOLUME 25 Jun 27 20:57:02 rivoplus volumio[3423]: SPOTIFY: VOLUMIO VOLUME 26 Jun 27 20:57:02 rivoplus volumio[3423]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 27 20:57:02 rivoplus volumio[3423]: info: Updating RAAT Signal Path Jun 27 20:57:02 rivoplus volumio[3423]: info: Executing endpoint qc_getconfig Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 27 20:57:02 rivoplus qobuz-connect[5431]: 20250627 20:57:02.825 [5431.5431] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 27 20:57:02 rivoplus volumio[3423]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 27 20:57:02 rivoplus qobuz-connect[5431]: 20250627 20:57:02.832 [5431.5431] INFO VolumeManager: [0xacc927f0]: Setting new playback volume: 75 Jun 27 20:57:02 rivoplus qobuz-connect[5431]: 20250627 20:57:02.833 [5431.5431] INFO VolumeManager: [0xacc927f0]: Setting new mute state: 0 Jun 27 20:57:02 rivoplus qobuz-connect[5431]: 20250627 20:57:02.833 [5431.5431] INFO QobuzConnect: [0xacc931c0]: Client initialized! Jun 27 20:57:02 rivoplus qobuz-connect[5431]: 20250627 20:57:02.833 [5431.5431] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jun 27 20:57:02 rivoplus volumio[3423]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:02 rivoplus qobuz-connect[5431]: 20250627 20:57:02.846 [5431.5431] INFO LocalConfigManager: [0xacc920d0]: Starting Local Configuration server Jun 27 20:57:02 rivoplus qobuz-connect[5431]: 20250627 20:57:02.846 [5431.5431] INFO SampleApp: Starting Local configuration server Jun 27 20:57:02 rivoplus qobuz-connect[5431]: 20250627 20:57:02.846 [5431.5431] INFO SampleApp: Connected to UNIX socket client 0xacc87ed8 Jun 27 20:57:02 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 27 20:57:02 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:02 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:02 rivoplus volumio[3423]: info: Asound.conf file unchanged, so no further update is needed Jun 27 20:57:02 rivoplus volumio[3423]: info: Output device has changed, restarting MPD Jun 27 20:57:02 rivoplus qobuz-connect[5431]: 20250627 20:57:02.975 [5431.5431] INFO SampleApp: Playback volume changed: 75 Jun 27 20:57:02 rivoplus sudo[5455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 27 20:57:02 rivoplus sudo[5455]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:03 rivoplus sudo[5455]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:03 rivoplus sudo[5458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 20:57:03 rivoplus sudo[5458]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:03 rivoplus sudo[5459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 27 20:57:03 rivoplus sudo[5459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:03 rivoplus sudo[5458]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:03 rivoplus volumio[3423]: info: Output device has changed, restarting Shairport Sync Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 20:57:03 rivoplus systemd[1]: Stopping RAAT DAEMON... Jun 27 20:57:03 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 27 20:57:03 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jun 27 20:57:03 rivoplus systemd[1]: Stopped RAAT DAEMON. Jun 27 20:57:03 rivoplus sudo[5463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 20:57:03 rivoplus sudo[5463]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:03 rivoplus systemd[1]: Started RAAT DAEMON. Jun 27 20:57:03 rivoplus sudo[5459]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:03 rivoplus systemd[1]: Stopping Music Player Daemon... Jun 27 20:57:03 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:03 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:03 rivoplus volumio[3423]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 27 20:57:03 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:03 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:03 rivoplus volumio[3423]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 27 20:57:03 rivoplus systemd[1]: mpd.service: Succeeded. Jun 27 20:57:03 rivoplus systemd[1]: Stopped Music Player Daemon. Jun 27 20:57:03 rivoplus systemd[1]: Starting Music Player Daemon... Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: QobuzConnect: setDeactiveState invoked Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:03 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:03 rivoplus volumio[3423]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus sudo[5482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 27 20:57:03 rivoplus sudo[5482]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:03 rivoplus sudo[5485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 27 20:57:03 rivoplus sudo[5485]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:03 rivoplus sudo[5482]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:03 rivoplus sudo[5477]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 20:57:03 rivoplus sudo[5477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:03 rivoplus sudo[5477]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:03 rivoplus sudo[5485]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:03 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 27 20:57:03 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 27 20:57:03 rivoplus sudo[5494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 27 20:57:03 rivoplus sudo[5494]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:03 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 27 20:57:03 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 27 20:57:03 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 27 20:57:03 rivoplus sudo[5494]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:03 rivoplus sudo[5497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 27 20:57:03 rivoplus sudo[5497]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:03 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:03 rivoplus volumio[3423]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jun 27 20:57:03 rivoplus volumio[3423]: info: MPD Permissions set Jun 27 20:57:03 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 27 20:57:03 rivoplus volumio[3423]: info: Raat Daemon started successfully Jun 27 20:57:03 rivoplus qobuz-connect[5431]: 20250627 20:57:03.500 [5431.5431] INFO SampleApp: Stopping Local configuration server Jun 27 20:57:03 rivoplus qobuz-connect[5431]: 20250627 20:57:03.511 [5431.5431] INFO SampleApp: shat down connection on UNIX socket Jun 27 20:57:03 rivoplus volumio[3423]: info: Executing endpoint restartRAATSocket Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 27 20:57:03 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jun 27 20:57:03 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 27 20:57:03 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 27 20:57:03 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jun 27 20:57:03 rivoplus sudo[5497]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:03 rivoplus volumio[3423]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 27 20:57:03 rivoplus volumio[3423]: info: QobuzConnect: setDeactiveState invoked Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:03 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 27 20:57:03 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:03 rivoplus volumio[3423]: info: Executing endpoint qc_getconfig Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 27 20:57:03 rivoplus qobuz-connect[5500]: 20250627 20:57:03.732 [5500.5500] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 27 20:57:03 rivoplus volumio[3423]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 27 20:57:03 rivoplus qobuz-connect[5500]: 20250627 20:57:03.740 [5500.5500] INFO VolumeManager: [0xab82e7f0]: Setting new playback volume: 75 Jun 27 20:57:03 rivoplus qobuz-connect[5500]: 20250627 20:57:03.740 [5500.5500] INFO VolumeManager: [0xab82e7f0]: Setting new mute state: 0 Jun 27 20:57:03 rivoplus qobuz-connect[5500]: 20250627 20:57:03.740 [5500.5500] INFO QobuzConnect: [0xab82f1c0]: Client initialized! Jun 27 20:57:03 rivoplus qobuz-connect[5500]: 20250627 20:57:03.740 [5500.5500] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jun 27 20:57:03 rivoplus volumio[3423]: info: Starting Shairport Sync Jun 27 20:57:03 rivoplus qobuz-connect[5500]: 20250627 20:57:03.755 [5500.5500] INFO LocalConfigManager: [0xab82e0d0]: Starting Local Configuration server Jun 27 20:57:03 rivoplus qobuz-connect[5500]: 20250627 20:57:03.756 [5500.5500] INFO SampleApp: Starting Local configuration server Jun 27 20:57:03 rivoplus qobuz-connect[5500]: 20250627 20:57:03.756 [5500.5500] INFO SampleApp: Connected to UNIX socket client 0xab823ed8 Jun 27 20:57:03 rivoplus volumio[3423]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 27 20:57:03 rivoplus volumio[3423]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 27 20:57:03 rivoplus sudo[5512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 27 20:57:03 rivoplus sudo[5512]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:03 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 27 20:57:03 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jun 27 20:57:03 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 27 20:57:03 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 27 20:57:03 rivoplus sudo[5512]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:03 rivoplus volumio[3423]: info: Not Reporting Auto name since its the default one Jun 27 20:57:03 rivoplus qobuz-connect[5500]: 20250627 20:57:03.882 [5500.5500] INFO SampleApp: Playback volume changed: 75 Jun 27 20:57:03 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 27 20:57:03 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 27 20:57:03 rivoplus volumio[3423]: info: Shairport-Sync Started Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:03 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:03 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 27 20:57:04 rivoplus volumio[3423]: No protocol specified Jun 27 20:57:04 rivoplus volumio[3423]: xcb_connection_has_error() returned true Jun 27 20:57:04 rivoplus sudo[5526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 27 20:57:04 rivoplus sudo[5526]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:04 rivoplus sudo[5526]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:04 rivoplus sudo[5528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 27 20:57:04 rivoplus sudo[5528]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:04 rivoplus systemd[1]: Stopping RAAT DAEMON... Jun 27 20:57:04 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 27 20:57:04 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jun 27 20:57:04 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 27 20:57:04 rivoplus systemd[1]: Stopped RAAT DAEMON. Jun 27 20:57:04 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 27 20:57:04 rivoplus systemd[1]: Started RAAT DAEMON. Jun 27 20:57:04 rivoplus sudo[5528]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:04 rivoplus volumio[3423]: info: Raat Daemon started successfully Jun 27 20:57:04 rivoplus volumio[3423]: info: Executing endpoint restartRAATSocket Jun 27 20:57:04 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 27 20:57:04 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:04 rivoplus volumio[3423]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 27 20:57:04 rivoplus volumio[3423]: info: Executing endpoint metavolumio Jun 27 20:57:04 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 27 20:57:05 rivoplus volumio[3423]: info: TidalConnect service stoped! Jun 27 20:57:05 rivoplus volumio[3423]: info: TidalConnect service stoped! Jun 27 20:57:05 rivoplus volumio[3423]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 27 20:57:05 rivoplus volumio[3423]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 27 20:57:05 rivoplus sudo[5591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 27 20:57:05 rivoplus sudo[5591]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:05 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Jun 27 20:57:05 rivoplus sudo[5591]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:05 rivoplus mpd[5495]: Jun 27 20:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 20:57:05 rivoplus systemd[1]: Started Music Player Daemon. Jun 27 20:57:05 rivoplus sudo[5463]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:05 rivoplus volumio[3423]: error: updateQueue error: null Jun 27 20:57:05 rivoplus volumio[3423]: info: Executing endpoint tc_getconfig Jun 27 20:57:05 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 27 20:57:05 rivoplus vtcs[5593]: STARTING TidalConnect services, version: 1.5.1-beta1 Jun 27 20:57:05 rivoplus vtcs[5593]: STARTED TidalConnect services. Jun 27 20:57:05 rivoplus volumio[3423]: info: Executing endpoint tc_connect Jun 27 20:57:05 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 27 20:57:05 rivoplus volumio[3423]: info: Connecting to TidalConnect Jun 27 20:57:05 rivoplus volumio[3423]: info: CoreCommandRouter::servicePushState Jun 27 20:57:05 rivoplus volumio[3423]: info: CoreStateMachine::pushState Jun 27 20:57:05 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:05 rivoplus volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 27 20:57:05 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:05 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:05 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 27 20:57:05 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output Jun 27 20:57:05 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:05 rivoplus volumio[3423]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jun 27 20:57:05 rivoplus volumio[3423]: info: CoreCommandRouter::servicePushState Jun 27 20:57:05 rivoplus volumio[3423]: info: CoreStateMachine::pushState Jun 27 20:57:05 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:05 rivoplus volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 27 20:57:05 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:05 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:05 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 27 20:57:05 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output Jun 27 20:57:05 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:05 rivoplus volumio[3423]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jun 27 20:57:05 rivoplus volumio[3423]: info: RAAT: Requesting Headphone Status Jun 27 20:57:05 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 27 20:57:05 rivoplus volumio[3423]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Jun 27 20:57:05 rivoplus volumio[3423]: SPOTIFY: SPOTIFY VOLUME 25 Jun 27 20:57:05 rivoplus volumio[3423]: SPOTIFY: VOLUMIO VOLUME 26 Jun 27 20:57:05 rivoplus volumio[3423]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 27 20:57:05 rivoplus volumio[3423]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Jun 27 20:57:05 rivoplus volumio[3423]: SPOTIFY: SPOTIFY VOLUME 25 Jun 27 20:57:05 rivoplus volumio[3423]: SPOTIFY: VOLUMIO VOLUME 26 Jun 27 20:57:05 rivoplus volumio[3423]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 27 20:57:05 rivoplus volumio[3423]: info: Updating RAAT Signal Path Jun 27 20:57:05 rivoplus volumio[3423]: info: Updating RAAT Signal Path Jun 27 20:57:05 rivoplus volumio[3423]: info: MCU Signalled Headphone Mode Disabled Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 27 20:57:06 rivoplus volumio[3423]: info: Reporting Headphone State: false Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:06 rivoplus volumio[3423]: info: Updating RAAT Signal Path Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreStateMachine::getcurrentVolume Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 20:57:06 rivoplus volumio[3423]: info: VolumeController:: Volume=26 Mute =false Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreStateMachine::pushState Jun 27 20:57:06 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:06 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:06 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 27 20:57:06 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreStateMachine::updateTrackBlock Jun 27 20:57:06 rivoplus volumio[3423]: info: CorePlayQueue::getTrackBlock Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 20:57:06 rivoplus volumio[3423]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Jun 27 20:57:06 rivoplus volumio[3423]: SPOTIFY: SPOTIFY VOLUME 25 Jun 27 20:57:06 rivoplus volumio[3423]: SPOTIFY: VOLUMIO VOLUME 26 Jun 27 20:57:06 rivoplus volumio[3423]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 27 20:57:06 rivoplus volumio[3423]: info: Updating RAAT Signal Path Jun 27 20:57:06 rivoplus volumio[3423]: info: TidalConnect service stoped! Jun 27 20:57:06 rivoplus volumio[3423]: info: TidalConnect service stoped! Jun 27 20:57:06 rivoplus volumio[3423]: info: VolumeController:: Volume=26 Mute =false Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreStateMachine::pushState Jun 27 20:57:06 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreCommandRouter::volumioPushState Jun 27 20:57:06 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetState Jun 27 20:57:06 rivoplus volumio[3423]: info: CorePlayQueue::getTrack 0 Jun 27 20:57:06 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output update for this device Jun 27 20:57:06 rivoplus volumio[3423]: info: MRS: Pushing multiroomSync output Jun 27 20:57:06 rivoplus volumio[3423]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Jun 27 20:57:06 rivoplus volumio[3423]: SPOTIFY: SPOTIFY VOLUME 25 Jun 27 20:57:06 rivoplus volumio[3423]: SPOTIFY: VOLUMIO VOLUME 26 Jun 27 20:57:06 rivoplus volumio[3423]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 27 20:57:06 rivoplus volumio[3423]: info: Updating RAAT Signal Path Jun 27 20:57:06 rivoplus volumio[3423]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 27 20:57:06 rivoplus volumio[3423]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 27 20:57:06 rivoplus sudo[5628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 27 20:57:06 rivoplus sudo[5628]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:06 rivoplus sudo[5628]: pam_unix(sudo:session): session closed for user root Jun 27 20:57:07 rivoplus volumio[3423]: info: RAAT: Requesting Headphone Status Jun 27 20:57:07 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 27 20:57:07 rivoplus volumio[3423]: info: MCU Signalled Headphone Mode Disabled Jun 27 20:57:07 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 27 20:57:07 rivoplus volumio[3423]: info: Reporting Headphone State: false Jun 27 20:57:07 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:07 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 20:57:07 rivoplus volumio[3423]: info: Updating RAAT Signal Path Jun 27 20:57:08 rivoplus volumio[3423]: info: Executing endpoint metavolumio Jun 27 20:57:08 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 27 20:57:08 rivoplus volumio[3423]: info: TidalConnect service started! Jun 27 20:57:09 rivoplus volumio[3423]: info: TidalConnect service started! Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:57:20 rivoplus volumio[3423]: info: Retrieving Cloud Streaming UI Jun 27 20:57:20 rivoplus volumio[3423]: info: Getting Tidal Cloud Configuration Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:57:20 rivoplus volumio[3423]: info: Getting Qobuz Cloud Configuration Jun 27 20:57:20 rivoplus volumio[3423]: info: Asking plugin for UI Config Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:57:20 rivoplus volumio[3423]: info: Getting Spotify Cloud Configuration Jun 27 20:57:20 rivoplus volumio[3423]: info: Asking plugin for UI Config Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:57:20 rivoplus volumio[3423]: info: Saving Spotify Acccount Jun 27 20:57:20 rivoplus volumio[3423]: info: Got it Jun 27 20:57:20 rivoplus volumio[3423]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 27 20:57:20 rivoplus volumio[3423]: info: Got Tidal Cloud Configuration Jun 27 20:57:20 rivoplus volumio[3423]: info: Got it Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetBrowseSources Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetBrowseSources Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::volumioGetBrowseSources Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 20:57:20 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 27 20:57:24 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 20:57:28 rivoplus volumio[3423]: info: Disabling MyMusic plugin upnp Jun 27 20:57:28 rivoplus sudo[5675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jun 27 20:57:28 rivoplus sudo[5675]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 20:57:28 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... Jun 27 20:57:28 rivoplus volumio[3423]: error: Upnp client error: Error: This socket has been ended by the other party Jun 27 20:57:28 rivoplus volumio[3423]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 27 20:57:28 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 27 20:57:29 rivoplus go-librespot[4142]: time="2025-06-27T20:57:29+03:00" level=trace msg="sent dealer ping" Jun 27 20:57:29 rivoplus go-librespot[4142]: time="2025-06-27T20:57:29+03:00" level=trace msg="received dealer pong" Jun 27 20:57:30 rivoplus volumio[3423]: info: Enabling MyMusic plugin upnp Jun 27 20:57:30 rivoplus volumio[3423]: info: Enabling plugin upnp Jun 27 20:57:30 rivoplus volumio[3423]: info: Loading plugin "upnp"... Jun 27 20:57:30 rivoplus volumio[3423]: info: [1751047050182] Starting Upmpd Daemon Jun 27 20:57:30 rivoplus volumio[3423]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 20:57:30 rivoplus volumio[3423]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 20:57:30 rivoplus volumio[3423]: Error: listen EADDRINUSE: address already in use :::6599 Jun 27 20:57:30 rivoplus volumio[3423]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Jun 27 20:57:30 rivoplus volumio[3423]: at listenInCluster (net.js:1379:12) Jun 27 20:57:30 rivoplus volumio[3423]: at Server.listen (net.js:1465:7) Jun 27 20:57:30 rivoplus volumio[3423]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jun 27 20:57:30 rivoplus volumio[3423]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jun 27 20:57:30 rivoplus volumio[3423]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jun 27 20:57:30 rivoplus volumio[3423]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 27 20:57:30 rivoplus volumio[3423]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Jun 27 20:57:30 rivoplus volumio[3423]: code: 'EADDRINUSE', Jun 27 20:57:30 rivoplus volumio[3423]: errno: -98, Jun 27 20:57:30 rivoplus volumio[3423]: syscall: 'listen', Jun 27 20:57:30 rivoplus volumio[3423]: address: '::', Jun 27 20:57:30 rivoplus volumio[3423]: port: 6599 Jun 27 20:57:30 rivoplus volumio[3423]: } Jun 27 20:57:30 rivoplus volumio[3423]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 20:57:30 rivoplus sudo[5703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-27 20:56 Jun 27 20:57:30 rivoplus sudo[5703]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:41:30 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="e2256d79dd6d0ae59eb47f10e57a5e95"