-- Logs begin at Wed 2025-12-24 19:26:19 CET, end at Wed 2025-12-24 20:11:47 CET. -- Dec 24 20:10:00 rivoplus volumio[3205]: info: Selecting previously unselected package libalgorithm-diff-xs-perl. Dec 24 20:10:00 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:00.633Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:00 rivoplus volumio[3205]: info: Preparing to unpack .../22-libalgorithm-diff-xs-perl_0.04-5+b1_armhf.deb ... Dec 24 20:10:00 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:00.691Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:00 rivoplus volumio[3205]: info: Unpacking libalgorithm-diff-xs-perl (0.04-5+b1) ... Dec 24 20:10:00 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:00.713Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:00 rivoplus volumio[3205]: info: Selecting previously unselected package libalgorithm-merge-perl. Dec 24 20:10:00 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:00.909Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:00 rivoplus volumio[3205]: info: Preparing to unpack .../23-libalgorithm-merge-perl_0.08-3_all.deb ... Dec 24 20:10:00 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:00.953Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:00 rivoplus volumio[3205]: info: Unpacking libalgorithm-merge-perl (0.08-3) ... Dec 24 20:10:00 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:00.977Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:01 rivoplus volumio[3205]: info: Selecting previously unselected package libexpat1-dev:armhf. Dec 24 20:10:01 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:01.170Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:01 rivoplus volumio[3205]: info: Preparing to unpack .../24-libexpat1-dev_2.2.6-2+deb10u4_armhf.deb ... Dec 24 20:10:01 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:01.202Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:01 rivoplus volumio[3205]: info: Unpacking libexpat1-dev:armhf (2.2.6-2+deb10u4) ... Dec 24 20:10:01 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:01.229Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:01 rivoplus volumio[3205]: info: Selecting previously unselected package libpython3.7-dev:armhf. Dec 24 20:10:01 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:01.488Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:01 rivoplus volumio[3205]: info: Preparing to unpack .../25-libpython3.7-dev_3.7.3-2+deb10u3_armhf.deb ... Dec 24 20:10:01 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:01.516Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:01 rivoplus volumio[3205]: info: Unpacking libpython3.7-dev:armhf (3.7.3-2+deb10u3) ... Dec 24 20:10:01 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:01.541Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:01 rivoplus qobuz-connect[6511]: 20251224 20:10:01.674 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:10:01 rivoplus qobuz-connect[6511]: 20251224 20:10:01.674 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:10:01 rivoplus qobuz-connect[6511]: 20251224 20:10:01.674 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:10:01 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:01 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:01 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:01 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:01 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:01 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:01 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:01 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:01 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:10:01 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:11 rivoplus qobuz-connect[6511]: 20251224 20:10:11.675 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:10:11 rivoplus qobuz-connect[6511]: 20251224 20:10:11.675 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:10:11 rivoplus qobuz-connect[6511]: 20251224 20:10:11.675 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:10:11 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:11 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:11 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:11 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:11 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:11 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:11 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:11 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:11 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:10:11 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:12 rivoplus volumio[3205]: info: Selecting previously unselected package libpython3-dev:armhf. Dec 24 20:10:12 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:12.279Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:12 rivoplus volumio[3205]: info: Preparing to unpack .../26-libpython3-dev_3.7.3-1_armhf.deb ... Dec 24 20:10:12 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:12.312Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:12 rivoplus volumio[3205]: info: Unpacking libpython3-dev:armhf (3.7.3-1) ... Dec 24 20:10:12 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:12.330Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:12 rivoplus volumio[3205]: info: Selecting previously unselected package python-pip-whl. Dec 24 20:10:12 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:12.517Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:12 rivoplus volumio[3205]: info: Preparing to unpack .../27-python-pip-whl_18.1-5_all.deb ... Dec 24 20:10:12 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:12.552Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:12 rivoplus volumio[3205]: info: Unpacking python-pip-whl (18.1-5) ... Dec 24 20:10:12 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:12.579Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:13 rivoplus volumio[3205]: info: Selecting previously unselected package python3-multidict. Dec 24 20:10:13 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:13.081Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:13 rivoplus volumio[3205]: info: Preparing to unpack .../28-python3-multidict_4.5.2-1_armhf.deb ... Dec 24 20:10:13 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:13.104Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:13 rivoplus volumio[3205]: info: Unpacking python3-multidict (4.5.2-1) ... Dec 24 20:10:13 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:13.137Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:13 rivoplus volumio[3205]: info: Selecting previously unselected package python3-yarl. Dec 24 20:10:13 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:13.380Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:13 rivoplus volumio[3205]: info: Preparing to unpack .../29-python3-yarl_1.3.0-1_armhf.deb ... Dec 24 20:10:13 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:13.405Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:13 rivoplus volumio[3205]: info: Unpacking python3-yarl (1.3.0-1) ... Dec 24 20:10:13 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:13.426Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:13 rivoplus volumio[3205]: info: Selecting previously unselected package python3-async-timeout. Dec 24 20:10:13 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:13.626Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:13 rivoplus volumio[3205]: info: Preparing to unpack .../30-python3-async-timeout_3.0.1-1_all.deb ... Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.653 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Received SetState message: Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.653 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Playing state: Playing Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.653 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Playback position: 0 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.653 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Queue version: 36.1 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.653 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Current track: TID: 79758100, QID: 0, Context UUID: 78613f95-42f6-4429-a542-57dcbaaa0065 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.653 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Next track: TID: 79758101, QID: 1, Context UUID: 78613f95-42f6-4429-a542-57dcbaaa0065 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.653 [6511.6511] INFO MediaEngine: [0xab43e558]: Stopping playback, clearing tracks Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.653 [6511.6511] INFO MediaEngine: [0xab43e558]: Initiating playback Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.653 [6511.6511] INFO AudioRenderer: [0xab43e778]: Stopping audio renderer Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.653 [6511.6511] INFO SampleApp: Stopping playback Dec 24 20:10:13 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:13.660Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.668 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Clearing all streams Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.668 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 52]: Disposing of audio stream Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.668 [6511.6511] INFO ContentFetcher: [0xab517198]: Canceling download Dec 24 20:10:13 rivoplus volumio[3205]: info: Unpacking python3-async-timeout (3.0.1-1) ... Dec 24 20:10:13 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:13.685Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.734 [6511.6511] INFO SampleApp: Playback state changed: Stopped Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.734 [6511.6511] INFO StreamingEventReporter: [0xab43f0b0]: Reporting streaming ended event Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.735 [6511.6511] INFO HttpDownloader: [0xab43f108]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.735 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.735 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.735 [6511.6511] INFO SampleApp: [Stream 52]: Audio stream has been disposed of Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.735 [6511.6511] INFO MediaEngine: [0xab43e558]: Setting current track: 79758100, initial offset: 0ms Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.735 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Clearing all streams Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.735 [6511.6511] INFO AudioStreamManager: [0xab43e640]: New stream: 54 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.735 [6511.6511] INFO HttpDownloader: [0xab6b27f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=93fc755ea58ca7f0368954fdc6a85caf&request_ts=1766603413&track_id=79758100 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.735 [6511.6511] INFO HttpDownloader: [0xab47fb98]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=79758100 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.736 [6511.6511] INFO HttpDownloader: [0xab479578]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b54a6aa80de465a899b2bd7375985176&request_ts=1766603413&track_id=79758100 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.736 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 54]: Running audio stream Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.736 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:13 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:13 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.754 [6511.6511] INFO MediaEngine: [0xab43e558]: Setting next track: 79758101 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.755 [6511.6511] INFO AudioStreamManager: [0xab43e640]: New stream: 55 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.756 [6511.6511] INFO HttpDownloader: [0xab5567e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=0d71bc2085e2334f04a07136fb3ff8d4&request_ts=1766603413&track_id=79758101 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.757 [6511.6511] INFO HttpDownloader: [0xab55eac0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=79758101 Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.757 [6511.6511] INFO HttpDownloader: [0xab5815f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=6d2c4373c52d4f5fe01897165a42c79c&request_ts=1766603413&track_id=79758101 Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:13 rivoplus qobuz-connect[6511]: 20251224 20:10:13.775 [6511.6511] INFO MediaEngine: [0xab43e558]: Waiting for current stream to start before starting audio renderer Dec 24 20:10:13 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:13 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:13 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:13 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:13 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:13 rivoplus volumio[3205]: info: MCU Signalled Playback Inactive Dec 24 20:10:13 rivoplus volumio[3205]: info: Selecting previously unselected package python3-attr. Dec 24 20:10:13 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:13.934Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:13 rivoplus volumio[3205]: info: Preparing to unpack .../31-python3-attr_18.2.0-1_all.deb ... Dec 24 20:10:13 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:13.976Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:14 rivoplus volumio[3205]: info: Unpacking python3-attr (18.2.0-1) ... Dec 24 20:10:14 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:14.029Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:14 rivoplus volumio[3205]: info: Selecting previously unselected package python3-aiohttp. Dec 24 20:10:14 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:14.277Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:14 rivoplus volumio[3205]: info: Preparing to unpack .../32-python3-aiohttp_3.5.1-1+deb10u1_armhf.deb ... Dec 24 20:10:14 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:14.298Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:14 rivoplus volumio[3205]: info: Unpacking python3-aiohttp (3.5.1-1+deb10u1) ... Dec 24 20:10:14 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:14.322Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.676 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Received SetState message: Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.676 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Playing state: Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.676 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Playback position: Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.676 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Queue version: 36.2 Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.677 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Current track: Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.677 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Next track: TID: 79758101, QID: 1, Context UUID: 78613f95-42f6-4429-a542-57dcbaaa0065 Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.724 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 55]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758101&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607014&hmac=861E4ziTxaBzVhsXVLXZirCtTPU Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.743 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 54]: stream information have been fetched Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.753 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 54]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758100&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607014&hmac=gmJg7MuvK_YC9jNpzj5lrW_8Lg8 Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.753 [6511.6511] INFO UrlAudioSource: [0xab91fa78]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758100&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607014&hmac=gmJg7MuvK_YC9jNpzj5lrW_8Lg8 Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.753 [6511.6511] INFO ContentFetcher: [0xab490008]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758100&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607014&hmac=gmJg7MuvK_YC9jNpzj5lrW_8Lg8, offset: 0 Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.755 [6511.6511] INFO AudioRenderer: [0xab43e778]: Starting audio renderer, initial playback state: Playing Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.755 [6511.6511] INFO SampleApp: [Stream 54]: New audio stream (starting from 0ms) Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:14 rivoplus volumio[3205]: info: QobuzConnect: alread currrent service, send playback confirmation Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.757 [6511.6511] INFO SampleApp: Initiating playback, initial state: Playing Dec 24 20:10:14 rivoplus volumio[3205]: info: Selecting previously unselected package python3-asn1crypto. Dec 24 20:10:14 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:14.769Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:14 rivoplus volumio[3205]: info: Preparing to unpack .../33-python3-asn1crypto_0.24.0-1_all.deb ... Dec 24 20:10:14 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:14.800Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.802 [6511.6511] INFO SampleApp: Playback state changed: Playing Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.802 [6511.6511] INFO StreamingEventReporter: [0xab43f0b0]: Reporting streaming started event Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.802 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.803 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.803 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.803 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending FileAudioQualityChanged message Dec 24 20:10:14 rivoplus qobuz-connect[6511]: 20251224 20:10:14.803 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:14 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:14 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:14 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:14 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:14 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:14 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:14 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:14 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:14 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:14 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:14 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:14 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:14 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:10:14 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:10:14 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:10:14 rivoplus volumio[3205]: info: Unpacking python3-asn1crypto (0.24.0-1) ... Dec 24 20:10:14 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:14.972Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.015 [6511.6511] INFO HttpDownloader: [0xab43f108]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.017 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 55]: stream information have been fetched Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.074 [6511.6511] INFO UrlAudioSource: [0xab91fa78]: Content info extracted Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.074 [6511.6511] INFO UrlAudioSource: [0xab91fa78]: Size: 129093516 Dec 24 20:10:15 rivoplus volumio[3205]: info: MCU Signalled Playback Active Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.083 [6511.13682] INFO AudioDecoder: [0xab4de468]: Stream info: Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.083 [6511.13682] INFO AudioDecoder: [0xab4de468]: Total samples: 32687350 Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.083 [6511.13682] INFO AudioDecoder: [0xab4de468]: Sample rate: 96000 Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.083 [6511.13682] INFO AudioDecoder: [0xab4de468]: Channel count: 2 Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.084 [6511.13682] INFO AudioDecoder: [0xab4de468]: Bits per sample: 24 Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.084 [6511.13682] INFO AudioDecoder: [0xab4de468]: Duration: 340493 Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.084 [6511.6511] INFO UrlAudioSource: [0xab91fa78]: Audio properties have changed Dec 24 20:10:15 rivoplus volumio[3205]: info: Selecting previously unselected package python3-cffi-backend. Dec 24 20:10:15 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:15.137Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:15 rivoplus volumio[3205]: info: Preparing to unpack .../34-python3-cffi-backend_1.12.2-1_armhf.deb ... Dec 24 20:10:15 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:15.182Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:15 rivoplus volumio[3205]: info: Unpacking python3-cffi-backend (1.12.2-1) ... Dec 24 20:10:15 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:15.201Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:15 rivoplus volumio[3205]: info: Selecting previously unselected package python3-crypto. Dec 24 20:10:15 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:15.381Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:15 rivoplus volumio[3205]: info: Preparing to unpack .../35-python3-crypto_2.6.1-9+b1_armhf.deb ... Dec 24 20:10:15 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:15.404Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:15 rivoplus volumio[3205]: info: Unpacking python3-crypto (2.6.1-9+b1) ... Dec 24 20:10:15 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:15.422Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.435 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 55]: Metadata became available: Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.436 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Title: Sledgehammer (Remaster 2012) Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.436 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Artist: Peter Gabriel Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.436 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album: So (Remaster 2012) Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.436 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album art URL: https://static.qobuz.com/images/covers/9b/b1/kjsz9jmqpb19b_600.jpg Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.522 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 54]: Metadata became available: Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.522 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Title: Red Rain (Remaster 2012) Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.522 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Artist: Peter Gabriel Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.522 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album: So (Remaster 2012) Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.522 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album art URL: https://static.qobuz.com/images/covers/9b/b1/kjsz9jmqpb19b_600.jpg Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.522 [6511.6511] INFO SampleApp: [Stream 54]: Stream metadata became available: Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.522 [6511.6511] INFO SampleApp: Title: Red Rain (Remaster 2012) Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.522 [6511.6511] INFO SampleApp: Artist: Peter Gabriel Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.522 [6511.6511] INFO SampleApp: Album: So (Remaster 2012) Dec 24 20:10:15 rivoplus qobuz-connect[6511]: 20251224 20:10:15.522 [6511.6511] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/9b/b1/kjsz9jmqpb19b_600.jpg Dec 24 20:10:15 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:15 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:15 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:15 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:15 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:15 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:15 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:15 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:15 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:10:15 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:15 rivoplus volumio[3205]: info: Selecting previously unselected package python3-cryptography. Dec 24 20:10:15 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:15.865Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:15 rivoplus volumio[3205]: info: Preparing to unpack .../36-python3-cryptography_2.6.1-3+deb10u2_armhf.deb ... Dec 24 20:10:15 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:15.888Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:15 rivoplus volumio[3205]: info: Unpacking python3-cryptography (2.6.1-3+deb10u2) ... Dec 24 20:10:15 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:15.900Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Selecting previously unselected package python3-dbus. Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.211Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Preparing to unpack .../37-python3-dbus_1.2.8-3_armhf.deb ... Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.228Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Unpacking python3-dbus (1.2.8-3) ... Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.243Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Selecting previously unselected package python3.7-dev. Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.391Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Preparing to unpack .../38-python3.7-dev_3.7.3-2+deb10u3_armhf.deb ... Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.414Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Unpacking python3.7-dev (3.7.3-2+deb10u3) ... Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.425Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Selecting previously unselected package python3-dev. Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.623Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Preparing to unpack .../39-python3-dev_3.7.3-1_armhf.deb ... Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.663Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Unpacking python3-dev (3.7.3-1) ... Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.702Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Selecting previously unselected package python3-entrypoints. Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.824Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Preparing to unpack .../40-python3-entrypoints_0.3-1_all.deb ... Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.867Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:16 rivoplus volumio[3205]: info: Unpacking python3-entrypoints (0.3-1) ... Dec 24 20:10:16 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:16.891Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Selecting previously unselected package python3-gi. Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.011Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Preparing to unpack .../41-python3-gi_3.30.4-1_armhf.deb ... Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.029Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Unpacking python3-gi (3.30.4-1) ... Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.050Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Selecting previously unselected package python3-secretstorage. Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.241Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Preparing to unpack .../42-python3-secretstorage_2.3.1-2_all.deb ... Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.261Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Unpacking python3-secretstorage (2.3.1-2) ... Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.275Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Selecting previously unselected package python3-keyring. Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.397Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Preparing to unpack .../43-python3-keyring_17.1.1-1_all.deb ... Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.413Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Unpacking python3-keyring (17.1.1-1) ... Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.428Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Executing endpoint metavolumio Dec 24 20:10:17 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 24 20:10:17 rivoplus volumio[3205]: info: Executing endpoint metavolumio Dec 24 20:10:17 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 24 20:10:17 rivoplus volumio[3205]: info: Executing endpoint metavolumio Dec 24 20:10:17 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 24 20:10:17 rivoplus volumio[3205]: info: Selecting previously unselected package python3-keyrings.alt. Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.648Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Preparing to unpack .../44-python3-keyrings.alt_3.1.1-1_all.deb ... Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.659Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Unpacking python3-keyrings.alt (3.1.1-1) ... Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.664Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Selecting previously unselected package python3-pip. Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.837Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Preparing to unpack .../45-python3-pip_18.1-5_all.deb ... Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.857Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:17 rivoplus volumio[3205]: info: Unpacking python3-pip (18.1-5) ... Dec 24 20:10:17 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:17.874Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:18 rivoplus volumio[3205]: info: Selecting previously unselected package python3-setuptools. Dec 24 20:10:18 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:18.118Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:18 rivoplus volumio[3205]: info: Preparing to unpack .../46-python3-setuptools_40.8.0-1_all.deb ... Dec 24 20:10:18 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:18.137Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:18 rivoplus volumio[3205]: info: Unpacking python3-setuptools (40.8.0-1) ... Dec 24 20:10:18 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:18.149Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:18 rivoplus volumio[3205]: info: Selecting previously unselected package python3-wheel. Dec 24 20:10:18 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:18.411Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:18 rivoplus volumio[3205]: info: Preparing to unpack .../47-python3-wheel_0.32.3-2_all.deb ... Dec 24 20:10:18 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:18.429Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:18 rivoplus volumio[3205]: info: Unpacking python3-wheel (0.32.3-2) ... Dec 24 20:10:18 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:18.446Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:18 rivoplus volumio[3205]: info: Selecting previously unselected package python3-xdg. Dec 24 20:10:18 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:18.575Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:18 rivoplus volumio[3205]: info: Preparing to unpack .../48-python3-xdg_0.25-5_all.deb ... Dec 24 20:10:18 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:18.594Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:18 rivoplus volumio[3205]: info: Unpacking python3-xdg (0.25-5) ... Dec 24 20:10:18 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:18.608Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:18 rivoplus volumio[3205]: info: Setting up python3-entrypoints (0.3-1) ... Dec 24 20:10:18 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:18.841Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:19 rivoplus volumio[3205]: info: Setting up dh-python (3.20190308) ... Dec 24 20:10:19 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:19.920Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:20 rivoplus volumio[3205]: info: Setting up python3-attr (18.2.0-1) ... Dec 24 20:10:20 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:20.811Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:21 rivoplus volumio[3205]: info: Setting up python3-dbus (1.2.8-3) ... Dec 24 20:10:21 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:21.658Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:22 rivoplus volumio[3205]: info: Setting up python3-setuptools (40.8.0-1) ... Dec 24 20:10:22 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:22.549Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:24 rivoplus volumio[3205]: info: Setting up libalgorithm-diff-perl (1.19.03-2) ... Dec 24 20:10:24 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:24.438Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:24 rivoplus volumio[3205]: info: Setting up binutils-common:armhf (2.31.1-16) ... Dec 24 20:10:24 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:24.477Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:24 rivoplus volumio[3205]: info: Setting up python3-xdg (0.25-5) ... Dec 24 20:10:24 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:24.522Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:24 rivoplus qobuz-connect[6511]: 20251224 20:10:24.808 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:10:24 rivoplus qobuz-connect[6511]: 20251224 20:10:24.808 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:10:24 rivoplus qobuz-connect[6511]: 20251224 20:10:24.808 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:10:24 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:24 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:24 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:24 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:24 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:24 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:24 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:24 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:24 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:10:24 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:25 rivoplus volumio[3205]: info: Setting up bzip2 (1.0.6-9.2~deb10u1) ... Dec 24 20:10:25 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:25.754Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:25 rivoplus volumio[3205]: info: Setting up python3-wheel (0.32.3-2) ... Dec 24 20:10:25 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:25.789Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:26 rivoplus volumio[3205]: info: Setting up python3-multidict (4.5.2-1) ... Dec 24 20:10:26 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:26.849Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:27 rivoplus volumio[3205]: info: Setting up libfakeroot:armhf (1.23-1) ... Dec 24 20:10:27 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:27.863Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:27 rivoplus volumio[3205]: info: Setting up python3-async-timeout (3.0.1-1) ... Dec 24 20:10:27 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:27.910Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:28 rivoplus volumio[3205]: info: Setting up fakeroot (1.23-1) ... Dec 24 20:10:28 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:28.861Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:28 rivoplus volumio[3205]: info: update-alternatives: using /usr/bin/fakeroot-sysv to provide /usr/bin/fakeroot (fakeroot) in auto mode Dec 24 20:10:28 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:28.965Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:28 rivoplus volumio[3205]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/fakeroot.1.gz because associated file /usr/share/man/es/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Dec 24 20:10:28 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:28.995Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:29 rivoplus volumio[3205]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/faked.1.gz because associated file /usr/share/man/es/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Dec 24 20:10:29 rivoplus volumio[3205]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/fakeroot.1.gz because associated file /usr/share/man/fr/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Dec 24 20:10:29 rivoplus volumio[3205]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/faked.1.gz because associated file /usr/share/man/fr/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Dec 24 20:10:29 rivoplus volumio[3205]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/fakeroot.1.gz because associated file /usr/share/man/sv/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist Dec 24 20:10:29 rivoplus volumio[3205]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/faked.1.gz because associated file /usr/share/man/sv/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist Dec 24 20:10:29 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:29.020Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:29 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:29.024Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:29 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:29.034Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:29 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:29.038Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:29 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:29.042Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:29 rivoplus volumio[3205]: info: Setting up libasan5:armhf (8.3.0-6) ... Dec 24 20:10:29 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:29.150Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:29 rivoplus volumio[3205]: info: Setting up libexpat1-dev:armhf (2.2.6-2+deb10u4) ... Dec 24 20:10:29 rivoplus volumio[3205]: info: Setting up make (4.2.1-1.2) ... Dec 24 20:10:29 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:29.164Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:29 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:29.167Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:29 rivoplus volumio[3205]: info: Setting up python3-gi (3.30.4-1) ... Dec 24 20:10:29 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:29.206Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:30 rivoplus volumio[3205]: info: Setting up patch (2.7.6-3+deb10u1) ... Dec 24 20:10:30 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:30.599Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:30 rivoplus volumio[3205]: info: Setting up libubsan1:armhf (8.3.0-6) ... Dec 24 20:10:30 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:30.628Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:30 rivoplus volumio[3205]: info: Setting up python3-crypto (2.6.1-9+b1) ... Dec 24 20:10:30 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:30.665Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:32 rivoplus volumio[3205]: info: Setting up python-pip-whl (18.1-5) ... Dec 24 20:10:32 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:32.534Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:32 rivoplus volumio[3205]: info: Setting up libbinutils:armhf (2.31.1-16) ... Dec 24 20:10:32 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:32.574Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:32 rivoplus volumio[3205]: info: Setting up binutils-arm-linux-gnueabihf (2.31.1-16) ... Dec 24 20:10:32 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:32.607Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:32 rivoplus volumio[3205]: info: Setting up python3-yarl (1.3.0-1) ... Dec 24 20:10:32 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:32.643Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:33 rivoplus volumio[3205]: info: Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ... Dec 24 20:10:33 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:33.689Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:33 rivoplus volumio[3205]: info: Setting up python3-asn1crypto (0.24.0-1) ... Dec 24 20:10:33 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:33.746Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:34 rivoplus qobuz-connect[6511]: 20251224 20:10:34.808 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:10:34 rivoplus qobuz-connect[6511]: 20251224 20:10:34.808 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:10:34 rivoplus qobuz-connect[6511]: 20251224 20:10:34.809 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:10:34 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:34 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:34 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:34 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:34 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:34 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:34 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:34 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:34 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:10:34 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:35 rivoplus volumio[3205]: info: Setting up libcc1-0:armhf (8.3.0-6) ... Dec 24 20:10:35 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:35.261Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:35 rivoplus volumio[3205]: info: Setting up python3-cffi-backend (1.12.2-1) ... Dec 24 20:10:35 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:35.331Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:35 rivoplus volumio[3205]: info: Setting up libalgorithm-merge-perl (0.08-3) ... Dec 24 20:10:35 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:35.370Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:35 rivoplus volumio[3205]: info: Setting up libpython3.7-dev:armhf (3.7.3-2+deb10u3) ... Dec 24 20:10:35 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:35.389Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:35 rivoplus volumio[3205]: info: Setting up python3-aiohttp (3.5.1-1+deb10u1) ... Dec 24 20:10:35 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:35.429Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:37 rivoplus volumio[3205]: info: Setting up binutils (2.31.1-16) ... Dec 24 20:10:37 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:37.093Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:37 rivoplus volumio[3205]: info: Setting up python3.7-dev (3.7.3-2+deb10u3) ... Dec 24 20:10:37 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:37.131Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:37 rivoplus volumio[3205]: info: Setting up dpkg-dev (1.19.8) ... Dec 24 20:10:37 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:37.167Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:37 rivoplus volumio[3205]: info: Setting up python3-cryptography (2.6.1-3+deb10u2) ... Dec 24 20:10:37 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:37.213Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:39 rivoplus volumio[3205]: info: Setting up python3-pip (18.1-5) ... Dec 24 20:10:39 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:39.079Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:40 rivoplus volumio[3205]: info: Setting up libgcc-8-dev:armhf (8.3.0-6) ... Dec 24 20:10:40 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:40.674Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:40 rivoplus volumio[3205]: info: Setting up python3-keyrings.alt (3.1.1-1) ... Dec 24 20:10:40 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:40.742Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:41 rivoplus volumio[3205]: info: Setting up libstdc++-8-dev:armhf (8.3.0-6) ... Dec 24 20:10:41 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:41.798Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:41 rivoplus volumio[3205]: info: Setting up gcc-8 (8.3.0-6) ... Dec 24 20:10:41 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:41.845Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:41 rivoplus volumio[3205]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... Dec 24 20:10:41 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:41.885Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:41 rivoplus volumio[3205]: info: Setting up gcc (4:8.3.0-1) ... Dec 24 20:10:41 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:41.910Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:41 rivoplus volumio[3205]: info: Setting up python3-secretstorage (2.3.1-2) ... Dec 24 20:10:42 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:41.995Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:42 rivoplus volumio[3205]: info: Setting up python3-dev (3.7.3-1) ... Dec 24 20:10:42 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:42.978Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:43 rivoplus volumio[3205]: info: Setting up g++-8 (8.3.0-6) ... Dec 24 20:10:43 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:43.021Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:43 rivoplus volumio[3205]: info: Setting up python3-keyring (17.1.1-1) ... Dec 24 20:10:43 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:43.039Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:44 rivoplus volumio[3205]: info: Setting up g++ (4:8.3.0-1) ... Dec 24 20:10:44 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:44.232Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:44 rivoplus volumio[3205]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode Dec 24 20:10:44 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:44.409Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:44 rivoplus volumio[3205]: info: Setting up build-essential (12.6) ... Dec 24 20:10:44 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:44.439Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:44 rivoplus volumio[3205]: info: Processing triggers for man-db (2.8.5-2) ... Dec 24 20:10:44 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:44.480Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:44 rivoplus qobuz-connect[6511]: 20251224 20:10:44.808 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:10:44 rivoplus qobuz-connect[6511]: 20251224 20:10:44.809 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:10:44 rivoplus qobuz-connect[6511]: 20251224 20:10:44.809 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:10:44 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:44 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:44 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:44 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:44 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:44 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:44 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:44 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:44 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:10:44 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:46 rivoplus volumio[3205]: info: Processing triggers for libc-bin (2.28-10+deb10u1) ... Dec 24 20:10:46 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:46.577Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:50 rivoplus sudo[13199]: pam_unix(sudo:session): session closed for user root Dec 24 20:10:50 rivoplus sudo[14528]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Dec 24 20:10:50 rivoplus sudo[14528]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:10:50 rivoplus volumio[3205]: info: lib/systemd/system/fusiondsp.service Dec 24 20:10:50 rivoplus sudo[14528]: pam_unix(sudo:session): session closed for user root Dec 24 20:10:50 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:50.285Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:52 rivoplus volumio[3205]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Dec 24 20:10:52 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:52.818Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:52 rivoplus volumio[3205]: info: more info at http://www.winimage.com/zLibDll/unzip.html Dec 24 20:10:52 rivoplus volumio[3205]: info: Dec 24 20:10:52 rivoplus volumio[3205]: info: cgui-1.0.0.zip opened Dec 24 20:10:52 rivoplus volumio[3205]: info: creating directory: cgui/ Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/LICENSE.txt Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/README.md Dec 24 20:10:52 rivoplus volumio[3205]: info: creating directory: cgui/build/ Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/robots.txt Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/logo512.png Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/asset-manifest.json Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/index.html Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/.put_statics_here Dec 24 20:10:52 rivoplus sudo[14532]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Dec 24 20:10:52 rivoplus sudo[14532]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/favicon.ico Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/logo192.png Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/manifest.json Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/css-variables.css Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/service-worker.js Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Dec 24 20:10:52 rivoplus volumio[3205]: info: creating directory: cgui/build/static/ Dec 24 20:10:52 rivoplus volumio[3205]: info: creating directory: cgui/build/static/css/ Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Dec 24 20:10:52 rivoplus sudo[14532]: pam_unix(sudo:session): session closed for user root Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/css/main.1503f6cd.css Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Dec 24 20:10:52 rivoplus volumio[3205]: info: creating directory: cgui/build/static/media/ Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Dec 24 20:10:52 rivoplus volumio[3205]: info: creating directory: cgui/build/static/js/ Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/main.71e11955.js Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Dec 24 20:10:52 rivoplus sudo[14534]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Dec 24 20:10:52 rivoplus sudo[14534]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Dec 24 20:10:52 rivoplus sudo[14534]: pam_unix(sudo:session): session closed for user root Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/main.71e11955.js.map Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Dec 24 20:10:52 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Dec 24 20:10:53 rivoplus volumio[3205]: info: creating directory: cgui/config/ Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/config/camillagui.yml Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/config/gui-config.yml Dec 24 20:10:53 rivoplus volumio[3205]: info: creating directory: cgui/backend/ Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/filters_test.py Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/version.py Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/filters.py Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/settings.py Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/views.py Dec 24 20:10:53 rivoplus sudo[14536]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/filterdefaults.py Dec 24 20:10:53 rivoplus sudo[14536]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/__init__.py Dec 24 20:10:53 rivoplus volumio[3205]: info: creating directory: cgui/backend/__pycache__/ Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Dec 24 20:10:53 rivoplus sudo[14536]: pam_unix(sudo:session): session closed for user root Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/routes.py Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/backend/filemanagement.py Dec 24 20:10:53 rivoplus volumio[3205]: info: extracting: cgui/main.py Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.085Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.089Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.093Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.094Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.108Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.109Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.121Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.125Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.130Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.131Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.133Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.134Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.138Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.140Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.143Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.144Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.167Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.183Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.187Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.190Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.193Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.194Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.198Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.209Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.214Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.221Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.225Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.227Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.238Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.241Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.254Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.256Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.264Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.265Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.273Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.289Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.290Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.291Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.294Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.295Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.298Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.299Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.304Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.305Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.307Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.324Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.325Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.328Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.330Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.329Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.335 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Received SetState message: Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.335 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Playing state: Playing Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.335 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Playback position: 0 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.335 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Queue version: 37.1 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.335 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Current track: TID: 79758102, QID: 55, Context UUID: 11fe62fc-1580-40f8-80cb-8eed66d18ea9 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.335 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Next track: TID: 79758103, QID: 56, Context UUID: 11fe62fc-1580-40f8-80cb-8eed66d18ea9 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.335 [6511.6511] INFO MediaEngine: [0xab43e558]: Stopping playback, clearing tracks Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.335 [6511.6511] INFO MediaEngine: [0xab43e558]: Initiating playback Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.335 [6511.6511] INFO AudioRenderer: [0xab43e778]: Stopping audio renderer Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.335 [6511.6511] INFO SampleApp: Stopping playback Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.335Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.339Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.340Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.347Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.348Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.348 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Clearing all streams Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.348 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 54]: Disposing of audio stream Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.348 [6511.6511] INFO ContentFetcher: [0xab490008]: Canceling download Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.357Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.358Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.360Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.364Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.365Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.367Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.370 [6511.6511] INFO SampleApp: Playback state changed: Stopped Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.371 [6511.6511] INFO StreamingEventReporter: [0xab43f0b0]: Reporting streaming ended event Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.372 [6511.6511] INFO HttpDownloader: [0xab43f108]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.373 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.373 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.374 [6511.6511] INFO SampleApp: [Stream 54]: Audio stream has been disposed of Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.374 [6511.6511] INFO MediaEngine: [0xab43e558]: Setting current track: 79758102, initial offset: 0ms Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.375 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Clearing all streams Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.375 [6511.6511] INFO AudioStreamManager: [0xab43e640]: New stream: 56 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.376 [6511.6511] INFO HttpDownloader: [0xab6b2440]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=26de82075ad9dcdbd3c3a7512becb968&request_ts=1766603453&track_id=79758102 Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.388Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.393Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.395Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.400Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.415 [6511.6511] INFO HttpDownloader: [0xab442f08]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=79758102 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.418 [6511.6511] INFO HttpDownloader: [0xab6b9cc8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=37b4f4a32725eedfdf7c6b60dbf500a8&request_ts=1766603453&track_id=79758102 Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.418Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.418Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.420 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 56]: Running audio stream Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.420 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.435Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.436Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.440 [6511.6511] INFO MediaEngine: [0xab43e558]: Setting next track: 79758103 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.440 [6511.6511] INFO AudioStreamManager: [0xab43e640]: New stream: 57 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.440 [6511.6511] INFO HttpDownloader: [0xab6b23f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=96e0a3d5e8ab3fbad387a29c519767ae&request_ts=1766603453&track_id=79758103 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.440 [6511.6511] INFO HttpDownloader: [0xab559b88]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=79758103 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.440 [6511.6511] INFO HttpDownloader: [0xab54ef08]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b577ffc1e63dbf9e5907f806e1fd254d&request_ts=1766603453&track_id=79758103 Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.441Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.442 [6511.6511] INFO MediaEngine: [0xab43e558]: Waiting for current stream to start before starting audio renderer Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.444Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.445Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.446Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:10:53.448Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.530 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 56]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758102&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607053&hmac=-2_zik09bx7z4hm2fKfmMrYtNao Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.615 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 57]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758103&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607053&hmac=kmIjPPyI8spqg0-z1cD46PjYpyw Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.630 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 56]: stream information have been fetched Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.631 [6511.6511] INFO UrlAudioSource: [0xab92a810]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758102&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607053&hmac=-2_zik09bx7z4hm2fKfmMrYtNao Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.631 [6511.6511] INFO ContentFetcher: [0xab48fd90]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758102&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607053&hmac=-2_zik09bx7z4hm2fKfmMrYtNao, offset: 0 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.631 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 57]: Metadata became available: Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.632 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Title: That Voice Again (Remaster 2012) Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.632 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Artist: Peter Gabriel Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.632 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album: So (Remaster 2012) Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.632 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album art URL: https://static.qobuz.com/images/covers/9b/b1/kjsz9jmqpb19b_600.jpg Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.633 [6511.6511] INFO AudioRenderer: [0xab43e778]: Starting audio renderer, initial playback state: Playing Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.633 [6511.6511] INFO SampleApp: [Stream 56]: New audio stream (starting from 0ms) Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.641 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 56]: Metadata became available: Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.641 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Title: Don't Give Up (Remaster 2012) Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.641 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Artist: Peter Gabriel Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.642 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album: So (Remaster 2012) Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.642 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album art URL: https://static.qobuz.com/images/covers/9b/b1/kjsz9jmqpb19b_600.jpg Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.642 [6511.6511] INFO SampleApp: [Stream 56]: Stream metadata became available: Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.642 [6511.6511] INFO SampleApp: Title: Don't Give Up (Remaster 2012) Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.642 [6511.6511] INFO SampleApp: Artist: Peter Gabriel Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.642 [6511.6511] INFO SampleApp: Album: So (Remaster 2012) Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.642 [6511.6511] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/9b/b1/kjsz9jmqpb19b_600.jpg Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.642 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 57]: stream information have been fetched Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.901 [6511.6511] INFO UrlAudioSource: [0xab92a810]: Content info extracted Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.901 [6511.6511] INFO UrlAudioSource: [0xab92a810]: Size: 139629294 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.909 [6511.14543] INFO AudioDecoder: [0xabb26330]: Stream info: Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.909 [6511.14543] INFO AudioDecoder: [0xabb26330]: Total samples: 37784300 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.909 [6511.14543] INFO AudioDecoder: [0xabb26330]: Sample rate: 96000 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.909 [6511.14543] INFO AudioDecoder: [0xabb26330]: Channel count: 2 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.909 [6511.14543] INFO AudioDecoder: [0xabb26330]: Bits per sample: 24 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.909 [6511.14543] INFO AudioDecoder: [0xabb26330]: Duration: 393586 Dec 24 20:10:53 rivoplus qobuz-connect[6511]: 20251224 20:10:53.911 [6511.6511] INFO UrlAudioSource: [0xab92a810]: Audio properties have changed Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: QobuzConnect: alread currrent service, send playback confirmation Dec 24 20:10:54 rivoplus qobuz-connect[6511]: 20251224 20:10:54.522 [6511.6511] INFO SampleApp: Initiating playback, initial state: Playing Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus qobuz-connect[6511]: 20251224 20:10:54.536 [6511.6511] INFO SampleApp: Playback state changed: Playing Dec 24 20:10:54 rivoplus qobuz-connect[6511]: 20251224 20:10:54.536 [6511.6511] INFO StreamingEventReporter: [0xab43f0b0]: Reporting streaming started event Dec 24 20:10:54 rivoplus qobuz-connect[6511]: 20251224 20:10:54.536 [6511.6511] INFO HttpDownloader: [0xab43f108]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Dec 24 20:10:54 rivoplus qobuz-connect[6511]: 20251224 20:10:54.537 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:10:54 rivoplus qobuz-connect[6511]: 20251224 20:10:54.537 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:10:54 rivoplus qobuz-connect[6511]: 20251224 20:10:54.539 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:10:54 rivoplus qobuz-connect[6511]: 20251224 20:10:54.540 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending FileAudioQualityChanged message Dec 24 20:10:54 rivoplus qobuz-connect[6511]: 20251224 20:10:54.549 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:10:54 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:54 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:10:54 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:10:54 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:10:54 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:10:54 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:10:54 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:54 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:54 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:54 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:10:54 rivoplus volumio[3205]: info: MCU Signalled Playback Inactive Dec 24 20:10:54 rivoplus volumio[3205]: info: MCU Signalled Playback Active Dec 24 20:11:01 rivoplus volumio[3205]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Dec 24 20:11:01 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:11:01.562Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:11:01 rivoplus volumio[3205]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-dzkl809n Dec 24 20:11:01 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:11:01.610Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:11:04 rivoplus qobuz-connect[6511]: 20251224 20:11:04.536 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:11:04 rivoplus qobuz-connect[6511]: 20251224 20:11:04.536 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:11:04 rivoplus qobuz-connect[6511]: 20251224 20:11:04.536 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:11:04 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:04 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:11:04 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:11:04 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:11:04 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:11:04 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:04 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:11:04 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:11:04 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:11:04 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:11:05 rivoplus volumio[3205]: info: Collecting PyYAML (from camilladsp==1.0.0) Dec 24 20:11:05 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:11:05.092Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:11:07 rivoplus volumio[3205]: info: Downloading https://files.pythonhosted.org/packages/cd/e5/af35f7ea75cf72f2cd079c95ee16797de7cd71f29ea7c68ae5ce7be1eda0/PyYAML-6.0.1.tar.gz (125kB) Dec 24 20:11:07 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:11:07.097Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:11:08 rivoplus volumio[3205]: info: Installing build dependencies: started Dec 24 20:11:08 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:11:08.323Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:11:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:14 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:11:14 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:11:14 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:11:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:11:14 rivoplus qobuz-connect[6511]: 20251224 20:11:14.540 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:11:14 rivoplus qobuz-connect[6511]: 20251224 20:11:14.540 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:11:14 rivoplus qobuz-connect[6511]: 20251224 20:11:14.540 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:11:14 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:14 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:11:14 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:11:14 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:11:14 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:11:20 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:11:20.174Z level=INFO msg="continue navigation request" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.165:64460,192.168.1.165:64568,00:00:00:00:00:00%1e @ 0x30d4b70" latency=30.753671ms timeout=10s from=APP_PAGE_ROOT Dec 24 20:11:20 rivoplus sudo[14625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 20:11:20 rivoplus sudo[14625]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:20 rivoplus sudo[14625]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:20 rivoplus sudo[14629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 20:11:20 rivoplus sudo[14629]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:20 rivoplus sudo[14629]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:20 rivoplus volumio[3205]: verbose: New Socket.io Connection to 192.168.1.114 from 192.168.1.165 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 24 20:11:21 rivoplus sudo[14633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 20:11:21 rivoplus sudo[14633]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:21 rivoplus sudo[14633]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:21 rivoplus sudo[14636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 20:11:21 rivoplus sudo[14636]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:21 rivoplus sudo[14636]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:21 rivoplus volumio[3205]: verbose: New Socket.io Connection to 192.168.1.114 from 192.168.1.165 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 24 20:11:21 rivoplus volumio[3205]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 24 20:11:21 rivoplus volumio[3205]: info: Received Get System Info Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 20:11:21 rivoplus volumio[3205]: info: Discovery: Getting this device information Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:21 rivoplus volumio[3205]: info: Listing playlists Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 24 20:11:21 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 24 20:11:22 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 24 20:11:23 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 20:11:23 rivoplus volumio[3205]: info: Received Get System Info Dec 24 20:11:23 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 20:11:23 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 20:11:23 rivoplus volumio[3205]: info: Discovery: Getting this device information Dec 24 20:11:23 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:23 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 20:11:24 rivoplus volumio[3205]: info: Received Get System Info Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 20:11:24 rivoplus volumio[3205]: info: Discovery: Getting this device information Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 24 20:11:24 rivoplus qobuz-connect[6511]: 20251224 20:11:24.537 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:11:24 rivoplus qobuz-connect[6511]: 20251224 20:11:24.537 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:11:24 rivoplus qobuz-connect[6511]: 20251224 20:11:24.537 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:11:24 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:24 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:11:24 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:11:24 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:11:24 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:11:31 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 24 20:11:34 rivoplus qobuz-connect[6511]: 20251224 20:11:34.537 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:11:34 rivoplus qobuz-connect[6511]: 20251224 20:11:34.537 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:11:34 rivoplus qobuz-connect[6511]: 20251224 20:11:34.537 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:11:34 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:34 rivoplus volumio[3205]: info: CoreCommandRouter::servicePushState Dec 24 20:11:34 rivoplus volumio[3205]: info: CoreStateMachine::pushState Dec 24 20:11:34 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:11:34 rivoplus volumio[3205]: info: CoreCommandRouter::volumioPushState Dec 24 20:11:34 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:34 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output update for this device Dec 24 20:11:34 rivoplus volumio[3205]: info: MRS: Pushing multiroomSync output Dec 24 20:11:34 rivoplus volumio[3205]: info: Signalling Playback active due to playback status change Dec 24 20:11:34 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:11:39 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 24 20:11:39 rivoplus volumio[3205]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 24 20:11:39 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 24 20:11:39 rivoplus volumio[3205]: info: Received Get System Version Dec 24 20:11:39 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 24 20:11:39 rivoplus volumio[3205]: info: Received Get System Info Dec 24 20:11:39 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 20:11:39 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 20:11:39 rivoplus volumio[3205]: info: Discovery: Getting this device information Dec 24 20:11:39 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:39 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 20:11:39 rivoplus volumio[3205]: info: Installing build dependencies: finished with status 'done' Dec 24 20:11:39 rivoplus volumio5-onboarding[4017]: time=2025-12-24T19:11:39.395Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 24 20:11:41 rivoplus volumio[3205]: info: Enabling plugin fusiondsp Dec 24 20:11:41 rivoplus volumio[3205]: info: Loading plugin "fusiondsp"... Dec 24 20:11:42 rivoplus volumio[3205]: info: Preparing to generate the ALSA configuration file Dec 24 20:11:42 rivoplus volumio[3205]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 24 20:11:42 rivoplus volumio[3205]: info: Reading ALSA contributions from plugins. Dec 24 20:11:42 rivoplus volumio[3205]: info: Asound.conf file unchanged, so no further update is needed Dec 24 20:11:42 rivoplus volumio[3205]: info: Output device has changed, restarting MPD Dec 24 20:11:42 rivoplus sudo[14740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 24 20:11:42 rivoplus sudo[14740]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:42 rivoplus sudo[14740]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:42 rivoplus volumio[3205]: info: Output device has changed, restarting Shairport Sync Dec 24 20:11:42 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:42 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 20:11:43 rivoplus sudo[14743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 24 20:11:43 rivoplus sudo[14743]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:43 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 24 20:11:43 rivoplus volumio[3205]: No protocol specified Dec 24 20:11:43 rivoplus volumio[3205]: xcb_connection_has_error() returned true Dec 24 20:11:43 rivoplus volumio[3205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 20:11:43 rivoplus volumio[3205]: No protocol specified Dec 24 20:11:43 rivoplus volumio[3205]: xcb_connection_has_error() returned true Dec 24 20:11:43 rivoplus volumio[3205]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:43 rivoplus volumio[3205]: info: QobuzConnect: setDeactiveState invoked Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:43 rivoplus volumio[3205]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State Dec 24 20:11:43 rivoplus volumio[3205]: verbose: UNSET VOLATILE: Service: qobuzconnect Dec 24 20:11:43 rivoplus volumio[3205]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Dec 24 20:11:43 rivoplus volumio[3205]: info: QobuzConnect: Received stop Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.227 [6511.6511] INFO MediaEngine: [0xab43e558]: Stopping playback, keeping tracks Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.228 [6511.6511] INFO AudioRenderer: [0xab43e778]: Stopping audio renderer Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.228 [6511.6511] INFO SampleApp: Stopping playback Dec 24 20:11:43 rivoplus volumio[3205]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.241 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Clearing all streams Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.241 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 56]: Disposing of audio stream Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.241 [6511.6511] INFO ContentFetcher: [0xab48fd90]: Canceling download Dec 24 20:11:43 rivoplus vtcs[6592]: [2025-12-24 20:11:43.239] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 24 20:11:43 rivoplus vtcs[6592]: [2025-12-24 20:11:43.238] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.263 [6511.6511] INFO AudioStreamManager: [0xab43e640]: New stream: 58 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.269 [6511.6511] INFO HttpDownloader: [0xab6b23f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=237fc94b0ba57ae9b5c7b61fd2c5d449&request_ts=1766603503&track_id=79758102 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.270 [6511.6511] INFO HttpDownloader: [0xab68c148]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=79758102 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.270 [6511.6511] INFO HttpDownloader: [0xab62f148]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ebbe7b7a8adb74dac517b629d75ac91c&request_ts=1766603503&track_id=79758102 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.272 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 58]: Running audio stream Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.274 [6511.6511] INFO AudioStreamManager: [0xab43e640]: New stream: 59 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.274 [6511.6511] INFO HttpDownloader: [0xab441b88]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b62251a74030fa1d4bb0adaae3d53192&request_ts=1766603503&track_id=79758103 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.275 [6511.6511] INFO HttpDownloader: [0xabb1b5b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=79758103 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.275 [6511.6511] INFO HttpDownloader: [0xab4ab758]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=91aceede4675cc1f5adc24ec805d82ea&request_ts=1766603503&track_id=79758103 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.282 [6511.6511] INFO SampleApp: Playback state changed: Stopped Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.282 [6511.6511] INFO StreamingEventReporter: [0xab43f0b0]: Reporting streaming ended event Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.282 [6511.6511] INFO HttpDownloader: [0xab43f108]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.282 [6511.6511] INFO ProtocolHandler: [0xab43ec98]: Need to send state update Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.282 [6511.6511] INFO QConnectMessageSender: [0xab5c9390]: Sending StateUpdated message Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.282 [6511.6511] INFO SampleApp: [Stream 56]: Audio stream has been disposed of Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.283 [6511.6511] INFO QwspMessageSender: [0xab5c9380]: Sending Payload message Dec 24 20:11:43 rivoplus volumio[3205]: info: PLUGIN START: fusiondsp Dec 24 20:11:43 rivoplus volumio[3205]: info: Loading i18n strings for locale en Dec 24 20:11:43 rivoplus sudo[14751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 20:11:43 rivoplus sudo[14751]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:43 rivoplus volumio[3205]: info: FusionDsp - mixtype--------------------- Hardware Dec 24 20:11:43 rivoplus volumio[3205]: info: Preparing to generate the ALSA configuration file Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.393 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 58]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758102&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607103&hmac=689yDdDI_nPZNhZBM3mbhz-6dI0 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.420 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 58]: Metadata became available: Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.420 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Title: Don't Give Up (Remaster 2012) Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.420 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Artist: Peter Gabriel Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.420 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album: So (Remaster 2012) Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.420 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album art URL: https://static.qobuz.com/images/covers/9b/b1/kjsz9jmqpb19b_600.jpg Dec 24 20:11:43 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Dec 24 20:11:43 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 24 20:11:43 rivoplus systemd[1]: vtcs.service: Succeeded. Dec 24 20:11:43 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Dec 24 20:11:43 rivoplus volumio[3205]: info: Done. Dec 24 20:11:43 rivoplus sudo[14751]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.488 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 58]: stream information have been fetched Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.489 [6511.6511] INFO UrlAudioSource: [0xab4c2530]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758102&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607103&hmac=689yDdDI_nPZNhZBM3mbhz-6dI0 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.489 [6511.6511] INFO ContentFetcher: [0xab7e39c0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758102&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607103&hmac=689yDdDI_nPZNhZBM3mbhz-6dI0, offset: 0 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.489 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 59]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2479254&eid=79758103&fmt=7&profile=raw&app_id=174516466&cid=3444449&etsp=1766607103&hmac=hGu6KGP9qnjuz6RDxyZAIKijWMk Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.489 [6511.6511] INFO SampleApp: [Stream 58]: New audio stream (starting from 0ms) Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.490 [6511.6511] INFO SampleApp: [Stream 58]: Stream metadata became available: Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.490 [6511.6511] INFO SampleApp: Title: Don't Give Up (Remaster 2012) Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.490 [6511.6511] INFO SampleApp: Artist: Peter Gabriel Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.490 [6511.6511] INFO SampleApp: Album: So (Remaster 2012) Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.490 [6511.6511] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/9b/b1/kjsz9jmqpb19b_600.jpg Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.501 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 59]: Metadata became available: Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.501 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Title: That Voice Again (Remaster 2012) Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.501 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Artist: Peter Gabriel Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.501 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album: So (Remaster 2012) Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.501 [6511.6511] INFO AudioStreamManager: [0xab43e640]: Album art URL: https://static.qobuz.com/images/covers/9b/b1/kjsz9jmqpb19b_600.jpg Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.514 [6511.6511] INFO AudioStreamManager: [0xab43e640]: [Stream 59]: stream information have been fetched Dec 24 20:11:43 rivoplus systemd[1]: mpd.service: Succeeded. Dec 24 20:11:43 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 24 20:11:43 rivoplus sudo[14758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 20:11:43 rivoplus sudo[14758]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:43 rivoplus systemd[1]: Starting Music Player Daemon... Dec 24 20:11:43 rivoplus sudo[14758]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:43 rivoplus volumio[3205]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 24 20:11:43 rivoplus volumio[3205]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 24 20:11:43 rivoplus volumio[3205]: info: Reading ALSA contributions from plugins. Dec 24 20:11:43 rivoplus volumio[3205]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 20:11:43 rivoplus volumio[3205]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 20:11:43 rivoplus volumio[3205]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 20:11:43 rivoplus volumio[3205]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 20:11:43 rivoplus volumio[3205]: info: QobuzConnect: playback_stop received, deferred solved Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:43 rivoplus volumio[3205]: info: CorePlayQueue::getTrack 4 Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:43 rivoplus volumio[3205]: info: CorePlayQueue::getTrack 4 Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:43 rivoplus volumio[3205]: info: CorePlayQueue::getTrack 4 Dec 24 20:11:43 rivoplus sudo[14774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 24 20:11:43 rivoplus sudo[14774]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.790 [6511.6511] INFO UrlAudioSource: [0xab4c2530]: Content info extracted Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.790 [6511.6511] INFO UrlAudioSource: [0xab4c2530]: Size: 139629294 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.799 [6511.14776] INFO AudioDecoder: [0xabc7bcc8]: Stream info: Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.800 [6511.14776] INFO AudioDecoder: [0xabc7bcc8]: Total samples: 37784300 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.800 [6511.14776] INFO AudioDecoder: [0xabc7bcc8]: Sample rate: 96000 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.800 [6511.14776] INFO AudioDecoder: [0xabc7bcc8]: Channel count: 2 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.800 [6511.14776] INFO AudioDecoder: [0xabc7bcc8]: Bits per sample: 24 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.800 [6511.14776] INFO AudioDecoder: [0xabc7bcc8]: Duration: 393586 Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.801 [6511.6511] INFO UrlAudioSource: [0xab4c2530]: Audio properties have changed Dec 24 20:11:43 rivoplus volumio[3205]: info: MPD Permissions set Dec 24 20:11:43 rivoplus sudo[14774]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:43 rivoplus sudo[14777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 24 20:11:43 rivoplus sudo[14777]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:43 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.940 [6511.6511] INFO ContentFetcher: [0xab7e39c0]: Canceling download Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:43 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:11:43 rivoplus volumio[3205]: info: Not Reporting Auto name since its the default one Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.961 [6511.6511] INFO CloudClient: [0xab43eb30]: Disconnecting from the cloud Dec 24 20:11:43 rivoplus qobuz-connect[6511]: 20251224 20:11:43.961 [6511.6511] INFO CloudClient: [0xab43eb30]: Connection closed Dec 24 20:11:43 rivoplus sudo[14760]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 24 20:11:43 rivoplus sudo[14760]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:44 rivoplus volumio[3205]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 20:11:44 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:44 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:44 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:44 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:44 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:44 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:44 rivoplus sudo[14760]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:44 rivoplus qobuz-connect[6511]: 20251224 20:11:44.093 [6511.6511] INFO SampleApp: Stopping Local configuration server Dec 24 20:11:44 rivoplus qobuz-connect[6511]: 20251224 20:11:44.095 [6511.6511] INFO SampleApp: shat down connection on UNIX socket Dec 24 20:11:44 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 24 20:11:44 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 24 20:11:44 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 24 20:11:44 rivoplus sudo[14777]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:44 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 20:11:44 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 24 20:11:44 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:44 rivoplus volumio[3205]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 20:11:44 rivoplus volumio[3205]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 24 20:11:44 rivoplus volumio[3205]: info: QobuzConnect: setDeactiveState invoked Dec 24 20:11:44 rivoplus volumio[3205]: info: CoreCommandRouter::volumioGetState Dec 24 20:11:44 rivoplus volumio[3205]: info: CorePlayQueue::getTrack 4 Dec 24 20:11:44 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 24 20:11:44 rivoplus volumio[3205]: No protocol specified Dec 24 20:11:44 rivoplus volumio[3205]: xcb_connection_has_error() returned true Dec 24 20:11:44 rivoplus sudo[14798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 24 20:11:44 rivoplus sudo[14798]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:44 rivoplus sudo[14798]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:44 rivoplus sudo[14800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 24 20:11:44 rivoplus sudo[14800]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:44 rivoplus systemd[1]: Stopping RAAT DAEMON... Dec 24 20:11:44 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Dec 24 20:11:44 rivoplus systemd[1]: raat-daemon.service: Succeeded. Dec 24 20:11:44 rivoplus systemd[1]: Stopped RAAT DAEMON. Dec 24 20:11:44 rivoplus systemd[1]: Started RAAT DAEMON. Dec 24 20:11:44 rivoplus sudo[14800]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:45 rivoplus volumio[3205]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 24 20:11:45 rivoplus volumio[3205]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 24 20:11:45 rivoplus volumio[3205]: info: Raat Daemon started successfully Dec 24 20:11:45 rivoplus volumio[3205]: info: Starting Shairport Sync Dec 24 20:11:45 rivoplus volumio[3205]: info: Executing endpoint qc_getconfig Dec 24 20:11:45 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 24 20:11:45 rivoplus sudo[14834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 20:11:45 rivoplus sudo[14834]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:45 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 24 20:11:45 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 24 20:11:45 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 24 20:11:45 rivoplus qobuz-connect[14789]: 20251224 20:11:45.465 [14789.14789] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 24 20:11:45 rivoplus qobuz-connect[14789]: 20251224 20:11:45.486 [14789.14789] INFO VolumeManager: [0xabbfc9c0]: Setting new playback volume: 75 Dec 24 20:11:45 rivoplus qobuz-connect[14789]: 20251224 20:11:45.486 [14789.14789] INFO VolumeManager: [0xabbfc9c0]: Setting new mute state: 0 Dec 24 20:11:45 rivoplus qobuz-connect[14789]: 20251224 20:11:45.486 [14789.14789] INFO QobuzConnect: [0xabbfd390]: Client initialized! Dec 24 20:11:45 rivoplus qobuz-connect[14789]: 20251224 20:11:45.486 [14789.14789] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Dec 24 20:11:45 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 24 20:11:45 rivoplus volumio[3205]: info: Executing endpoint restartRAATSocket Dec 24 20:11:45 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 24 20:11:45 rivoplus sudo[14834]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:45 rivoplus qobuz-connect[14789]: 20251224 20:11:45.556 [14789.14789] INFO LocalConfigManager: [0xabbfc2a0]: Starting Local Configuration server Dec 24 20:11:45 rivoplus qobuz-connect[14789]: 20251224 20:11:45.556 [14789.14789] INFO SampleApp: Starting Local configuration server Dec 24 20:11:45 rivoplus qobuz-connect[14789]: 20251224 20:11:45.557 [14789.14789] INFO SampleApp: Connected to UNIX socket client 0xabbf1ed8 Dec 24 20:11:45 rivoplus qobuz-connect[14789]: 20251224 20:11:45.614 [14789.14789] INFO SampleApp: Playback volume changed: 75 Dec 24 20:11:45 rivoplus volumio[3205]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Dec 24 20:11:45 rivoplus volumio[3205]: info: camilladsp service started and running in background, instance 1 Dec 24 20:11:45 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:45 rivoplus volumio[3205]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 24 20:11:45 rivoplus volumio[3205]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 24 20:11:45 rivoplus volumio[3205]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 24 20:11:45 rivoplus volumio[3205]: info: FusionDsp loaded Dec 24 20:11:45 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 20:11:46 rivoplus sudo[14855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 24 20:11:46 rivoplus sudo[14855]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 24 20:11:46 rivoplus systemd[1]: Started FusionDsp Daemon. Dec 24 20:11:46 rivoplus sudo[14855]: pam_unix(sudo:session): session closed for user root Dec 24 20:11:46 rivoplus volumio[3205]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 24 20:11:46 rivoplus volumio[3205]: info: Adding Signal Path Element [object Object] Dec 24 20:11:46 rivoplus volumio[3205]: info: Adding fusiondspeq DSP Signal Path Element Dec 24 20:11:46 rivoplus volumio[3205]: info: Additional DSP elements updated Dec 24 20:11:46 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:46 rivoplus volumio[3205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:11:46 rivoplus volumio[3205]: info: Updating RAAT Signal Path Dec 24 20:11:46 rivoplus volumio[3205]: info: FusionDsp - ---- installed callbackRead Dec 24 20:11:46 rivoplus volumio[3205]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 20:11:46 rivoplus volumio[3205]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Dec 24 20:11:46 rivoplus volumio[3205]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Dec 24 20:11:46 rivoplus volumio[3205]: at onErrorNT (internal/child_process.js:472:16) Dec 24 20:11:46 rivoplus volumio[3205]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Dec 24 20:11:46 rivoplus volumio[3205]: at runNextTicks (internal/process/task_queues.js:64:3) Dec 24 20:11:46 rivoplus volumio[3205]: at listOnTimeout (internal/timers.js:526:9) Dec 24 20:11:46 rivoplus volumio[3205]: at processTimers (internal/timers.js:500:7) { Dec 24 20:11:46 rivoplus volumio[3205]: errno: -2, Dec 24 20:11:46 rivoplus volumio[3205]: code: 'ENOENT', Dec 24 20:11:46 rivoplus volumio[3205]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Dec 24 20:11:46 rivoplus volumio[3205]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Dec 24 20:11:46 rivoplus volumio[3205]: spawnargs: [ Dec 24 20:11:46 rivoplus volumio[3205]: '-p', Dec 24 20:11:46 rivoplus volumio[3205]: 9876, Dec 24 20:11:46 rivoplus volumio[3205]: '-o', Dec 24 20:11:46 rivoplus volumio[3205]: '/tmp/camilladsp.log', Dec 24 20:11:46 rivoplus volumio[3205]: '-l', Dec 24 20:11:46 rivoplus volumio[3205]: 'warn', Dec 24 20:11:46 rivoplus volumio[3205]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Dec 24 20:11:46 rivoplus volumio[3205]: ] Dec 24 20:11:46 rivoplus volumio[3205]: } Dec 24 20:11:46 rivoplus volumio[3205]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 20:11:47 rivoplus sudo[14867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-24 20:10 Dec 24 20:11:47 rivoplus sudo[14867]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:47:33 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="9e8aa5d6afb5e5d87a87bafae7aa5647"