-- Logs begin at Fri 2025-12-19 05:05:23 MST, end at Sat 2025-12-20 21:23:20 MST. -- Dec 20 21:22:00 rivoplus volumio[3101]: info: Selecting previously unselected package python3-cryptography. Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.007-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Preparing to unpack .../36-python3-cryptography_2.6.1-3+deb10u2_armhf.deb ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.025-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Unpacking python3-cryptography (2.6.1-3+deb10u2) ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.038-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Selecting previously unselected package python3-dbus. Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.304-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Preparing to unpack .../37-python3-dbus_1.2.8-3_armhf.deb ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.323-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Unpacking python3-dbus (1.2.8-3) ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.335-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Selecting previously unselected package python3.7-dev. Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.475-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Preparing to unpack .../38-python3.7-dev_3.7.3-2+deb10u3_armhf.deb ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.492-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Unpacking python3.7-dev (3.7.3-2+deb10u3) ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.504-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Selecting previously unselected package python3-dev. Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.634-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Preparing to unpack .../39-python3-dev_3.7.3-1_armhf.deb ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.658-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Unpacking python3-dev (3.7.3-1) ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.668-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Selecting previously unselected package python3-entrypoints. Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.780-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Preparing to unpack .../40-python3-entrypoints_0.3-1_all.deb ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.796-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Unpacking python3-entrypoints (0.3-1) ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.807-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Selecting previously unselected package python3-gi. Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.908-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Preparing to unpack .../41-python3-gi_3.30.4-1_armhf.deb ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.928-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:00 rivoplus volumio[3101]: info: Unpacking python3-gi (3.30.4-1) ... Dec 20 21:22:00 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:00.940-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Selecting previously unselected package python3-secretstorage. Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.112-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Preparing to unpack .../42-python3-secretstorage_2.3.1-2_all.deb ... Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.129-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Unpacking python3-secretstorage (2.3.1-2) ... Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.139-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Selecting previously unselected package python3-keyring. Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.260-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Preparing to unpack .../43-python3-keyring_17.1.1-1_all.deb ... Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.277-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Unpacking python3-keyring (17.1.1-1) ... Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.290-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Selecting previously unselected package python3-keyrings.alt. Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.470-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Preparing to unpack .../44-python3-keyrings.alt_3.1.1-1_all.deb ... Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.482-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Unpacking python3-keyrings.alt (3.1.1-1) ... Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.499-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Selecting previously unselected package python3-pip. Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.615-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Preparing to unpack .../45-python3-pip_18.1-5_all.deb ... Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.635-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Unpacking python3-pip (18.1-5) ... Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.646-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Selecting previously unselected package python3-setuptools. Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.883-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Preparing to unpack .../46-python3-setuptools_40.8.0-1_all.deb ... Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.929-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:01 rivoplus volumio[3101]: info: Unpacking python3-setuptools (40.8.0-1) ... Dec 20 21:22:01 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:01.949-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:02 rivoplus volumio[3101]: info: Selecting previously unselected package python3-wheel. Dec 20 21:22:02 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:02.272-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:02 rivoplus volumio[3101]: info: Preparing to unpack .../47-python3-wheel_0.32.3-2_all.deb ... Dec 20 21:22:02 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:02.288-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:02 rivoplus volumio[3101]: info: Unpacking python3-wheel (0.32.3-2) ... Dec 20 21:22:02 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:02.301-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:02 rivoplus volumio[3101]: info: Selecting previously unselected package python3-xdg. Dec 20 21:22:02 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:02.428-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:02 rivoplus volumio[3101]: info: Preparing to unpack .../48-python3-xdg_0.25-5_all.deb ... Dec 20 21:22:02 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:02.446-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:02 rivoplus volumio[3101]: info: Unpacking python3-xdg (0.25-5) ... Dec 20 21:22:02 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:02.462-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:02 rivoplus volumio[3101]: info: Setting up python3-entrypoints (0.3-1) ... Dec 20 21:22:02 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:02.682-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:03 rivoplus volumio[3101]: info: Setting up dh-python (3.20190308) ... Dec 20 21:22:03 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:03.487-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:04 rivoplus volumio[3101]: info: Setting up python3-attr (18.2.0-1) ... Dec 20 21:22:04 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:04.349-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:05 rivoplus volumio[3101]: info: Setting up python3-dbus (1.2.8-3) ... Dec 20 21:22:05 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:05.131-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:05 rivoplus volumio[3101]: info: Setting up python3-setuptools (40.8.0-1) ... Dec 20 21:22:05 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:05.948-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:06 rivoplus qobuz-connect[3489]: 20251220 21:22:06.083 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Need to send state update Dec 20 21:22:06 rivoplus qobuz-connect[3489]: 20251220 21:22:06.083 [3489.3489] INFO QConnectMessageSender: [0xac50b9f0]: Sending StateUpdated message Dec 20 21:22:06 rivoplus qobuz-connect[3489]: 20251220 21:22:06.083 [3489.3489] INFO QwspMessageSender: [0xac50b9e0]: Sending Payload message Dec 20 21:22:06 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:06 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:22:06 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:22:06 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 21:22:06 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:22:06 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:06 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:06 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:07 rivoplus volumio[3101]: info: Setting up libalgorithm-diff-perl (1.19.03-2) ... Dec 20 21:22:07 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:07.431-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:07 rivoplus volumio[3101]: info: Setting up binutils-common:armhf (2.31.1-16) ... Dec 20 21:22:07 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:07.460-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:07 rivoplus volumio[3101]: info: Setting up python3-xdg (0.25-5) ... Dec 20 21:22:07 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:07.479-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:08 rivoplus volumio[3101]: info: Setting up bzip2 (1.0.6-9.2~deb10u1) ... Dec 20 21:22:08 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:08.357-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:08 rivoplus volumio[3101]: info: Setting up python3-wheel (0.32.3-2) ... Dec 20 21:22:08 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:08.377-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:08 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:08.914-07:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.1.17:41528 error="websocket: close 1006 (abnormal closure): unexpected EOF" Dec 20 21:22:08 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:08.914-07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.17:41528 Dec 20 21:22:08 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:08.914-07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.17:41528 Dec 20 21:22:09 rivoplus volumio[3101]: info: Setting up python3-multidict (4.5.2-1) ... Dec 20 21:22:09 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:09.187-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:09 rivoplus volumio[3101]: info: Setting up libfakeroot:armhf (1.23-1) ... Dec 20 21:22:09 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:09.945-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:09 rivoplus volumio[3101]: info: Setting up python3-async-timeout (3.0.1-1) ... Dec 20 21:22:09 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:09.975-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus dbus-daemon[2847]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.12" (uid=0 pid=3448 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3186 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Dec 20 21:22:10 rivoplus volumio[3101]: info: Setting up fakeroot (1.23-1) ... Dec 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.713-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus volumio[3101]: info: update-alternatives: using /usr/bin/fakeroot-sysv to provide /usr/bin/fakeroot (fakeroot) in auto mode Dec 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.783-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus volumio[3101]: 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 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.797-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus volumio[3101]: 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 20 21:22:10 rivoplus volumio[3101]: 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 20 21:22:10 rivoplus volumio[3101]: 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 20 21:22:10 rivoplus volumio[3101]: 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 20 21:22:10 rivoplus volumio[3101]: 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 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.814-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.815-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.817-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.818-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.820-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus volumio[3101]: info: Setting up libasan5:armhf (8.3.0-6) ... Dec 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.859-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus volumio[3101]: info: Setting up libexpat1-dev:armhf (2.2.6-2+deb10u4) ... Dec 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.866-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus volumio[3101]: info: Setting up make (4.2.1-1.2) ... Dec 20 21:22:10 rivoplus volumio[3101]: info: Setting up python3-gi (3.30.4-1) ... Dec 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.889-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:10 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:10.895-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:11 rivoplus volumio[3101]: info: Setting up patch (2.7.6-3+deb10u1) ... Dec 20 21:22:11 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:11.941-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:11 rivoplus volumio[3101]: info: Setting up libubsan1:armhf (8.3.0-6) ... Dec 20 21:22:11 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:11.961-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:11 rivoplus volumio[3101]: info: Setting up python3-crypto (2.6.1-9+b1) ... Dec 20 21:22:11 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:11.978-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:13 rivoplus volumio[3101]: info: Setting up python-pip-whl (18.1-5) ... Dec 20 21:22:13 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:13.331-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:13 rivoplus volumio[3101]: info: Setting up libbinutils:armhf (2.31.1-16) ... Dec 20 21:22:13 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:13.350-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:13 rivoplus volumio[3101]: info: Setting up binutils-arm-linux-gnueabihf (2.31.1-16) ... Dec 20 21:22:13 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:13.374-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:13 rivoplus volumio[3101]: info: Setting up python3-yarl (1.3.0-1) ... Dec 20 21:22:13 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:13.392-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:14 rivoplus volumio[3101]: info: Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ... Dec 20 21:22:14 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:14.156-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:14 rivoplus volumio[3101]: info: Setting up python3-asn1crypto (0.24.0-1) ... Dec 20 21:22:14 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:14.180-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:15 rivoplus volumio[3101]: info: Setting up libcc1-0:armhf (8.3.0-6) ... Dec 20 21:22:15 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:15.255-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:15 rivoplus volumio[3101]: info: Setting up python3-cffi-backend (1.12.2-1) ... Dec 20 21:22:15 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:15.286-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:15 rivoplus volumio[3101]: info: Setting up libalgorithm-merge-perl (0.08-3) ... Dec 20 21:22:15 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:15.307-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:15 rivoplus volumio[3101]: info: Setting up libpython3.7-dev:armhf (3.7.3-2+deb10u3) ... Dec 20 21:22:15 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:15.328-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:15 rivoplus volumio[3101]: info: Setting up python3-aiohttp (3.5.1-1+deb10u1) ... Dec 20 21:22:15 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:15.348-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:16 rivoplus qobuz-connect[3489]: 20251220 21:22:16.084 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Need to send state update Dec 20 21:22:16 rivoplus qobuz-connect[3489]: 20251220 21:22:16.084 [3489.3489] INFO QConnectMessageSender: [0xac50b9f0]: Sending StateUpdated message Dec 20 21:22:16 rivoplus qobuz-connect[3489]: 20251220 21:22:16.084 [3489.3489] INFO QwspMessageSender: [0xac50b9e0]: Sending Payload message Dec 20 21:22:16 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:16 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:22:16 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:22:16 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 21:22:16 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:22:16 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:16 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:16 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:16 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:16.395-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=9 Dec 20 21:22:16 rivoplus volumio[3101]: info: Setting up binutils (2.31.1-16) ... Dec 20 21:22:16 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:16.639-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:16 rivoplus volumio[3101]: info: Setting up python3.7-dev (3.7.3-2+deb10u3) ... Dec 20 21:22:16 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:16.662-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:16 rivoplus volumio[3101]: info: Setting up dpkg-dev (1.19.8) ... Dec 20 21:22:16 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:16.685-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:16 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:16.696-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=9 Dec 20 21:22:16 rivoplus volumio[3101]: info: Setting up python3-cryptography (2.6.1-3+deb10u2) ... Dec 20 21:22:16 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:16.714-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:16 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:16.996-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=9 Dec 20 21:22:17 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:17.297-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=9 Dec 20 21:22:17 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:17.597-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=9 Dec 20 21:22:17 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:17.898-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=9 Dec 20 21:22:18 rivoplus volumio[3101]: info: Setting up python3-pip (18.1-5) ... Dec 20 21:22:18 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:18.073-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:18 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:18.199-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=9 Dec 20 21:22:18 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:18.499-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=9 Dec 20 21:22:18 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:18.800-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=9 Dec 20 21:22:19 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:19.102-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=9 Dec 20 21:22:19 rivoplus volumio[3101]: info: Setting up libgcc-8-dev:armhf (8.3.0-6) ... Dec 20 21:22:19 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:19.349-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:19 rivoplus volumio[3101]: info: Setting up python3-keyrings.alt (3.1.1-1) ... Dec 20 21:22:19 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:19.379-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:19 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:19.403-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=9 Dec 20 21:22:19 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:19.403-07:00 level=ERROR msg="pending write exceeded maximum retries, dropping" component=conn/ble addr=9 chunks=1 index=0 Dec 20 21:22:19 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:19.403-07:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%09 @ 0x303bc80" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Dec 20 21:22:20 rivoplus volumio[3101]: info: Setting up libstdc++-8-dev:armhf (8.3.0-6) ... Dec 20 21:22:20 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:20.198-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:20 rivoplus volumio[3101]: info: Setting up gcc-8 (8.3.0-6) ... Dec 20 21:22:20 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:20.217-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:20 rivoplus volumio[3101]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... Dec 20 21:22:20 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:20.234-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:20 rivoplus volumio[3101]: info: Setting up gcc (4:8.3.0-1) ... Dec 20 21:22:20 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:20.253-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:20 rivoplus volumio[3101]: info: Setting up python3-secretstorage (2.3.1-2) ... Dec 20 21:22:20 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:20.317-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:21 rivoplus volumio[3101]: info: Setting up python3-dev (3.7.3-1) ... Dec 20 21:22:21 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:21.063-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:21 rivoplus volumio[3101]: info: Setting up g++-8 (8.3.0-6) ... Dec 20 21:22:21 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:21.081-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:21 rivoplus volumio[3101]: info: Setting up python3-keyring (17.1.1-1) ... Dec 20 21:22:21 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:21.098-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:21 rivoplus volumio[3101]: info: Setting up g++ (4:8.3.0-1) ... Dec 20 21:22:21 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:21.954-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:22 rivoplus volumio[3101]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode Dec 20 21:22:22 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:22.108-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:22 rivoplus volumio[3101]: info: Setting up build-essential (12.6) ... Dec 20 21:22:22 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:22.126-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:22 rivoplus volumio[3101]: info: Processing triggers for man-db (2.8.5-2) ... Dec 20 21:22:22 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:22.142-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:23 rivoplus volumio[3101]: info: Processing triggers for libc-bin (2.28-10+deb10u1) ... Dec 20 21:22:23 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:23.647-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:25 rivoplus sudo[18803]: pam_unix(sudo:session): session closed for user root Dec 20 21:22:25 rivoplus sudo[20100]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Dec 20 21:22:25 rivoplus sudo[20100]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:22:25 rivoplus volumio[3101]: info: lib/systemd/system/fusiondsp.service Dec 20 21:22:25 rivoplus sudo[20100]: pam_unix(sudo:session): session closed for user root Dec 20 21:22:25 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:25.726-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:26 rivoplus qobuz-connect[3489]: 20251220 21:22:26.085 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Need to send state update Dec 20 21:22:26 rivoplus qobuz-connect[3489]: 20251220 21:22:26.085 [3489.3489] INFO QConnectMessageSender: [0xac50b9f0]: Sending StateUpdated message Dec 20 21:22:26 rivoplus qobuz-connect[3489]: 20251220 21:22:26.086 [3489.3489] INFO QwspMessageSender: [0xac50b9e0]: Sending Payload message Dec 20 21:22:26 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:26 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:22:26 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:22:26 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 21:22:26 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:22:26 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:26 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:26 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:28 rivoplus volumio[3101]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.495-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio[3101]: info: more info at http://www.winimage.com/zLibDll/unzip.html Dec 20 21:22:28 rivoplus volumio[3101]: info: Dec 20 21:22:28 rivoplus sudo[20107]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Dec 20 21:22:28 rivoplus volumio[3101]: info: cgui-1.0.0.zip opened Dec 20 21:22:28 rivoplus sudo[20107]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:22:28 rivoplus volumio[3101]: info: creating directory: cgui/ Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/LICENSE.txt Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/README.md Dec 20 21:22:28 rivoplus volumio[3101]: info: creating directory: cgui/build/ Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/robots.txt Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/logo512.png Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/asset-manifest.json Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/index.html Dec 20 21:22:28 rivoplus sudo[20107]: pam_unix(sudo:session): session closed for user root Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/.put_statics_here Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/favicon.ico Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/logo192.png Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/manifest.json Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/css-variables.css Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/service-worker.js Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Dec 20 21:22:28 rivoplus volumio[3101]: info: creating directory: cgui/build/static/ Dec 20 21:22:28 rivoplus volumio[3101]: info: creating directory: cgui/build/static/css/ Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Dec 20 21:22:28 rivoplus sudo[20109]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Dec 20 21:22:28 rivoplus sudo[20109]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/css/main.1503f6cd.css Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Dec 20 21:22:28 rivoplus sudo[20109]: pam_unix(sudo:session): session closed for user root Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Dec 20 21:22:28 rivoplus volumio[3101]: info: creating directory: cgui/build/static/media/ Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Dec 20 21:22:28 rivoplus volumio[3101]: info: creating directory: cgui/build/static/js/ Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/main.71e11955.js Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Dec 20 21:22:28 rivoplus sudo[20111]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Dec 20 21:22:28 rivoplus sudo[20111]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/main.71e11955.js.map Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Dec 20 21:22:28 rivoplus volumio[3101]: info: creating directory: cgui/config/ Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/config/camillagui.yml Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/config/gui-config.yml Dec 20 21:22:28 rivoplus volumio[3101]: info: creating directory: cgui/backend/ Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/filters_test.py Dec 20 21:22:28 rivoplus sudo[20111]: pam_unix(sudo:session): session closed for user root Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/version.py Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/filters.py Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/settings.py Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/views.py Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/filterdefaults.py Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/__init__.py Dec 20 21:22:28 rivoplus volumio[3101]: info: creating directory: cgui/backend/__pycache__/ Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/routes.py Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/backend/filemanagement.py Dec 20 21:22:28 rivoplus volumio[3101]: info: extracting: cgui/main.py Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.687-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.702-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.700-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.704-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.711-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.713-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.714-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.715-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.718-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.719-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.722-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.727-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.730-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.731-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.734-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.736-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.739-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.741-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.744-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.745-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.749-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.749-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.752-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.756-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.760-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.764-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.766-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.765-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.769-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.770-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.775-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.777-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.777-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.781-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.783-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.786-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.794-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.796-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.798-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.800-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.802-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.804-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.805-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.806-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.810-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.813-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.814-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.817-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.819-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.821-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.824-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.828-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.833-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.834-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.838-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.840-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.843-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.844-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.846-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.848-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.852-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.853-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.856-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.862-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.866-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.869-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.872-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.873-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.877-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.879-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.880-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.883-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.893-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:28 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:28.896-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:35 rivoplus volumio[3101]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Dec 20 21:22:35 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:35.095-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:35 rivoplus volumio[3101]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-xi90o8co Dec 20 21:22:35 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:35.110-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:36 rivoplus qobuz-connect[3489]: 20251220 21:22:36.087 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Need to send state update Dec 20 21:22:36 rivoplus qobuz-connect[3489]: 20251220 21:22:36.087 [3489.3489] INFO QConnectMessageSender: [0xac50b9f0]: Sending StateUpdated message Dec 20 21:22:36 rivoplus qobuz-connect[3489]: 20251220 21:22:36.087 [3489.3489] INFO QwspMessageSender: [0xac50b9e0]: Sending Payload message Dec 20 21:22:36 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:36 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:22:36 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:22:36 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 21:22:36 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:22:36 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:36 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:36 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:38 rivoplus volumio[3101]: info: Collecting PyYAML (from camilladsp==1.0.0) Dec 20 21:22:38 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:38.632-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:40 rivoplus volumio[3101]: info: Downloading https://files.pythonhosted.org/packages/cd/e5/af35f7ea75cf72f2cd079c95ee16797de7cd71f29ea7c68ae5ce7be1eda0/PyYAML-6.0.1.tar.gz (125kB) Dec 20 21:22:40 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:40.225-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:41 rivoplus volumio[3101]: info: Installing build dependencies: started Dec 20 21:22:41 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:41.122-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:22:42 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:42.559-07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.17:46982 Dec 20 21:22:42 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:42.589-07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.17:46982 @ 0x2cb4b10" latency=247.777487ms platform=PLATFORM_ANDROID version=4.251125.0 Dec 20 21:22:42 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:42.591-07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.17:46982 @ 0x2cb4b10" latency=247.159412ms timeout=10s Dec 20 21:22:42 rivoplus volumio[3101]: info: Received Get System Info Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 21:22:42 rivoplus volumio[3101]: info: Discovery: Getting this device information Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 20 21:22:42 rivoplus volumio[3101]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Dec 20 21:22:42 rivoplus volumio[3101]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Dec 20 21:22:42 rivoplus volumio[3101]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" Dec 20 21:22:42 rivoplus volumio[3101]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 21:22:42 rivoplus volumio[3101]: info: Discovery: Getting this device information Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 21:22:42 rivoplus volumio[3101]: info: Received Get System Info Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 21:22:42 rivoplus volumio[3101]: info: Discovery: Getting this device information Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:42 rivoplus volumio[3101]: verbose: New Socket.io Connection to 192.168.1.21:3000 from 192.168.1.17 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 20 21:22:42 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 20 21:22:43 rivoplus volumio[3101]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.17 UA: Mozilla/5.0 (Linux; Android 16; SM-S908U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 20 21:22:43 rivoplus volumio[3101]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.17 UA: Mozilla/5.0 (Linux; Android 16; SM-S908U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 20 21:22:43 rivoplus volumio[3101]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.17 UA: Mozilla/5.0 (Linux; Android 16; SM-S908U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 20 21:22:43 rivoplus volumio[3101]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.17 UA: Mozilla/5.0 (Linux; Android 16; SM-S908U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 20 21:22:43 rivoplus volumio[3101]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.17 UA: Mozilla/5.0 (Linux; Android 16; SM-S908U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Dec 20 21:22:44 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:44.316-07:00 level=INFO msg="new address was allocated" component=conn/ble old=10 new=11 Dec 20 21:22:44 rivoplus dbus-daemon[2847]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.12" (uid=0 pid=3448 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3186 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") Dec 20 21:22:44 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:44.816-07:00 level=INFO msg="new address was allocated" component=conn/ble old=11 new=12 Dec 20 21:22:46 rivoplus qobuz-connect[3489]: 20251220 21:22:46.088 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Need to send state update Dec 20 21:22:46 rivoplus qobuz-connect[3489]: 20251220 21:22:46.088 [3489.3489] INFO QConnectMessageSender: [0xac50b9f0]: Sending StateUpdated message Dec 20 21:22:46 rivoplus qobuz-connect[3489]: 20251220 21:22:46.088 [3489.3489] INFO QwspMessageSender: [0xac50b9e0]: Sending Payload message Dec 20 21:22:46 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:46 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:22:46 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:22:46 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 21:22:46 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:22:46 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:46 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:46 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:46 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:46.603-07:00 level=INFO msg="continue navigation request" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.17:46982,00:00:00:00:00:00%0b @ 0x2cb4b10" latency=247.39952ms timeout=10s from=APP_PAGE_ROOT Dec 20 21:22:47 rivoplus sudo[20188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 21:22:47 rivoplus sudo[20188]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:22:47 rivoplus sudo[20190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 21:22:47 rivoplus sudo[20190]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:22:47 rivoplus sudo[20188]: pam_unix(sudo:session): session closed for user root Dec 20 21:22:47 rivoplus sudo[20190]: pam_unix(sudo:session): session closed for user root Dec 20 21:22:47 rivoplus volumio[3101]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.17 UA: Mozilla/5.0 (Linux; Android 16; SM-S908U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Dec 20 21:22:47 rivoplus sudo[20196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 21:22:47 rivoplus sudo[20196]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:22:47 rivoplus sudo[20198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 21:22:47 rivoplus sudo[20196]: pam_unix(sudo:session): session closed for user root Dec 20 21:22:47 rivoplus sudo[20198]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:22:47 rivoplus sudo[20198]: pam_unix(sudo:session): session closed for user root Dec 20 21:22:47 rivoplus volumio[3101]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.17 UA: Mozilla/5.0 (Linux; Android 16; SM-S908U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 21:22:47 rivoplus volumio[3101]: info: Received Get System Info Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 21:22:47 rivoplus volumio[3101]: info: Discovery: Getting this device information Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:47 rivoplus volumio[3101]: info: Listing playlists Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 20 21:22:47 rivoplus volumio[3101]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.17 UA: Mozilla/5.0 (Linux; Android 16; SM-S908U1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 21:22:47 rivoplus volumio[3101]: info: Received Get System Info Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 21:22:47 rivoplus volumio[3101]: info: Discovery: Getting this device information Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:47 rivoplus volumio[3101]: info: Listing playlists Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetQueue Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreStateMachine::getQueue Dec 20 21:22:47 rivoplus volumio[3101]: info: CorePlayQueue::getQueue Dec 20 21:22:47 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 20 21:22:49 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 20 21:22:49 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 21:22:49 rivoplus volumio[3101]: info: Received Get System Info Dec 20 21:22:49 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 21:22:49 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 21:22:49 rivoplus volumio[3101]: info: Discovery: Getting this device information Dec 20 21:22:49 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:49 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 21:22:49 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 21:22:49 rivoplus volumio[3101]: info: Received Get System Info Dec 20 21:22:49 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 21:22:49 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 21:22:49 rivoplus volumio[3101]: info: Discovery: Getting this device information Dec 20 21:22:49 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:49 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 21:22:51 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:51.266-07:00 level=INFO msg="new address was allocated" component=conn/ble old=12 new=13 Dec 20 21:22:51 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:51.767-07:00 level=INFO msg="new address was allocated" component=conn/ble old=13 new=14 Dec 20 21:22:52 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:52.898-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=11 Dec 20 21:22:53 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:53.199-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=11 Dec 20 21:22:53 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 20 21:22:53 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 20 21:22:53 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:53.499-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=11 Dec 20 21:22:53 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:53.800-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=11 Dec 20 21:22:54 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:54.101-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=11 Dec 20 21:22:54 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:54.404-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=11 Dec 20 21:22:54 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:54.705-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=11 Dec 20 21:22:55 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:55.005-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=11 Dec 20 21:22:55 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:55.306-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=11 Dec 20 21:22:55 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:55.606-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=11 Dec 20 21:22:55 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:55.907-07:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=11 Dec 20 21:22:55 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:22:55.907-07:00 level=ERROR msg="pending write exceeded maximum retries, dropping" component=conn/ble addr=11 chunks=1 index=0 Dec 20 21:22:56 rivoplus qobuz-connect[3489]: 20251220 21:22:56.090 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Need to send state update Dec 20 21:22:56 rivoplus qobuz-connect[3489]: 20251220 21:22:56.090 [3489.3489] INFO QConnectMessageSender: [0xac50b9f0]: Sending StateUpdated message Dec 20 21:22:56 rivoplus qobuz-connect[3489]: 20251220 21:22:56.090 [3489.3489] INFO QwspMessageSender: [0xac50b9e0]: Sending Payload message Dec 20 21:22:56 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:56 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:22:56 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:22:56 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 21:22:56 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:22:56 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:22:56 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:56 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:22:57 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 20 21:23:03 rivoplus qobuz-connect[3489]: 20251220 21:23:03.860 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 41]: Running audio stream Dec 20 21:23:03 rivoplus qobuz-connect[3489]: 20251220 21:23:03.860 [3489.3489] INFO UrlAudioSource: [0xac74d6b0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4952441&eid=59672924&fmt=6&profile=raw&app_id=174516466&cid=2960456&etsp=1766294356&hmac=M58xR2TkBNoqbSnlJz9RNzxi9bg Dec 20 21:23:03 rivoplus qobuz-connect[3489]: 20251220 21:23:03.860 [3489.3489] INFO ContentFetcher: [0xac6f2828]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4952441&eid=59672924&fmt=6&profile=raw&app_id=174516466&cid=2960456&etsp=1766294356&hmac=M58xR2TkBNoqbSnlJz9RNzxi9bg, offset: 0 Dec 20 21:23:03 rivoplus qobuz-connect[3489]: 20251220 21:23:03.860 [3489.3489] INFO SampleApp: [Stream 41]: New audio stream (starting from 0ms) Dec 20 21:23:03 rivoplus qobuz-connect[3489]: 20251220 21:23:03.861 [3489.3489] INFO SampleApp: [Stream 41]: Stream metadata became available: Dec 20 21:23:03 rivoplus qobuz-connect[3489]: 20251220 21:23:03.861 [3489.3489] INFO SampleApp: Title: Hotel California (Live On MTV, 1994) Dec 20 21:23:03 rivoplus qobuz-connect[3489]: 20251220 21:23:03.861 [3489.3489] INFO SampleApp: Artist: Eagles Dec 20 21:23:03 rivoplus qobuz-connect[3489]: 20251220 21:23:03.861 [3489.3489] INFO SampleApp: Album: Hell Freezes Over (Live On MTV, 1994) Dec 20 21:23:03 rivoplus qobuz-connect[3489]: 20251220 21:23:03.861 [3489.3489] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/vc/nk/ubhqe4noqnkvc_600.jpg Dec 20 21:23:03 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:04 rivoplus qobuz-connect[3489]: 20251220 21:23:04.092 [3489.3489] INFO UrlAudioSource: [0xac74d6b0]: Content info extracted Dec 20 21:23:04 rivoplus qobuz-connect[3489]: 20251220 21:23:04.092 [3489.3489] INFO UrlAudioSource: [0xac74d6b0]: Size: 49012800 Dec 20 21:23:04 rivoplus qobuz-connect[3489]: 20251220 21:23:04.093 [3489.20239] INFO AudioDecoder: [0xac5e8140]: Stream info: Dec 20 21:23:04 rivoplus qobuz-connect[3489]: 20251220 21:23:04.093 [3489.20239] INFO AudioDecoder: [0xac5e8140]: Total samples: 19052964 Dec 20 21:23:04 rivoplus qobuz-connect[3489]: 20251220 21:23:04.093 [3489.20239] INFO AudioDecoder: [0xac5e8140]: Sample rate: 44100 Dec 20 21:23:04 rivoplus qobuz-connect[3489]: 20251220 21:23:04.093 [3489.20239] INFO AudioDecoder: [0xac5e8140]: Channel count: 2 Dec 20 21:23:04 rivoplus qobuz-connect[3489]: 20251220 21:23:04.093 [3489.20239] INFO AudioDecoder: [0xac5e8140]: Bits per sample: 16 Dec 20 21:23:04 rivoplus qobuz-connect[3489]: 20251220 21:23:04.093 [3489.20239] INFO AudioDecoder: [0xac5e8140]: Duration: 432040 Dec 20 21:23:04 rivoplus qobuz-connect[3489]: 20251220 21:23:04.094 [3489.3489] INFO UrlAudioSource: [0xac74d6b0]: Audio properties have changed Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 21:23:06 rivoplus volumio[3101]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 20 21:23:06 rivoplus volumio[3101]: info: Received Get System Version Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 21:23:06 rivoplus volumio[3101]: info: Received Get System Info Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 21:23:06 rivoplus volumio[3101]: info: Discovery: Getting this device information Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 21:23:06 rivoplus qobuz-connect[3489]: 20251220 21:23:06.091 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Need to send state update Dec 20 21:23:06 rivoplus qobuz-connect[3489]: 20251220 21:23:06.092 [3489.3489] INFO QConnectMessageSender: [0xac50b9f0]: Sending StateUpdated message Dec 20 21:23:06 rivoplus qobuz-connect[3489]: 20251220 21:23:06.092 [3489.3489] INFO QwspMessageSender: [0xac50b9e0]: Sending Payload message Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:23:06 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:06 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:06 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:09 rivoplus qobuz-connect[3489]: 20251220 21:23:09.029 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 40]: Audio stream has finished Dec 20 21:23:09 rivoplus qobuz-connect[3489]: 20251220 21:23:09.030 [3489.3489] INFO SampleApp: [Stream 40]: Audio stream finished Dec 20 21:23:09 rivoplus volumio[3101]: info: Installing build dependencies: finished with status 'done' Dec 20 21:23:09 rivoplus volumio5-onboarding[3448]: time=2025-12-20T21:23:09.086-07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Dec 20 21:23:10 rivoplus qobuz-connect[3489]: 20251220 21:23:10.861 [3489.3489] INFO SampleApp: Playback is nearly finished Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.859 [3489.3489] INFO SampleApp: Playback has finished Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.859 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 40]: Disposing of audio stream Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.859 [3489.3489] INFO ContentFetcher: [0xac6eb268]: Canceling download Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.860 [3489.3489] INFO SampleApp: [Stream 40]: Audio stream has been disposed of Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.860 [3489.3489] INFO StreamingEventReporter: [0xac5009f8]: Reporting streaming ended event Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.860 [3489.3489] INFO HttpDownloader: [0xac500a50]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.860 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Need to send state update Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.861 [3489.3489] INFO QConnectMessageSender: [0xac50b9f0]: Sending StateUpdated message Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.861 [3489.3489] INFO QwspMessageSender: [0xac50b9e0]: Sending Payload message Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.862 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Need to send state update Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.862 [3489.3489] INFO QConnectMessageSender: [0xac50b9f0]: Sending StateUpdated message Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.862 [3489.3489] INFO QwspMessageSender: [0xac50b9e0]: Sending Payload message Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.862 [3489.3489] INFO QConnectMessageSender: [0xac50b9f0]: Sending FileAudioQualityChanged message Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.862 [3489.3489] INFO QwspMessageSender: [0xac50b9e0]: Sending Payload message Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.863 [3489.3489] INFO StreamingEventReporter: [0xac5009f8]: Reporting streaming started event Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::servicePushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreStateMachine::pushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioPushState Dec 20 21:23:13 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:13 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:13 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:13 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:13 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:13 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:13 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:13 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:13 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:13 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:13 rivoplus volumio[3101]: info: Signalling Playback active due to playback status change Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.996 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Received SetState message: Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.996 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Playing state: Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.996 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Playback position: Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Queue version: 8.2 Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Current track: Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Next track: TID: 49955258, QID: 26, Context UUID: 9b48b100-506f-427c-99bc-fa9bbd60d14a Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO MediaEngine: [0xac4ffea0]: Setting next track: 49955258 Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: New stream: 42 Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO HttpDownloader: [0xac835158]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b81aaaaccf685f6583d1bfa8ad645404&request_ts=1766290993&track_id=49955258 Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO HttpDownloader: [0xac834d08]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=49955258 Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO HttpDownloader: [0xac756470]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ceb34ff22e093c4fa925cd68c668e664&request_ts=1766290993&track_id=49955258 Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Received SetState message: Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Playing state: Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Playback position: Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Queue version: 8.2 Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.997 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Current track: Dec 20 21:23:13 rivoplus qobuz-connect[3489]: 20251220 21:23:13.998 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Next track: TID: 49955258, QID: 26, Context UUID: 9b48b100-506f-427c-99bc-fa9bbd60d14a Dec 20 21:23:14 rivoplus qobuz-connect[3489]: 20251220 21:23:14.283 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 42]: Metadata became available: Dec 20 21:23:14 rivoplus qobuz-connect[3489]: 20251220 21:23:14.283 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Title: Siesta del Sol (Laid Back Summer Cut) Dec 20 21:23:14 rivoplus qobuz-connect[3489]: 20251220 21:23:14.283 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Artist: In Credo Dec 20 21:23:14 rivoplus qobuz-connect[3489]: 20251220 21:23:14.283 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Album: My Private Lounge - Ibiza Chillout Feelings (Laid Back Summer Cut) Dec 20 21:23:14 rivoplus qobuz-connect[3489]: 20251220 21:23:14.283 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Album art URL: https://static.qobuz.com/images/covers/ob/nj/o32jsf5funjob_600.jpg Dec 20 21:23:14 rivoplus qobuz-connect[3489]: 20251220 21:23:14.367 [3489.3489] INFO HttpDownloader: [0xac500a50]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Dec 20 21:23:14 rivoplus qobuz-connect[3489]: 20251220 21:23:14.434 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 42]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4952441&eid=49955258&fmt=6&profile=raw&app_id=174516466&cid=2960456&etsp=1766294594&hmac=vR5cGyE7LD6jhR42_eeVX--LzP4 Dec 20 21:23:14 rivoplus qobuz-connect[3489]: 20251220 21:23:14.478 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 42]: stream information have been fetched Dec 20 21:23:16 rivoplus volumio[3101]: info: Enabling plugin fusiondsp Dec 20 21:23:16 rivoplus volumio[3101]: info: Loading plugin "fusiondsp"... Dec 20 21:23:16 rivoplus volumio[3101]: info: Preparing to generate the ALSA configuration file Dec 20 21:23:16 rivoplus volumio[3101]: info: Asound.conf file unchanged, so no further update is needed Dec 20 21:23:16 rivoplus volumio[3101]: info: Output device has changed, restarting MPD Dec 20 21:23:16 rivoplus volumio[3101]: info: Output device has changed, restarting Shairport Sync Dec 20 21:23:16 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:16 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 21:23:16 rivoplus sudo[20267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 21:23:16 rivoplus sudo[20267]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:16 rivoplus sudo[20267]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:16 rivoplus sudo[20269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 21:23:16 rivoplus sudo[20269]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 20 21:23:17 rivoplus volumio[3101]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 21:23:17 rivoplus volumio[3101]: info: QobuzConnect: setDeactiveState invoked Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:17 rivoplus volumio[3101]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State Dec 20 21:23:17 rivoplus volumio[3101]: verbose: UNSET VOLATILE: Service: qobuzconnect Dec 20 21:23:17 rivoplus volumio[3101]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Dec 20 21:23:17 rivoplus volumio[3101]: info: QobuzConnect: Received stop Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.037 [3489.3489] INFO MediaEngine: [0xac4ffea0]: Stopping playback, keeping tracks Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.037 [3489.3489] INFO AudioRenderer: [0xac5000c0]: Stopping audio renderer Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.038 [3489.3489] INFO SampleApp: Stopping playback Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.050 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Clearing all streams Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.050 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 41]: Disposing of audio stream Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.050 [3489.3489] INFO ContentFetcher: [0xac6f2828]: Canceling download Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.060 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: New stream: 43 Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.061 [3489.3489] INFO HttpDownloader: [0xac835158]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e72d7ccbe2298a6f340ef1306f88c56f&request_ts=1766290997&track_id=59672924 Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.061 [3489.3489] INFO HttpDownloader: [0xac8352a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=59672924 Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.061 [3489.3489] INFO HttpDownloader: [0xacb2cdf0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=e91134be4b8cfa9524d950bf9093a352&request_ts=1766290997&track_id=59672924 Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.061 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 43]: Running audio stream Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.062 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: New stream: 44 Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.062 [3489.3489] INFO HttpDownloader: [0xaca4a740]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=8e867df55dbcbd3e0345f659246c7264&request_ts=1766290997&track_id=49955258 Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.062 [3489.3489] INFO HttpDownloader: [0xac53f1b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=49955258 Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.062 [3489.3489] INFO HttpDownloader: [0xac536c00]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=da62eb90514dd9c889e97c39995af697&request_ts=1766290997&track_id=49955258 Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.065 [3489.3489] INFO SampleApp: Playback state changed: Stopped Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.065 [3489.3489] INFO StreamingEventReporter: [0xac5009f8]: Reporting streaming ended event Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.065 [3489.3489] INFO HttpDownloader: [0xac500a50]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.065 [3489.3489] INFO ProtocolHandler: [0xac5005e0]: Need to send state update Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.065 [3489.3489] INFO QConnectMessageSender: [0xac50b9f0]: Sending StateUpdated message Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.066 [3489.3489] INFO SampleApp: [Stream 41]: Audio stream has been disposed of Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.066 [3489.3489] INFO QwspMessageSender: [0xac50b9e0]: Sending Payload message Dec 20 21:23:17 rivoplus volumio[3101]: info: PLUGIN START: fusiondsp Dec 20 21:23:17 rivoplus sudo[20276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 20 21:23:17 rivoplus sudo[20276]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus volumio[3101]: info: Loading i18n strings for locale en Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus volumio[3101]: info: FusionDsp - mixtype--------------------- None Dec 20 21:23:17 rivoplus sudo[20276]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:17 rivoplus volumio[3101]: info: Preparing to generate the ALSA configuration file Dec 20 21:23:17 rivoplus volumio[3101]: info: Done. Dec 20 21:23:17 rivoplus systemd[1]: mpd.service: Succeeded. Dec 20 21:23:17 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.251 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 44]: Metadata became available: Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.251 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Title: Siesta del Sol (Laid Back Summer Cut) Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.252 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Artist: In Credo Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.252 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Album: My Private Lounge - Ibiza Chillout Feelings (Laid Back Summer Cut) Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.252 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Album art URL: https://static.qobuz.com/images/covers/ob/nj/o32jsf5funjob_600.jpg Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.252 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 43]: Metadata became available: Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.252 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Title: Hotel California (Live On MTV, 1994) Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.252 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Artist: Eagles Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.252 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Album: Hell Freezes Over (Live On MTV, 1994) Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.252 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: Album art URL: https://static.qobuz.com/images/covers/vc/nk/ubhqe4noqnkvc_600.jpg Dec 20 21:23:17 rivoplus systemd[1]: Starting Music Player Daemon... Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.276 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 44]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4952441&eid=49955258&fmt=6&profile=raw&app_id=174516466&cid=2960456&etsp=1766294597&hmac=AsuWLY95QMmpnJcaBU_nyT13FgQ Dec 20 21:23:17 rivoplus sudo[20280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 20 21:23:17 rivoplus sudo[20280]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus sudo[20280]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:17 rivoplus volumio[3101]: info: QobuzConnect: playback_stop received, deferred solved Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:17 rivoplus volumio[3101]: info: CorePlayQueue::getTrack 0 Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:17 rivoplus volumio[3101]: info: CorePlayQueue::getTrack 0 Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 20 21:23:17 rivoplus volumio[3101]: info: MPD Permissions set Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.367 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 43]: stream information have been fetched Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus sudo[20295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 20 21:23:17 rivoplus sudo[20295]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.396 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 44]: stream information have been fetched Dec 20 21:23:17 rivoplus sudo[20295]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.421 [3489.3489] INFO AudioStreamManager: [0xac4fff88]: [Stream 43]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4952441&eid=59672924&fmt=6&profile=raw&app_id=174516466&cid=2960456&etsp=1766294597&hmac=lMWhSv7se3wLlU_UDVb4_9EV9QI Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.422 [3489.3489] INFO UrlAudioSource: [0xac5044f8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4952441&eid=59672924&fmt=6&profile=raw&app_id=174516466&cid=2960456&etsp=1766294597&hmac=lMWhSv7se3wLlU_UDVb4_9EV9QI Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.422 [3489.3489] INFO ContentFetcher: [0xac6acc78]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4952441&eid=59672924&fmt=6&profile=raw&app_id=174516466&cid=2960456&etsp=1766294597&hmac=lMWhSv7se3wLlU_UDVb4_9EV9QI, offset: 0 Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.422 [3489.3489] INFO SampleApp: [Stream 43]: New audio stream (starting from 0ms) Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.423 [3489.3489] INFO SampleApp: [Stream 43]: Stream metadata became available: Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.423 [3489.3489] INFO SampleApp: Title: Hotel California (Live On MTV, 1994) Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.423 [3489.3489] INFO SampleApp: Artist: Eagles Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.423 [3489.3489] INFO SampleApp: Album: Hell Freezes Over (Live On MTV, 1994) Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.423 [3489.3489] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/vc/nk/ubhqe4noqnkvc_600.jpg Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 20 21:23:17 rivoplus sudo[20300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 20 21:23:17 rivoplus sudo[20281]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 21:23:17 rivoplus sudo[20300]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus sudo[20281]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus sudo[20281]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.468 [3489.3489] INFO ContentFetcher: [0xac6acc78]: Canceling download Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.475 [3489.3489] INFO CloudClient: [0xac500478]: Disconnecting from the cloud Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.475 [3489.3489] INFO CloudClient: [0xac500478]: Connection closed Dec 20 21:23:17 rivoplus volumio[3101]: info: Asound.conf file unchanged, so no further update is needed Dec 20 21:23:17 rivoplus volumio[3101]: info: Output device has changed, restarting MPD Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.495 [3489.3489] INFO SampleApp: Stopping Local configuration server Dec 20 21:23:17 rivoplus qobuz-connect[3489]: 20251220 21:23:17.516 [3489.3489] INFO SampleApp: shat down connection on UNIX socket Dec 20 21:23:17 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 20 21:23:17 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 20 21:23:17 rivoplus sudo[20310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 21:23:17 rivoplus sudo[20310]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus volumio[3101]: info: Output device has changed, restarting Shairport Sync Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 21:23:17 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 20 21:23:17 rivoplus sudo[20310]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:17 rivoplus sudo[20300]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:17 rivoplus sudo[20313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 21:23:17 rivoplus sudo[20313]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 20 21:23:17 rivoplus systemd[1]: mpd.service: Succeeded. Dec 20 21:23:17 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 20 21:23:17 rivoplus volumio[3101]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 21:23:17 rivoplus volumio[3101]: info: QobuzConnect: setDeactiveState invoked Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:17 rivoplus volumio[3101]: info: CorePlayQueue::getTrack 0 Dec 20 21:23:17 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:17 rivoplus systemd[1]: Starting Music Player Daemon... Dec 20 21:23:17 rivoplus sudo[20324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 20 21:23:17 rivoplus sudo[20324]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus sudo[20324]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:17 rivoplus sudo[20330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 20 21:23:17 rivoplus sudo[20330]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus sudo[20330]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:17 rivoplus sudo[20322]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 21:23:17 rivoplus sudo[20322]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus sudo[20322]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:17 rivoplus sudo[20337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 20 21:23:17 rivoplus sudo[20337]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus sudo[20337]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:17 rivoplus sudo[20340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 20 21:23:17 rivoplus sudo[20340]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:17 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 20 21:23:17 rivoplus systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Dec 20 21:23:17 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 20 21:23:17 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 20 21:23:17 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 20 21:23:18 rivoplus sudo[20340]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:18 rivoplus volumio[3101]: info: CorePlayQueue::getTrack 0 Dec 20 21:23:18 rivoplus volumio[3101]: info: MPD Permissions set Dec 20 21:23:18 rivoplus volumio[3101]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 20 21:23:18 rivoplus volumio[3101]: info: QobuzConnect: setDeactiveState invoked Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:18 rivoplus volumio[3101]: info: CorePlayQueue::getTrack 0 Dec 20 21:23:18 rivoplus volumio[3101]: info: Starting Shairport Sync Dec 20 21:23:18 rivoplus sudo[20348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 21:23:18 rivoplus volumio[3101]: info: Executing endpoint qc_getconfig Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 20 21:23:18 rivoplus sudo[20348]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:18 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 20 21:23:18 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 20 21:23:18 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 20 21:23:18 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 21:23:18 rivoplus sudo[20348]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:18 rivoplus volumio[3101]: info: Executing endpoint qc_getconfig Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 20 21:23:18 rivoplus qobuz-connect[20343]: 20251220 21:23:18.424 [20343.20343] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 20 21:23:18 rivoplus volumio[3101]: info: Shairport-Sync Started Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:18 rivoplus qobuz-connect[20343]: 20251220 21:23:18.433 [20343.20343] INFO VolumeManager: [0xab6b0308]: Setting new playback volume: 75 Dec 20 21:23:18 rivoplus qobuz-connect[20343]: 20251220 21:23:18.433 [20343.20343] INFO VolumeManager: [0xab6b0308]: Setting new mute state: 0 Dec 20 21:23:18 rivoplus qobuz-connect[20343]: 20251220 21:23:18.433 [20343.20343] INFO QobuzConnect: [0xab6b0cd8]: Client initialized! Dec 20 21:23:18 rivoplus qobuz-connect[20343]: 20251220 21:23:18.433 [20343.20343] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:18 rivoplus qobuz-connect[20343]: 20251220 21:23:18.464 [20343.20343] INFO LocalConfigManager: [0xab6afbe8]: Starting Local Configuration server Dec 20 21:23:18 rivoplus qobuz-connect[20343]: 20251220 21:23:18.464 [20343.20343] INFO SampleApp: Starting Local configuration server Dec 20 21:23:18 rivoplus qobuz-connect[20343]: 20251220 21:23:18.465 [20343.20343] INFO SampleApp: Connected to UNIX socket client 0xab6a5ed8 Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:18 rivoplus volumio[3101]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 20 21:23:18 rivoplus volumio[3101]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 20 21:23:18 rivoplus volumio[3101]: info: Starting Shairport Sync Dec 20 21:23:18 rivoplus qobuz-connect[20343]: 20251220 21:23:18.574 [20343.20343] INFO SampleApp: Playback volume changed: 75 Dec 20 21:23:18 rivoplus volumio[3101]: info: CoreCommandRouter::volumioGetState Dec 20 21:23:18 rivoplus volumio[3101]: info: CorePlayQueue::getTrack 0 Dec 20 21:23:18 rivoplus sudo[20365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 21:23:18 rivoplus sudo[20365]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:18 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 20 21:23:18 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 20 21:23:18 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 20 21:23:18 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 21:23:18 rivoplus sudo[20365]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:18 rivoplus volumio[3101]: info: Shairport-Sync Started Dec 20 21:23:19 rivoplus volumio[3101]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Dec 20 21:23:19 rivoplus volumio[3101]: info: camilladsp service started and running in background, instance 1 Dec 20 21:23:19 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 21:23:19 rivoplus volumio[3101]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 20 21:23:19 rivoplus volumio[3101]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 20 21:23:19 rivoplus volumio[3101]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 20 21:23:19 rivoplus volumio[3101]: info: FusionDsp loaded Dec 20 21:23:19 rivoplus volumio[3101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 21:23:19 rivoplus sudo[20381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 20 21:23:19 rivoplus sudo[20381]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 21:23:19 rivoplus volumio[3101]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 20 21:23:19 rivoplus volumio[3101]: info: Adding Signal Path Element [object Object] Dec 20 21:23:19 rivoplus volumio[3101]: info: Adding fusiondspeq DSP Signal Path Element Dec 20 21:23:19 rivoplus volumio[3101]: info: FusionDsp - ---- installed callbackRead Dec 20 21:23:19 rivoplus volumio[3101]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 21:23:19 rivoplus volumio[3101]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Dec 20 21:23:19 rivoplus volumio[3101]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Dec 20 21:23:19 rivoplus volumio[3101]: at onErrorNT (internal/child_process.js:472:16) Dec 20 21:23:19 rivoplus volumio[3101]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Dec 20 21:23:19 rivoplus volumio[3101]: errno: -2, Dec 20 21:23:19 rivoplus volumio[3101]: code: 'ENOENT', Dec 20 21:23:19 rivoplus volumio[3101]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Dec 20 21:23:19 rivoplus volumio[3101]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Dec 20 21:23:19 rivoplus volumio[3101]: spawnargs: [ Dec 20 21:23:19 rivoplus volumio[3101]: '-p', Dec 20 21:23:19 rivoplus volumio[3101]: 9876, Dec 20 21:23:19 rivoplus volumio[3101]: '-o', Dec 20 21:23:19 rivoplus volumio[3101]: '/tmp/camilladsp.log', Dec 20 21:23:19 rivoplus volumio[3101]: '-l', Dec 20 21:23:19 rivoplus volumio[3101]: 'warn', Dec 20 21:23:19 rivoplus volumio[3101]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Dec 20 21:23:19 rivoplus volumio[3101]: ] Dec 20 21:23:19 rivoplus volumio[3101]: } Dec 20 21:23:19 rivoplus volumio[3101]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 21:23:19 rivoplus systemd[1]: Started FusionDsp Daemon. Dec 20 21:23:19 rivoplus sudo[20381]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:20 rivoplus mpd[20341]: Dec 20 21:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 21:23:20 rivoplus systemd[1]: Started Music Player Daemon. Dec 20 21:23:20 rivoplus sudo[20313]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:20 rivoplus sudo[20269]: pam_unix(sudo:session): session closed for user root Dec 20 21:23:20 rivoplus sudo[20412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-20 21:22 Dec 20 21:23:20 rivoplus sudo[20412]: 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"