-- Logs begin at Mon 2026-04-13 18:39:56 BST, end at Tue 2026-04-14 14:21:45 BST. -- Apr 14 14:20:00 rivoplus volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 14:20:00 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 14:20:00 rivoplus sudo[14053]: pam_unix(sudo:session): session closed for user root Apr 14 14:20:00 rivoplus systemd[1]: shairport-sync.service: Succeeded. Apr 14 14:20:00 rivoplus volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 14:20:00 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 14:20:00 rivoplus volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 14 14:20:00 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 14:20:00 rivoplus sudo[14057]: pam_unix(sudo:session): session closed for user root Apr 14 14:20:00 rivoplus sudo[14065]: pam_unix(sudo:session): session closed for user root Apr 14 14:20:00 rivoplus volumio[3185]: info: Shairport-Sync Started Apr 14 14:20:00 rivoplus volumio[3185]: info: Shairport-Sync Started Apr 14 14:20:00 rivoplus volumio[3185]: info: Executing endpoint tc_getconfig Apr 14 14:20:00 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 14 14:20:00 rivoplus vtcs[14047]: STARTING TidalConnect services, version: 1.6.1 Apr 14 14:20:00 rivoplus vtcs[14047]: STARTED TidalConnect services. Apr 14 14:20:00 rivoplus sudo[14068]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 14:20:00 rivoplus sudo[14068]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 14:20:00 rivoplus volumio[3185]: info: Executing endpoint tc_connect Apr 14 14:20:00 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 14 14:20:00 rivoplus volumio[3185]: info: Connecting to TidalConnect Apr 14 14:20:00 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:20:00 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:00 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:00 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:00 rivoplus sudo[14068]: pam_unix(sudo:session): session closed for user root Apr 14 14:20:00 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:00 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:00 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:00 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:00 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:00 rivoplus volumio[3185]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Apr 14 14:20:00 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:20:00 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:00 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:00 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:00 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:00 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:00 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:00 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:00 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:00 rivoplus volumio[3185]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Apr 14 14:20:00 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:00.253+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=0 volume=100 Apr 14 14:20:00 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:00.254+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id="http://lsn.lv/bbcradio.m3u8?station=bbc_radio_fourfm&bitrate=320000" title="bbc 4" Apr 14 14:20:00 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:00.256+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=0 volume=100 Apr 14 14:20:00 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:00.257+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id="http://lsn.lv/bbcradio.m3u8?station=bbc_radio_fourfm&bitrate=320000" title="bbc 4" Apr 14 14:20:00 rivoplus volumio[3185]: info: TidalConnect service stoped! Apr 14 14:20:00 rivoplus volumio[3185]: info: TidalConnect service stoped! Apr 14 14:20:00 rivoplus volumio[3185]: info: TidalConnect service stoped! Apr 14 14:20:01 rivoplus volumio[3185]: info: TidalConnect service stoped! Apr 14 14:20:01 rivoplus volumio[3185]: info: TidalConnect service stoped! Apr 14 14:20:01 rivoplus volumio[3185]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 14 14:20:01 rivoplus volumio[3185]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 14 14:20:01 rivoplus sudo[14115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 14 14:20:01 rivoplus sudo[14115]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 14:20:01 rivoplus volumio[3185]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 14 14:20:01 rivoplus volumio[3185]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 14 14:20:01 rivoplus sudo[14115]: pam_unix(sudo:session): session closed for user root Apr 14 14:20:01 rivoplus volumio[3185]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 14 14:20:01 rivoplus volumio[3185]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 14 14:20:01 rivoplus sudo[14123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 14 14:20:01 rivoplus sudo[14123]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 14:20:01 rivoplus sudo[14126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 14 14:20:01 rivoplus sudo[14126]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 14:20:01 rivoplus sudo[14123]: pam_unix(sudo:session): session closed for user root Apr 14 14:20:01 rivoplus sudo[14126]: pam_unix(sudo:session): session closed for user root Apr 14 14:20:01 rivoplus volumio[3185]: info: Executing endpoint restartRAATSocket Apr 14 14:20:01 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 14 14:20:01 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:01 rivoplus volumio[3185]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 14 14:20:02 rivoplus mpd[14090]: Apr 14 14:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 14:20:02 rivoplus systemd[1]: Started Music Player Daemon. Apr 14 14:20:02 rivoplus sudo[13899]: pam_unix(sudo:session): session closed for user root Apr 14 14:20:02 rivoplus sudo[13870]: pam_unix(sudo:session): session closed for user root Apr 14 14:20:02 rivoplus sudo[14045]: pam_unix(sudo:session): session closed for user root Apr 14 14:20:02 rivoplus sudo[13725]: pam_unix(sudo:session): session closed for user root Apr 14 14:20:02 rivoplus volumio[3185]: error: MPD error: The expression evaluated to a falsy value: Apr 14 14:20:02 rivoplus volumio[3185]: assert.ok(self.idling) Apr 14 14:20:02 rivoplus volumio[3185]: error: The expression evaluated to a falsy value: Apr 14 14:20:02 rivoplus volumio[3185]: assert.ok(self.idling) Apr 14 14:20:02 rivoplus volumio[3185]: error: MPD error: The expression evaluated to a falsy value: Apr 14 14:20:02 rivoplus volumio[3185]: assert.ok(self.idling) Apr 14 14:20:02 rivoplus volumio[3185]: error: The expression evaluated to a falsy value: Apr 14 14:20:02 rivoplus volumio[3185]: assert.ok(self.idling) Apr 14 14:20:02 rivoplus volumio[3185]: error: MPD error: The expression evaluated to a falsy value: Apr 14 14:20:02 rivoplus volumio[3185]: assert.ok(self.idling) Apr 14 14:20:02 rivoplus volumio[3185]: error: The expression evaluated to a falsy value: Apr 14 14:20:02 rivoplus volumio[3185]: assert.ok(self.idling) Apr 14 14:20:02 rivoplus volumio[3185]: info: RAAT: Requesting Headphone Status Apr 14 14:20:02 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 14 14:20:02 rivoplus volumio[3185]: error: updateQueue error: null Apr 14 14:20:02 rivoplus volumio[3185]: info: MCU Signalled Headphone Mode Disabled Apr 14 14:20:02 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 14 14:20:02 rivoplus volumio[3185]: info: Reporting Headphone State: false Apr 14 14:20:02 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 14:20:02 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:20:02 rivoplus volumio[3185]: info: RAAT: Requesting Headphone Status Apr 14 14:20:02 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 14 14:20:02 rivoplus volumio[3185]: info: MCU Signalled Headphone Mode Disabled Apr 14 14:20:02 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 14 14:20:02 rivoplus volumio[3185]: info: Reporting Headphone State: false Apr 14 14:20:02 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 14:20:02 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:20:02 rivoplus volumio[3185]: info: RAAT: Requesting Headphone Status Apr 14 14:20:02 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 14 14:20:02 rivoplus volumio[3185]: info: MCU Signalled Headphone Mode Disabled Apr 14 14:20:02 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 14 14:20:02 rivoplus volumio[3185]: info: Reporting Headphone State: false Apr 14 14:20:02 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 14:20:02 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:20:02 rivoplus volumio[3185]: info: TidalConnect service started! Apr 14 14:20:03 rivoplus volumio[3185]: info: TidalConnect service started! Apr 14 14:20:04 rivoplus volumio[3185]: info: TidalConnect service started! Apr 14 14:20:04 rivoplus volumio[3185]: info: TidalConnect service started! Apr 14 14:20:04 rivoplus volumio[3185]: info: TidalConnect service started! Apr 14 14:20:18 rivoplus qobuz-connect[13968]: 20260414 14:20:18.326 [13968.14174] INFO SampleApp: API endpoint invoked: get-display-info Apr 14 14:20:18 rivoplus qobuz-connect[13968]: 20260414 14:20:18.360 [13968.14174] INFO SampleApp: API endpoint invoked: get-connect-info Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.701 [13968.14174] INFO SampleApp: API endpoint invoked: connect-to-qconnect Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.702 [13968.13968] INFO EndpointManager: [0xab20aff8]: Updating API endpoint Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.702 [13968.13968] INFO EndpointManager: [0xab20aff8]: Updating QConnect endpoint Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.702 [13968.13968] INFO ActiveStateManager: [0xab20a090]: Setting new active state: active Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.702 [13968.13968] INFO PlaybackSessionManager: [0xab20b310]: Starting playback session maintenance Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.702 [13968.13968] INFO HttpDownloader: [0xab20b4b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.702 [13968.13968] INFO CloudClient: [0xab20b9e0]: Connecting to the cloud Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.703 [13968.13968] INFO SampleApp: Renderer is now active Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.925 [13968.13968] INFO CloudClient: [0xab20b9e0]: Connection established Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.925 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Authenticate message Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.925 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Subscribe message Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.925 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending JoinSession message Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.925 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending VolumeChanged message Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.925 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending VolumeMuted message Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.925 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending MaxAudioQualityChanged message Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.926 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.996 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Received SetActive message: active Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.996 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Received SetState message: Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.996 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Playing state: Playing Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.996 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Playback position: 70 Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.996 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Queue version: 6.1 Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.996 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Current track: TID: 7920031, QID: 6, Context UUID: 597cda4e-9915-435b-9657-8d5acee0668d Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.996 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Next track: TID: 4294967295, QID: -1, Context UUID: Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.996 [13968.13968] INFO MediaEngine: [0xab20b518]: Stopping playback, clearing tracks Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.996 [13968.13968] INFO MediaEngine: [0xab20b518]: Initiating playback Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.996 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Received SetLoopMode message: Off Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.996 [13968.13968] INFO PlaybackModeManager: [0xab20b7e0]: Setting new loop mode: Off Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.997 [13968.13968] INFO PlaybackSessionManager: [0xab20b310]: Playback session has been refreshed Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.997 [13968.13968] INFO MediaEngine: [0xab20b518]: Setting current track: 7920031, initial offset: 70ms Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.997 [13968.13968] INFO AudioStreamManager: [0xab20b5c8]: Clearing all streams Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.997 [13968.13968] INFO AudioStreamManager: [0xab20b5c8]: New stream: 1 Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.997 [13968.13968] INFO HttpDownloader: [0xab217430]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b19f72a29ee3fc0b7f7d4500aa095ac8&request_ts=1776172820&track_id=7920031 Apr 14 14:20:20 rivoplus qobuz-connect[13968]: 20260414 14:20:20.997 [13968.13968] INFO HttpDownloader: [0xab2e64f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=7920031 Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:20.997 [13968.13968] INFO HttpDownloader: [0xab211650]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=8b819d36edfe2c79fc51b6608608afaa&request_ts=1776172820&track_id=7920031 Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:20.997 [13968.13968] INFO AudioStreamManager: [0xab20b5c8]: [Stream 1]: Running audio stream Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:20.998 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Received SetShuffleMode message: disabled Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:20.998 [13968.13968] INFO PlaybackModeManager: [0xab20b7e0]: Setting new shuffle mode: disabled Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:20.999 [13968.13968] INFO MediaEngine: [0xab20b518]: Setting next track: NONE Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:20.999 [13968.13968] INFO MediaEngine: [0xab20b518]: Waiting for current stream to start before starting audio renderer Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.078 [13968.13968] INFO AudioStreamManager: [0xab20b5c8]: [Stream 1]: Metadata became available: Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.078 [13968.13968] INFO AudioStreamManager: [0xab20b5c8]: Title: Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.078 [13968.13968] INFO AudioStreamManager: [0xab20b5c8]: Artist: Quatuor Elyséen Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.078 [13968.13968] INFO AudioStreamManager: [0xab20b5c8]: Album: Chausson, De Castillion : Quatuors pour piano, violon, alto & violoncelle Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.078 [13968.13968] INFO AudioStreamManager: [0xab20b5c8]: Album art URL: https://static.qobuz.com/images/covers/84/16/3325480681684_600.jpg Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.106 [13968.13968] INFO AudioStreamManager: [0xab20b5c8]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=11174244&eid=7920031&fmt=6&profile=raw&app_id=174516466&cid=4126440&etsp=1776176421&hmac=0QlYuKyZOmz-llC6msPMK-JrUGA Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.273 [13968.13968] INFO AudioStreamManager: [0xab20b5c8]: [Stream 1]: stream information have been fetched Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.273 [13968.13968] INFO UrlAudioSource: [0xab2ebab8]: Starting URL audio source, initial position: 70ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=11174244&eid=7920031&fmt=6&profile=raw&app_id=174516466&cid=4126440&etsp=1776176421&hmac=0QlYuKyZOmz-llC6msPMK-JrUGA Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.274 [13968.13968] INFO ContentFetcher: [0xab2ebbe0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=11174244&eid=7920031&fmt=6&profile=raw&app_id=174516466&cid=4126440&etsp=1776176421&hmac=0QlYuKyZOmz-llC6msPMK-JrUGA, offset: 0 Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.274 [13968.13968] INFO AudioRenderer: [0xab20b680]: Starting audio renderer, initial playback state: Playing Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.274 [13968.13968] INFO SampleApp: [Stream 1]: New audio stream (starting from 70ms) Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.274 [13968.13968] INFO SampleApp: [Stream 1]: Stream metadata became available: Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.274 [13968.13968] INFO SampleApp: Title: Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.274 [13968.13968] INFO SampleApp: Artist: Quatuor Elyséen Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.274 [13968.13968] INFO SampleApp: Album: Chausson, De Castillion : Quatuors pour piano, violon, alto & violoncelle Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.274 [13968.13968] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/84/16/3325480681684_600.jpg Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:21 rivoplus volumio[3185]: info: QobuzConnect: setActiveState() Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:21 rivoplus volumio[3185]: info: QobuzConnect: Set Qobuz connect default volume to 100 Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.278 [13968.13968] INFO VolumeManager: [0xab20b870]: Setting new playback volume: 100 Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:21 rivoplus volumio[3185]: info: QobuzConnect: Stopping currently active service Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioStop Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::stop Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::stPlaybackTimer Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::updateTrackBlock Apr 14 14:20:21 rivoplus volumio[3185]: info: CorePlayQueue::getTrackBlock Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::serviceStop Apr 14 14:20:21 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::serviceStop Apr 14 14:20:21 rivoplus volumio[3185]: info: [1776172821290] ControllerWebradio::stop Apr 14 14:20:21 rivoplus volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.292+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_STOPPED positionMs=0 volume=100 Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.292+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id="http://lsn.lv/bbcradio.m3u8?station=bbc_radio_fourfm&bitrate=320000" title="bbc 4" Apr 14 14:20:21 rivoplus volumio[3185]: info: sendMpdCommand stop took 31 milliseconds Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:21 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:21 rivoplus volumio[3185]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received qobuzconnect Apr 14 14:20:21 rivoplus volumio[3185]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.336+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_STOPPED positionMs=260012 volume=100 Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.336+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:20:21 rivoplus volumio[3185]: info: QobuzConnect: setActiveState() completed, send playback confirmation Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.418 [13968.13968] INFO SampleApp: Initiating playback, initial state: Playing Apr 14 14:20:21 rivoplus volumio[3185]: info: MCU Signalled Playback Inactive Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.544 [13968.13968] INFO SampleApp: Playback volume changed: 100 Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.544 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Volume changed: 100 Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.544 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending VolumeChanged message Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.544 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.544 [13968.13968] INFO SampleApp: Playback state changed: Playing Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.544 [13968.13968] INFO StreamingEventReporter: [0xab20bea0]: Reporting streaming started event Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.544 [13968.13968] INFO HttpDownloader: [0xab20bef8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.544 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Need to send state update Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.544 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending StateUpdated message Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.546 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.546 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending FileAudioQualityChanged message Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.546 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.546 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending DeviceAudioQualityChanged message Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.546 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:21 rivoplus volumio[3185]: info: CorePlayQueue::getTrack 0 Apr 14 14:20:21 rivoplus volumio[3185]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received qobuzconnect Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.582+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_STOPPED positionMs=70 volume=100 Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.583+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: QobuzConnect: Volume received from daemon: 100 Apr 14 14:20:21 rivoplus volumio[3185]: info: VolumeController::SetAlsaVolume100 Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.629 [13968.13968] INFO UrlAudioSource: [0xab2ebab8]: Content info extracted Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.629 [13968.13968] INFO UrlAudioSource: [0xab2ebab8]: Size: 56567547 Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.650 [13968.14181] INFO AudioDecoder: [0xab478788]: Stream info: Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.651 [13968.14181] INFO AudioDecoder: [0xab478788]: Total samples: 33904596 Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.651 [13968.14181] INFO AudioDecoder: [0xab478788]: Sample rate: 44100 Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.652 [13968.14181] INFO AudioDecoder: [0xab478788]: Channel count: 2 Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.655 [13968.14181] INFO AudioDecoder: [0xab478788]: Bits per sample: 16 Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.655 [13968.14181] INFO AudioDecoder: [0xab478788]: Duration: 768811 Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.656 [13968.13968] INFO UrlAudioSource: [0xab2ebab8]: Audio properties have changed Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.656 [13968.13968] INFO UrlAudioSource: [0xab2ebab8]: Seeking input stream to byte offset: 1432 Apr 14 14:20:21 rivoplus qobuz-connect[13968]: 20260414 14:20:21.656 [13968.13968] INFO ContentFetcher: [0xab2ebbe0]: Adjusting download offset to: 1432 Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.689+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=70 volume=100 Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.689+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.695+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=70 volume=100 Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.696+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.698+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=70 volume=100 Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.699+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.727+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=70 volume=100 Apr 14 14:20:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:21.728+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:20:21 rivoplus volumio[3185]: info: Signalling Playback active due to playback status change Apr 14 14:20:21 rivoplus volumio[3185]: info: Signalling Playback active due to playback status change Apr 14 14:20:21 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:20:21 rivoplus volumio[3185]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Apr 14 14:20:21 rivoplus volumio[3185]: xcb_connection_has_error() returned true Apr 14 14:20:21 rivoplus volumio[3185]: info: MCU Signalled Playback Active Apr 14 14:20:23 rivoplus volumio[3185]: info: Executing endpoint metavolumio Apr 14 14:20:23 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 14:20:23 rivoplus volumio[3185]: info: Executing endpoint metavolumio Apr 14 14:20:23 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 14:20:23 rivoplus volumio[3185]: info: Executing endpoint metavolumio Apr 14 14:20:23 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 14 14:20:30 rivoplus volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 14:20:31 rivoplus volumio[3185]: No protocol specified Apr 14 14:20:31 rivoplus volumio[3185]: xcb_connection_has_error() returned true Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 14:20:31 rivoplus volumio[3185]: No protocol specified Apr 14 14:20:31 rivoplus volumio[3185]: xcb_connection_has_error() returned true Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 14:20:31 rivoplus qobuz-connect[13968]: 20260414 14:20:31.544 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Need to send state update Apr 14 14:20:31 rivoplus qobuz-connect[13968]: 20260414 14:20:31.544 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending StateUpdated message Apr 14 14:20:31 rivoplus qobuz-connect[13968]: 20260414 14:20:31.545 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:31 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:31 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:31 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:31 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:31.567+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=7662 volume=100 Apr 14 14:20:31 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:31.567+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:20:31 rivoplus volumio[3185]: info: Signalling Playback active due to playback status change Apr 14 14:20:31 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:20:41 rivoplus qobuz-connect[13968]: 20260414 14:20:41.545 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Need to send state update Apr 14 14:20:41 rivoplus qobuz-connect[13968]: 20260414 14:20:41.545 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending StateUpdated message Apr 14 14:20:41 rivoplus qobuz-connect[13968]: 20260414 14:20:41.546 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:20:41 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:41 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:20:41 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:41 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:20:41 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:41 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:41 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:41 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:41 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:41.561+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=17663 volume=100 Apr 14 14:20:41 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:41.562+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:20:41 rivoplus volumio[3185]: info: Signalling Playback active due to playback status change Apr 14 14:20:41 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:20:48 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 14:20:48 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 14:20:51 rivoplus qobuz-connect[13968]: 20260414 14:20:51.546 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Need to send state update Apr 14 14:20:51 rivoplus qobuz-connect[13968]: 20260414 14:20:51.546 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending StateUpdated message Apr 14 14:20:51 rivoplus qobuz-connect[13968]: 20260414 14:20:51.546 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:20:51 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:51 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:20:51 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:20:51 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:20:51 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:20:51 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:20:51 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:20:51 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:20:51 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:51.564+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=27663 volume=100 Apr 14 14:20:51 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:20:51.564+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:20:51 rivoplus volumio[3185]: info: Signalling Playback active due to playback status change Apr 14 14:20:51 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:21:01 rivoplus nmbd[3066]: [2026/04/14 14:21:01.068930, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 14 14:21:01 rivoplus nmbd[3066]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.123 for name WORKGROUP<1d>. Apr 14 14:21:01 rivoplus nmbd[3066]: This response was from IP 192.168.0.8, reporting an IP address of 192.168.0.8. Apr 14 14:21:01 rivoplus qobuz-connect[13968]: 20260414 14:21:01.547 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Need to send state update Apr 14 14:21:01 rivoplus qobuz-connect[13968]: 20260414 14:21:01.547 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending StateUpdated message Apr 14 14:21:01 rivoplus qobuz-connect[13968]: 20260414 14:21:01.547 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:21:01 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:21:01 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:21:01 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:21:01 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:21:01 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:21:01 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:21:01 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:21:01 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:21:01 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:21:01.566+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=37664 volume=100 Apr 14 14:21:01 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:21:01.566+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:21:01 rivoplus volumio[3185]: info: Signalling Playback active due to playback status change Apr 14 14:21:01 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:21:06 rivoplus nmbd[3066]: [2026/04/14 14:21:06.081864, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 14 14:21:06 rivoplus nmbd[3066]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Apr 14 14:21:11 rivoplus qobuz-connect[13968]: 20260414 14:21:11.547 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Need to send state update Apr 14 14:21:11 rivoplus qobuz-connect[13968]: 20260414 14:21:11.547 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending StateUpdated message Apr 14 14:21:11 rivoplus qobuz-connect[13968]: 20260414 14:21:11.547 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:21:11 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:21:11 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:21:11 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:21:11 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:21:11 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:21:11 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:21:11 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:21:11 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:21:11 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:21:11.561+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=47663 volume=100 Apr 14 14:21:11 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:21:11.562+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:21:11 rivoplus volumio[3185]: info: Signalling Playback active due to playback status change Apr 14 14:21:11 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:21:12 rivoplus volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 14:21:12 rivoplus volumio[3185]: No protocol specified Apr 14 14:21:12 rivoplus volumio[3185]: xcb_connection_has_error() returned true Apr 14 14:21:12 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 14 14:21:12 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 14:21:12 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 14:21:12 rivoplus volumio[3185]: No protocol specified Apr 14 14:21:12 rivoplus volumio[3185]: xcb_connection_has_error() returned true Apr 14 14:21:12 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:21:12 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 14 14:21:12 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 14:21:12 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Apr 14 14:21:12 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Apr 14 14:21:12 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 14:21:12 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 14:21:21 rivoplus qobuz-connect[13968]: 20260414 14:21:21.547 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Need to send state update Apr 14 14:21:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:21:21 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:21:21 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:21:21 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:21:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:21:21 rivoplus qobuz-connect[13968]: 20260414 14:21:21.552 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending StateUpdated message Apr 14 14:21:21 rivoplus qobuz-connect[13968]: 20260414 14:21:21.552 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:21:21 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:21:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:21:21 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:21:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:21:21.573+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=57664 volume=100 Apr 14 14:21:21 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:21:21.574+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:21:21 rivoplus volumio[3185]: info: Signalling Playback active due to playback status change Apr 14 14:21:21 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:21:22 rivoplus volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 14:21:22 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 14 14:21:22 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 14 14:21:22 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 14 14:21:22 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 14 14:21:22 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Apr 14 14:21:22 rivoplus volumio[3185]: info: Received Get System Version Apr 14 14:21:22 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 14:21:27 rivoplus volumio[3185]: info: Retrieving Cloud Streaming UI Apr 14 14:21:27 rivoplus volumio[3185]: info: Getting Tidal Cloud Configuration Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 14:21:27 rivoplus volumio[3185]: info: Getting Qobuz Cloud Configuration Apr 14 14:21:27 rivoplus volumio[3185]: info: Asking plugin for UI Config Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 14:21:27 rivoplus volumio[3185]: info: Getting Spotify Cloud Configuration Apr 14 14:21:27 rivoplus volumio[3185]: info: Asking plugin for UI Config Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 14:21:27 rivoplus volumio[3185]: info: Saving Spotify Acccount Apr 14 14:21:27 rivoplus volumio[3185]: info: Got it Apr 14 14:21:27 rivoplus volumio[3185]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 14 14:21:27 rivoplus volumio[3185]: info: Got Tidal Cloud Configuration Apr 14 14:21:27 rivoplus volumio[3185]: info: Got it Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 14:21:27 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 14 14:21:30 rivoplus volumio[3185]: info: Disabling MyMusic plugin bluetooth Apr 14 14:21:30 rivoplus sudo[14355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Apr 14 14:21:30 rivoplus sudo[14355]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 14:21:30 rivoplus systemd[1]: Stopping Volumio Bluetooth Module... Apr 14 14:21:30 rivoplus volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 14:21:30 rivoplus volumiobt[14357]: Killing pulse Apr 14 14:21:30 rivoplus volumiobt[14357]: Killing BT Pair agent Apr 14 14:21:30 rivoplus bluetoothd[3382]: Endpoint unregistered: sender=:1.44 path=/MediaEndpoint/A2DPSource Apr 14 14:21:30 rivoplus bluetoothd[3382]: Endpoint unregistered: sender=:1.44 path=/MediaEndpoint/A2DPSink Apr 14 14:21:30 rivoplus systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Apr 14 14:21:30 rivoplus systemd[1]: volumiobt.service: Succeeded. Apr 14 14:21:30 rivoplus systemd[1]: Stopped Volumio Bluetooth Module. Apr 14 14:21:30 rivoplus sudo[14355]: pam_unix(sudo:session): session closed for user root Apr 14 14:21:30 rivoplus volumio[3185]: info: Volumio BT Module successfully stopped Apr 14 14:21:30 rivoplus volumio[3185]: info: Disabling plugin bluetooth Apr 14 14:21:30 rivoplus volumio[3185]: info: Done. Apr 14 14:21:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 14:21:31 rivoplus qobuz-connect[13968]: 20260414 14:21:31.547 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Need to send state update Apr 14 14:21:31 rivoplus qobuz-connect[13968]: 20260414 14:21:31.547 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending StateUpdated message Apr 14 14:21:31 rivoplus qobuz-connect[13968]: 20260414 14:21:31.547 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:21:31 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:21:31 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:21:31 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:21:31 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:21:31 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:21:31 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:21:31 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:21:31 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:21:31 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:21:31.568+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=67663 volume=100 Apr 14 14:21:31 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:21:31.569+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:21:31 rivoplus volumio[3185]: info: Signalling Playback active due to playback status change Apr 14 14:21:31 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:21:35 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 14:21:39 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 14:21:41 rivoplus volumio[3185]: info: Disabling MyMusic plugin upnp Apr 14 14:21:41 rivoplus sudo[14378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 14 14:21:41 rivoplus sudo[14378]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 14:21:41 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... Apr 14 14:21:41 rivoplus volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 14:21:41 rivoplus volumio[3185]: error: Upnp client error: Error: This socket has been ended by the other party Apr 14 14:21:41 rivoplus qobuz-connect[13968]: 20260414 14:21:41.547 [13968.13968] INFO ProtocolHandler: [0xab20baf0]: Need to send state update Apr 14 14:21:41 rivoplus qobuz-connect[13968]: 20260414 14:21:41.547 [13968.13968] INFO QConnectMessageSender: [0xab249020]: Sending StateUpdated message Apr 14 14:21:41 rivoplus qobuz-connect[13968]: 20260414 14:21:41.547 [13968.13968] INFO QwspMessageSender: [0xab315810]: Sending Payload message Apr 14 14:21:41 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:21:41 rivoplus volumio[3185]: info: CoreCommandRouter::servicePushState Apr 14 14:21:41 rivoplus volumio[3185]: info: CoreStateMachine::pushState Apr 14 14:21:41 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 14:21:41 rivoplus volumio[3185]: info: CoreCommandRouter::volumioPushState Apr 14 14:21:41 rivoplus volumio[3185]: info: CoreCommandRouter::volumioGetState Apr 14 14:21:41 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Apr 14 14:21:41 rivoplus volumio[3185]: info: MRS: Pushing multiroomSync output Apr 14 14:21:41 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:21:41.565+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" state=STATUS_PLAYING positionMs=77664 volume=100 Apr 14 14:21:41 rivoplus volumio5-onboarding[3838]: time=2026-04-14T14:21:41.566+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:45392 @ 0x3132810" id= title="Quatuor en sol mineur pour piano, Violon, Alto, Violoncelle, Op. 7 : Larghetto, Quasi marcia religiosa finale" Apr 14 14:21:41 rivoplus volumio[3185]: info: Signalling Playback active due to playback status change Apr 14 14:21:41 rivoplus volumio[3185]: info: Updating RAAT Signal Path Apr 14 14:21:43 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 14 14:21:44 rivoplus volumio[3185]: info: Enabling MyMusic plugin upnp Apr 14 14:21:44 rivoplus volumio[3185]: info: Enabling plugin upnp Apr 14 14:21:44 rivoplus volumio[3185]: info: Loading plugin "upnp"... Apr 14 14:21:44 rivoplus volumio[3185]: info: [1776172904343] Starting Upmpd Daemon Apr 14 14:21:44 rivoplus volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 14:21:44 rivoplus volumio[3185]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 14:21:44 rivoplus volumio[3185]: Error: listen EADDRINUSE: address already in use :::6599 Apr 14 14:21:44 rivoplus volumio[3185]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Apr 14 14:21:44 rivoplus volumio[3185]: at listenInCluster (net.js:1379:12) Apr 14 14:21:44 rivoplus volumio[3185]: at Server.listen (net.js:1465:7) Apr 14 14:21:44 rivoplus volumio[3185]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Apr 14 14:21:44 rivoplus volumio[3185]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Apr 14 14:21:44 rivoplus volumio[3185]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 14 14:21:44 rivoplus volumio[3185]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 14 14:21:44 rivoplus volumio[3185]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Apr 14 14:21:44 rivoplus volumio[3185]: code: 'EADDRINUSE', Apr 14 14:21:44 rivoplus volumio[3185]: errno: -98, Apr 14 14:21:44 rivoplus volumio[3185]: syscall: 'listen', Apr 14 14:21:44 rivoplus volumio[3185]: address: '::', Apr 14 14:21:44 rivoplus volumio[3185]: port: 6599 Apr 14 14:21:44 rivoplus volumio[3185]: } Apr 14 14:21:44 rivoplus volumio[3185]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 14:21:45 rivoplus sudo[14406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-14 14:20 Apr 14 14:21:45 rivoplus sudo[14406]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"