-- Logs begin at Wed 2026-01-14 13:50:34 ACDT, end at Wed 2026-01-14 14:01:30 ACDT. -- Jan 14 14:00:00 rivoplus kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 Jan 14 14:00:00 rivoplus kernel: hdmitx: video: Sink is HDMI device Jan 14 14:00:00 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:00:00 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:00:00 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:00:00 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:00:00 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:00:00 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:00:00 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:00:00 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:00:00 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:00:00 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:00:00 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:00:00 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:00:02 rivoplus volumio[3317]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Jan 14 14:00:02 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:02.557+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:02 rivoplus volumio[3317]: info: Collecting jsonschema (from camilladsp-plot==1.0.2) Jan 14 14:00:02 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:02.649+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:03 rivoplus volumio[3317]: info: Downloading https://files.pythonhosted.org/packages/c1/97/c698bd9350f307daad79dd740806e1a59becd693bd11443a0f531e3229b3/jsonschema-4.17.3-py3-none-any.whl (90kB) Jan 14 14:00:03 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:03.603+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:04 rivoplus volumio[3317]: info: Collecting pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 (from jsonschema->camilladsp-plot==1.0.2) Jan 14 14:00:04 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:04.096+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:04 rivoplus volumio[3317]: info: Downloading https://files.pythonhosted.org/packages/64/de/375aa14daaee107f987da76ca32f7a907fea00fa8b8afb67dc09bec0de91/pyrsistent-0.19.3-py3-none-any.whl (57kB) Jan 14 14:00:04 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:04.763+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:05 rivoplus volumio[3317]: info: Collecting typing-extensions; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Jan 14 14:00:05 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:05.020+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:05 rivoplus volumio[3317]: info: Downloading https://files.pythonhosted.org/packages/ec/6b/63cc3df74987c36fe26157ee12e09e8f9db4de771e0f3404263117e75b95/typing_extensions-4.7.1-py3-none-any.whl Jan 14 14:00:05 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:05.404+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:05 rivoplus volumio[3317]: info: Collecting pkgutil-resolve-name>=1.3.10; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Jan 14 14:00:05 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:05.648+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:05 rivoplus volumio[3317]: info: Downloading https://files.pythonhosted.org/packages/c9/5c/3d4882ba113fd55bdba9326c1e4c62a15e674a2501de4869e6bd6301f87e/pkgutil_resolve_name-1.3.10-py3-none-any.whl Jan 14 14:00:05 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:05.745+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:05 rivoplus volumio[3317]: info: Collecting importlib-metadata; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Jan 14 14:00:05 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:05.944+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:06 rivoplus volumio[3317]: info: Downloading https://files.pythonhosted.org/packages/ff/94/64287b38c7de4c90683630338cf28f129decbba0a44f0c6db35a873c73c4/importlib_metadata-6.7.0-py3-none-any.whl Jan 14 14:00:06 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:06.896+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:07 rivoplus volumio[3317]: info: Collecting importlib-resources>=1.4.0; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Jan 14 14:00:07 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:07.394+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:07 rivoplus volumio[3317]: info: Downloading https://files.pythonhosted.org/packages/38/71/c13ea695a4393639830bf96baea956538ba7a9d06fcce7cef10bfff20f72/importlib_resources-5.12.0-py3-none-any.whl Jan 14 14:00:08 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:08.002+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:08 rivoplus volumio[3317]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Jan 14 14:00:08 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:08.311+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:08 rivoplus volumio[3317]: info: Collecting zipp>=0.5 (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) Jan 14 14:00:08 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:08.453+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:08 rivoplus volumio[3317]: info: Downloading https://files.pythonhosted.org/packages/5b/fa/c9e82bbe1af6266adf08afb563905eb87cab83fde00a0a08963510621047/zipp-3.15.0-py3-none-any.whl Jan 14 14:00:08 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:08.877+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:09 rivoplus volumio[3317]: info: Building wheels for collected packages: camilladsp-plot Jan 14 14:00:09 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:09.168+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:09 rivoplus volumio[3317]: info: Running setup.py bdist_wheel for camilladsp-plot: started Jan 14 14:00:09 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:09.214+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:11 rivoplus volumio[3317]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Jan 14 14:00:11 rivoplus volumio[3317]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-2dwv0apm/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Jan 14 14:00:11 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:11.113+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:11 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:11.136+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:11 rivoplus volumio[3317]: info: Successfully built camilladsp-plot Jan 14 14:00:11 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:11.194+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:12 rivoplus volumio[3317]: info: Installing collected packages: pyrsistent, typing-extensions, pkgutil-resolve-name, zipp, importlib-metadata, importlib-resources, jsonschema, camilladsp-plot Jan 14 14:00:12 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:12.155+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:13 rivoplus volumio[3317]: info: Successfully installed camilladsp-plot-1.0.2 importlib-metadata-6.7.0 importlib-resources-5.12.0 jsonschema-4.17.3 pkgutil-resolve-name-1.3.10 pyrsistent-0.19.3 typing-extensions-4.7.1 zipp-3.15.0 Jan 14 14:00:13 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:13.547+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:13 rivoplus kernel: hdmitx: system: irq 4 0 Jan 14 14:00:13 rivoplus kernel: hdmitx: system: plugout Jan 14 14:00:13 rivoplus kernel: hdmitx: system: update physcial size: 16 9 Jan 14 14:00:13 rivoplus volumio[3317]: info: copying hw detection script Jan 14 14:00:13 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:13.899+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:13 rivoplus volumio[3317]: info: Detected cpu architecture as aarch64 Jan 14 14:00:13 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:13.954+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:14 rivoplus kernel: hdmitx: system: irq 2 0 Jan 14 14:00:15 rivoplus kernel: hdmitx: system: plugin Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: EDID Parser: Jan 14 14:00:15 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:15 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:15 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:15 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:15 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:15 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:15 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: change preferred_mode from 0 to 97 Jan 14 14:00:15 rivoplus kernel: hdmitx: hdmitx: get PMT vic: 97 Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: find IEEEOUT Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: update rx hdr info d at edid parsing Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: blk0 raw data Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: 00ffffffffffff004dd905b901010101011f0103809051780a0dc9a057479827 12484c2108008180a9c0714fb300010101010101010108e80030f2705a80b058 8a009f295300001e023a801871382d40582c45009f295300001e000000fc0053 4f4e5920545620202a33300a000000fd0017790e883c000a202020202020016d Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: blk1 raw data Jan 14 14:00:15 rivoplus kernel: hdmitx: edid: 020367f05861605d5e5f621f101405130420223c3e1203110265663f402f0d7f 071507503d07bc570601670403830f00006e030c002000b8442b008001020304 67d85dc401788003eb0146d000481b9288627697e200cbe305df01e40f030030 e6060d01aeae09011d007251d01e206e2855009f295300001e00000000000051 Jan 14 14:00:15 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:00:15 rivoplus kernel: hdmitx: system: get current mode: 1080p60hz Jan 14 14:00:15 rivoplus kernel: hdmitx: system: update rx hdr info d Jan 14 14:00:15 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:00:15 rivoplus kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 Jan 14 14:00:15 rivoplus kernel: hdmitx: hw: div40: 0 Jan 14 14:00:15 rivoplus kernel: hdmitx: frac_rate = 1 Jan 14 14:00:15 rivoplus kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 Jan 14 14:00:15 rivoplus kernel: hdmitx: HPLLv1: 0xdb3a04f7 Jan 14 14:00:15 rivoplus kernel: hdmitx: config HPLL done Jan 14 14:00:15 rivoplus kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 Jan 14 14:00:15 rivoplus kernel: hdmitx: j = 4 vid_clk_div = 1 Jan 14 14:00:15 rivoplus kernel: hdmitx: set enc for VIC: 16 Jan 14 14:00:15 rivoplus volumio[3317]: info: camilladsp Jan 14 14:00:15 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:15.510+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:15 rivoplus kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 Jan 14 14:00:15 rivoplus kernel: hdmitx: video: Sink is HDMI device Jan 14 14:00:15 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:00:15 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:00:15 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:00:15 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:00:15 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:00:15 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:00:15 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:00:15 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:00:15 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:00:15 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:00:15 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:00:15 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:00:15 rivoplus sudo[7215]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Jan 14 14:00:15 rivoplus sudo[7215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:15 rivoplus sudo[7215]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:15 rivoplus sudo[7217]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Jan 14 14:00:16 rivoplus sudo[7217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:16 rivoplus sudo[7217]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:16 rivoplus sudo[7219]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Jan 14 14:00:16 rivoplus sudo[7219]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:16 rivoplus volumio[3317]: info: Reading package lists... Jan 14 14:00:16 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:16.238+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:17 rivoplus volumio[3317]: info: Building dependency tree... Jan 14 14:00:17 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:17.079+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:17 rivoplus volumio[3317]: info: Reading state information... Jan 14 14:00:17 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:17.127+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:18 rivoplus volumio[3317]: info: The following NEW packages will be installed: Jan 14 14:00:18 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:18.671+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:18 rivoplus volumio[3317]: info: drc Jan 14 14:00:18 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:18.717+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:19 rivoplus volumio[3317]: info: 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. Jan 14 14:00:19 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:19.541+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:19 rivoplus volumio[3317]: info: Need to get 118 kB of archives. Jan 14 14:00:19 rivoplus volumio[3317]: info: After this operation, 471 kB of additional disk space will be used. Jan 14 14:00:19 rivoplus volumio[3317]: info: Get:1 http://archive.debian.org/debian buster/main armhf drc armhf 3.2.2~dfsg0-2 [118 kB] Jan 14 14:00:19 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:19.576+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:19 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:19.578+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:19 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:19.583+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:21 rivoplus volumio[3317]: info: Fetched 118 kB in 1s (113 kB/s) Jan 14 14:00:21 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:21.273+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:21 rivoplus volumio[3317]: info: Selecting previously unselected package drc. Jan 14 14:00:21 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:21.357+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:21 rivoplus volumio[3317]: [620B blob data] Jan 14 14:00:21 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:21.971+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:21 rivoplus volumio[3317]: info: Preparing to unpack .../drc_3.2.2~dfsg0-2_armhf.deb ... Jan 14 14:00:21 rivoplus volumio[3317]: info: Unpacking drc (3.2.2~dfsg0-2) ... Jan 14 14:00:21 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:21.990+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:22 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:22.000+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:22 rivoplus volumio[3317]: info: Setting up drc (3.2.2~dfsg0-2) ... Jan 14 14:00:22 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:22.324+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:22 rivoplus volumio[3317]: info: Processing triggers for man-db (2.8.5-2) ... Jan 14 14:00:22 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:22.352+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:23 rivoplus sudo[7219]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:23 rivoplus volumio[3317]: Plugin install end detected on script Jan 14 14:00:23 rivoplus sudo[5226]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:23 rivoplus volumio[3317]: info: Install script completed Jan 14 14:00:23 rivoplus volumio[3317]: info: Adding reference to registry Jan 14 14:00:23 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:23.355+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:23 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:23.372+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:23 rivoplus volumio[3317]: info: Done installing plugin. Jan 14 14:00:23 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 14 14:00:23 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 14 14:00:23 rivoplus volumio[3317]: info: Folder /tmp/plugins removed Jan 14 14:00:23 rivoplus volumio[3317]: info: Folder /tmp/downloaded_plugin.zip removed Jan 14 14:00:23 rivoplus volumio[3317]: info: Folder /data/temp removed Jan 14 14:00:27 rivoplus volumio[3317]: info: Enabling plugin fusiondsp Jan 14 14:00:27 rivoplus volumio[3317]: info: Loading plugin "fusiondsp"... Jan 14 14:00:28 rivoplus volumio[3317]: info: Preparing to generate the ALSA configuration file Jan 14 14:00:28 rivoplus volumio[3317]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 14 14:00:28 rivoplus volumio[3317]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 14 14:00:28 rivoplus volumio[3317]: info: Reading ALSA contributions from plugins. Jan 14 14:00:28 rivoplus volumio[3317]: info: Asound.conf file written Jan 14 14:00:28 rivoplus sudo[7297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 14 14:00:28 rivoplus sudo[7297]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:28 rivoplus sudo[7297]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:28 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Jan 14 14:00:28 rivoplus kernel: Err: can not set rate to 4278967296! Jan 14 14:00:28 rivoplus kernel: Range[3920000 - 500000000] Jan 14 14:00:28 rivoplus kernel: out of value, fixed it Jan 14 14:00:28 rivoplus kernel: id=0 set inskew=0 Jan 14 14:00:28 rivoplus volumio[3317]: No protocol specified Jan 14 14:00:28 rivoplus volumio[3317]: xcb_connection_has_error() returned true Jan 14 14:00:28 rivoplus volumio[3317]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Jan 14 14:00:28 rivoplus volumio[3317]: Hardware is initialized using a generic method Jan 14 14:00:28 rivoplus volumio[3317]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jan 14 14:00:28 rivoplus volumio[3317]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jan 14 14:00:28 rivoplus volumio[3317]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jan 14 14:00:28 rivoplus volumio[3317]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jan 14 14:00:28 rivoplus volumio[3317]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Jan 14 14:00:28 rivoplus volumio[3317]: info: Output device has changed, restarting MPD Jan 14 14:00:28 rivoplus sudo[7302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 14:00:28 rivoplus sudo[7302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:28 rivoplus volumio[3317]: info: Output device has changed, restarting Shairport Sync Jan 14 14:00:28 rivoplus sudo[7302]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:00:28 rivoplus sudo[7305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 14:00:28 rivoplus sudo[7305]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:28 rivoplus systemd[1]: Stopping Music Player Daemon... Jan 14 14:00:28 rivoplus volumio[3317]: No protocol specified Jan 14 14:00:28 rivoplus volumio[3317]: xcb_connection_has_error() returned true Jan 14 14:00:28 rivoplus volumio[3317]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 14:00:28 rivoplus volumio[3317]: No protocol specified Jan 14 14:00:28 rivoplus volumio[3317]: xcb_connection_has_error() returned true Jan 14 14:00:28 rivoplus volumio[3317]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus volumio[3317]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::volumioGetState Jan 14 14:00:28 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:28 rivoplus volumio[3317]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus vtcs[3997]: [2026-01-14 14:00:28.563] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 14 14:00:28 rivoplus vtcs[3997]: [2026-01-14 14:00:28.564] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Jan 14 14:00:28 rivoplus vtcs[3997]: [2026-01-14 14:00:28.564] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jan 14 14:00:28 rivoplus volumio[3317]: info: PLUGIN START: fusiondsp Jan 14 14:00:28 rivoplus volumio[3317]: info: Loading i18n strings for locale en Jan 14 14:00:28 rivoplus sudo[7313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 14 14:00:28 rivoplus sudo[7313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus volumio[3317]: info: FusionDsp - mixtype--------------------- None Jan 14 14:00:28 rivoplus volumio[3317]: info: Preparing to generate the ALSA configuration file Jan 14 14:00:28 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Jan 14 14:00:28 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 14 14:00:28 rivoplus systemd[1]: vtcs.service: Succeeded. Jan 14 14:00:28 rivoplus volumio[3317]: info: Done. Jan 14 14:00:28 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Jan 14 14:00:28 rivoplus systemd[1]: mpd.service: Succeeded. Jan 14 14:00:28 rivoplus systemd[1]: Stopped Music Player Daemon. Jan 14 14:00:28 rivoplus sudo[7313]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:28 rivoplus sudo[7317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 14 14:00:28 rivoplus sudo[7317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:28 rivoplus systemd[1]: Starting Music Player Daemon... Jan 14 14:00:28 rivoplus volumio[3317]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 14 14:00:28 rivoplus volumio[3317]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 14 14:00:28 rivoplus volumio[3317]: info: Reading ALSA contributions from plugins. Jan 14 14:00:28 rivoplus sudo[7317]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:28 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:28 rivoplus sudo[7332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 14 14:00:28 rivoplus sudo[7332]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:28 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:28 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:00:28 rivoplus sudo[7332]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:28 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:00:28 rivoplus sudo[7335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 14 14:00:28 rivoplus sudo[7335]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:28 rivoplus volumio[3317]: info: MPD Permissions set Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:28 rivoplus qobuz-connect[3876]: 20260114 14:00:28.893 [3876.3876] INFO SampleApp: Stopping Local configuration server Jan 14 14:00:28 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 14 14:00:28 rivoplus sudo[7323]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 14:00:28 rivoplus sudo[7323]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:28 rivoplus qobuz-connect[3876]: 20260114 14:00:28.903 [3876.3876] INFO SampleApp: shat down connection on UNIX socket Jan 14 14:00:28 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jan 14 14:00:28 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 14 14:00:28 rivoplus sudo[7323]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:28 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jan 14 14:00:28 rivoplus sudo[7335]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 14 14:00:28 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:29 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:29 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:00:29 rivoplus volumio[3317]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 14 14:00:29 rivoplus volumio[3317]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::volumioGetState Jan 14 14:00:29 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 14:00:29 rivoplus volumio[3317]: info: Not Reporting Auto name since its the default one Jan 14 14:00:29 rivoplus volumio[3317]: info: Executing endpoint qc_getconfig Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 14 14:00:29 rivoplus qobuz-connect[7343]: 20260114 14:00:29.643 [7343.7343] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 14 14:00:29 rivoplus volumio[3317]: info: Starting Shairport Sync Jan 14 14:00:29 rivoplus qobuz-connect[7343]: 20260114 14:00:29.650 [7343.7343] INFO VolumeManager: [0xacdae7d8]: Setting new playback volume: 75 Jan 14 14:00:29 rivoplus qobuz-connect[7343]: 20260114 14:00:29.650 [7343.7343] INFO VolumeManager: [0xacdae7d8]: Setting new mute state: 0 Jan 14 14:00:29 rivoplus qobuz-connect[7343]: 20260114 14:00:29.650 [7343.7343] INFO QobuzConnect: [0xacdaf1a8]: Client initialized! Jan 14 14:00:29 rivoplus qobuz-connect[7343]: 20260114 14:00:29.650 [7343.7343] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 14 14:00:29 rivoplus qobuz-connect[7343]: 20260114 14:00:29.667 [7343.7343] INFO LocalConfigManager: [0xacdae0b8]: Starting Local Configuration server Jan 14 14:00:29 rivoplus qobuz-connect[7343]: 20260114 14:00:29.667 [7343.7343] INFO SampleApp: Starting Local configuration server Jan 14 14:00:29 rivoplus qobuz-connect[7343]: 20260114 14:00:29.668 [7343.7343] INFO SampleApp: Connected to UNIX socket client 0xacda3ed8 Jan 14 14:00:29 rivoplus sudo[7359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 14 14:00:29 rivoplus sudo[7359]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:29 rivoplus volumio[3317]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 14 14:00:29 rivoplus volumio[3317]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 14 14:00:29 rivoplus volumio[3317]: info: Asound.conf file unchanged, so no further update is needed Jan 14 14:00:29 rivoplus volumio[3317]: info: Output device has changed, restarting MPD Jan 14 14:00:29 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 14 14:00:29 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jan 14 14:00:29 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 14 14:00:29 rivoplus qobuz-connect[7343]: 20260114 14:00:29.792 [7343.7343] INFO SampleApp: Playback volume changed: 75 Jan 14 14:00:29 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 14 14:00:29 rivoplus sudo[7359]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:29 rivoplus sudo[7362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 14 14:00:29 rivoplus sudo[7362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:29 rivoplus volumio[3317]: info: Output device has changed, restarting Shairport Sync Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:00:29 rivoplus sudo[7362]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:29 rivoplus sudo[7366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 14 14:00:29 rivoplus sudo[7366]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:29 rivoplus volumio[3317]: No protocol specified Jan 14 14:00:29 rivoplus volumio[3317]: xcb_connection_has_error() returned true Jan 14 14:00:29 rivoplus volumio[3317]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 14 14:00:29 rivoplus volumio[3317]: No protocol specified Jan 14 14:00:29 rivoplus volumio[3317]: xcb_connection_has_error() returned true Jan 14 14:00:29 rivoplus volumio[3317]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:29 rivoplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 14 14:00:29 rivoplus systemd[1]: mpd.service: Succeeded. Jan 14 14:00:29 rivoplus systemd[1]: Stopped Music Player Daemon. Jan 14 14:00:29 rivoplus systemd[1]: Starting Music Player Daemon... Jan 14 14:00:29 rivoplus volumio[3317]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::volumioGetState Jan 14 14:00:29 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:29 rivoplus volumio[3317]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jan 14 14:00:29 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:30 rivoplus sudo[7378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 14 14:00:30 rivoplus sudo[7378]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:30 rivoplus sudo[7378]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:30 rivoplus sudo[7392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 14 14:00:30 rivoplus sudo[7392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:30 rivoplus sudo[7375]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 14 14:00:30 rivoplus sudo[7375]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:30 rivoplus sudo[7392]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:30 rivoplus sudo[7375]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 14 14:00:30 rivoplus sudo[7409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 14 14:00:30 rivoplus sudo[7409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:30 rivoplus sudo[7409]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:30 rivoplus sudo[7414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 14 14:00:30 rivoplus sudo[7414]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:30 rivoplus volumio[3317]: No protocol specified Jan 14 14:00:30 rivoplus volumio[3317]: xcb_connection_has_error() returned true Jan 14 14:00:30 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 14 14:00:30 rivoplus qobuz-connect[7343]: 20260114 14:00:30.285 [7343.7343] INFO SampleApp: Stopping Local configuration server Jan 14 14:00:30 rivoplus qobuz-connect[7343]: 20260114 14:00:30.295 [7343.7343] INFO SampleApp: shat down connection on UNIX socket Jan 14 14:00:30 rivoplus volumio[3317]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jan 14 14:00:30 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Jan 14 14:00:30 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 14 14:00:30 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:30.307+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:30 rivoplus sudo[7417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 14 14:00:30 rivoplus sudo[7417]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:30 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Jan 14 14:00:30 rivoplus sudo[7414]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:30 rivoplus sudo[7417]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:30 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:30 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:00:30 rivoplus sudo[7421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 14 14:00:30 rivoplus sudo[7421]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:30 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:00:30 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:30 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:00:30 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:30 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:30 rivoplus systemd[1]: Stopping RAAT DAEMON... Jan 14 14:00:30 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:00:30 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 14 14:00:30 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jan 14 14:00:30 rivoplus systemd[1]: Stopped RAAT DAEMON. Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::volumioGetState Jan 14 14:00:30 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:30 rivoplus volumio[3317]: info: MPD Permissions set Jan 14 14:00:30 rivoplus volumio[3317]: info: Shairport-Sync Started Jan 14 14:00:30 rivoplus systemd[1]: Started RAAT DAEMON. Jan 14 14:00:30 rivoplus sudo[7421]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 14:00:30 rivoplus volumio[3317]: info: Not Reporting Auto name since its the default one Jan 14 14:00:30 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:30 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:00:30 rivoplus volumio[3317]: info: Raat Daemon started successfully Jan 14 14:00:30 rivoplus volumio[3317]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 14 14:00:30 rivoplus volumio[3317]: info: QobuzConnect: setDeactiveState invoked Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::volumioGetState Jan 14 14:00:30 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:30 rivoplus volumio[3317]: info: Executing endpoint qc_getconfig Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 14 14:00:30 rivoplus qobuz-connect[7418]: 20260114 14:00:30.675 [7418.7418] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 14 14:00:30 rivoplus qobuz-connect[7418]: 20260114 14:00:30.682 [7418.7418] INFO VolumeManager: [0xab1ac7d8]: Setting new playback volume: 75 Jan 14 14:00:30 rivoplus qobuz-connect[7418]: 20260114 14:00:30.682 [7418.7418] INFO VolumeManager: [0xab1ac7d8]: Setting new mute state: 0 Jan 14 14:00:30 rivoplus qobuz-connect[7418]: 20260114 14:00:30.682 [7418.7418] INFO QobuzConnect: [0xab1ad1a8]: Client initialized! Jan 14 14:00:30 rivoplus qobuz-connect[7418]: 20260114 14:00:30.682 [7418.7418] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Jan 14 14:00:30 rivoplus qobuz-connect[7418]: 20260114 14:00:30.696 [7418.7418] INFO LocalConfigManager: [0xab1ac0b8]: Starting Local Configuration server Jan 14 14:00:30 rivoplus qobuz-connect[7418]: 20260114 14:00:30.696 [7418.7418] INFO SampleApp: Starting Local configuration server Jan 14 14:00:30 rivoplus qobuz-connect[7418]: 20260114 14:00:30.697 [7418.7418] INFO SampleApp: Connected to UNIX socket client 0xab1a1ed8 Jan 14 14:00:30 rivoplus volumio[3317]: info: camilladsp spawned new process with pid 7451, instance 1, run: true Jan 14 14:00:30 rivoplus volumio[3317]: info: camilladsp service started and running in background, instance 1 Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:30 rivoplus qobuz-connect[7418]: 20260114 14:00:30.824 [7418.7418] INFO SampleApp: Playback volume changed: 75 Jan 14 14:00:30 rivoplus volumio[3317]: No protocol specified Jan 14 14:00:30 rivoplus volumio[3317]: xcb_connection_has_error() returned true Jan 14 14:00:30 rivoplus volumio[3317]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Jan 14 14:00:30 rivoplus volumio[3317]: info: FusionDsp loaded Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 14:00:30 rivoplus sudo[7458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 14 14:00:30 rivoplus sudo[7458]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:30 rivoplus volumio[3317]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 14 14:00:30 rivoplus volumio[3317]: info: Adding Signal Path Element [object Object] Jan 14 14:00:30 rivoplus volumio[3317]: info: Adding fusiondspeq DSP Signal Path Element Jan 14 14:00:30 rivoplus volumio[3317]: info: Additional DSP elements updated Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:30 rivoplus volumio[3317]: info: Updating RAAT Signal Path Jan 14 14:00:30 rivoplus volumio[3317]: info: FusionDsp - ---- installed callbackRead Jan 14 14:00:30 rivoplus volumio[3317]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 14 14:00:30 rivoplus systemd[1]: Started FusionDsp Daemon. Jan 14 14:00:30 rivoplus sudo[7458]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:30 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:31 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 14 14:00:31 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 14 14:00:31 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:31 rivoplus volumio[3317]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 14 14:00:31 rivoplus volumio[3317]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 14 14:00:31 rivoplus volumio[3317]: error: FusionDsp - WebSocket error: [object Object] Jan 14 14:00:31 rivoplus volumio[3317]: info: Executing endpoint restartRAATSocket Jan 14 14:00:31 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 14 14:00:31 rivoplus volumio[3317]: info: CoreCommandRouter::volumioGetState Jan 14 14:00:31 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:31 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 14 14:00:31 rivoplus volumio[3317]: No protocol specified Jan 14 14:00:31 rivoplus volumio[3317]: xcb_connection_has_error() returned true Jan 14 14:00:31 rivoplus sudo[7471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 14 14:00:31 rivoplus sudo[7471]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:31 rivoplus volumio[3317]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 14 14:00:31 rivoplus sudo[7471]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:31 rivoplus sudo[7473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 14 14:00:31 rivoplus sudo[7473]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:31 rivoplus volumio[3317]: info: Starting Shairport Sync Jan 14 14:00:31 rivoplus systemd[1]: Stopping RAAT DAEMON... Jan 14 14:00:31 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jan 14 14:00:31 rivoplus systemd[1]: raat-daemon.service: Succeeded. Jan 14 14:00:31 rivoplus systemd[1]: Stopped RAAT DAEMON. Jan 14 14:00:31 rivoplus systemd[1]: Started RAAT DAEMON. Jan 14 14:00:31 rivoplus sudo[7473]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:31 rivoplus sudo[7477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 14 14:00:31 rivoplus sudo[7477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:31 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:31 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 14 14:00:31 rivoplus systemd[1]: shairport-sync.service: Succeeded. Jan 14 14:00:31 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:31 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:00:31 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 14 14:00:31 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:31 rivoplus volumio[3317]: info: Raat Daemon started successfully Jan 14 14:00:31 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:00:31 rivoplus volumio[3317]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 14 14:00:31 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 14 14:00:31 rivoplus sudo[7477]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:31 rivoplus volumio[3317]: info: Executing endpoint restartRAATSocket Jan 14 14:00:31 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 14 14:00:31 rivoplus volumio[3317]: info: Shairport-Sync Started Jan 14 14:00:31 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:31 rivoplus volumio[3317]: info: TidalConnect service stoped! Jan 14 14:00:31 rivoplus volumio[3317]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 14 14:00:31 rivoplus volumio[3317]: info: TidalConnect service stoped! Jan 14 14:00:32 rivoplus volumio[3317]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:00:32 rivoplus volumio[3317]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:00:32 rivoplus sudo[7502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 14 14:00:32 rivoplus sudo[7502]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:32 rivoplus volumio[3317]: 0+0 records in Jan 14 14:00:32 rivoplus volumio[3317]: 0+0 records out Jan 14 14:00:32 rivoplus volumio[3317]: 0 bytes copied, 0.000244833 s, 0.0 kB/s Jan 14 14:00:32 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Jan 14 14:00:32 rivoplus sudo[7502]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:32 rivoplus volumio[3317]: info: camilladsp spawned new process with pid 7506, instance 1, run: true Jan 14 14:00:33 rivoplus volumio[3317]: info: TidalConnect service stoped! Jan 14 14:00:33 rivoplus volumio[3317]: info: Executing endpoint tc_getconfig Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 14 14:00:33 rivoplus vtcs[7507]: STARTING TidalConnect services, version: 1.5.2.56 Jan 14 14:00:33 rivoplus vtcs[7507]: STARTED TidalConnect services. Jan 14 14:00:33 rivoplus volumio[3317]: info: Executing endpoint tc_connect Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 14 14:00:33 rivoplus volumio[3317]: info: Connecting to TidalConnect Jan 14 14:00:33 rivoplus volumio[3317]: info: TidalConnect service stoped! Jan 14 14:00:33 rivoplus volumio[3317]: info: RAAT: Requesting Headphone Status Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::servicePushState Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreStateMachine::pushState Jan 14 14:00:33 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::volumioPushState Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::volumioGetState Jan 14 14:00:33 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:33 rivoplus volumio[3317]: info: MRS: Pushing multiroomSync output update for this device Jan 14 14:00:33 rivoplus volumio[3317]: info: MRS: Pushing multiroomSync output Jan 14 14:00:33 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:33 rivoplus volumio[3317]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::servicePushState Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreStateMachine::pushState Jan 14 14:00:33 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::volumioPushState Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::volumioGetState Jan 14 14:00:33 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:33 rivoplus volumio[3317]: info: MRS: Pushing multiroomSync output update for this device Jan 14 14:00:33 rivoplus volumio[3317]: info: MRS: Pushing multiroomSync output Jan 14 14:00:33 rivoplus volumio[3317]: info: CorePlayQueue::getTrack 0 Jan 14 14:00:33 rivoplus volumio[3317]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jan 14 14:00:33 rivoplus volumio[3317]: info: Updating RAAT Signal Path Jan 14 14:00:33 rivoplus volumio[3317]: info: Updating RAAT Signal Path Jan 14 14:00:33 rivoplus volumio[3317]: info: MCU Signalled Headphone Mode Disabled Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 14 14:00:33 rivoplus volumio[3317]: info: Reporting Headphone State: false Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:33 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:33 rivoplus volumio[3317]: info: Updating RAAT Signal Path Jan 14 14:00:33 rivoplus volumio[3317]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:00:33 rivoplus volumio[3317]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 14 14:00:33 rivoplus mpd[7412]: Jan 14 14:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 14 14:00:33 rivoplus systemd[1]: Started Music Player Daemon. Jan 14 14:00:33 rivoplus sudo[7366]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:33 rivoplus sudo[7305]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:33 rivoplus sudo[7543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 14 14:00:33 rivoplus sudo[7543]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:33 rivoplus volumio[3317]: error: MPD error: The expression evaluated to a falsy value: Jan 14 14:00:33 rivoplus volumio[3317]: assert.ok(self.idling) Jan 14 14:00:33 rivoplus volumio[3317]: error: The expression evaluated to a falsy value: Jan 14 14:00:33 rivoplus volumio[3317]: assert.ok(self.idling) Jan 14 14:00:33 rivoplus volumio[3317]: error: updateQueue error: null Jan 14 14:00:33 rivoplus sudo[7543]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:34 rivoplus volumio[3317]: info: RAAT: Requesting Headphone Status Jan 14 14:00:34 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 14 14:00:34 rivoplus volumio[3317]: info: MCU Signalled Headphone Mode Disabled Jan 14 14:00:34 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 14 14:00:34 rivoplus volumio[3317]: info: Reporting Headphone State: false Jan 14 14:00:34 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:34 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:34 rivoplus volumio[3317]: info: Updating RAAT Signal Path Jan 14 14:00:34 rivoplus volumio[3317]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jan 14 14:00:34 rivoplus volumio[3317]: info: FusionDsp - Stopping FusionDsp service Jan 14 14:00:34 rivoplus volumio[3317]: info: camilladsp stopping service pid 7506... Jan 14 14:00:34 rivoplus volumio[3317]: info: camilladsp service terminated, instance 1 Jan 14 14:00:34 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:34.406+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:34 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:34.416+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:34 rivoplus sudo[7552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jan 14 14:00:34 rivoplus sudo[7552]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:34 rivoplus systemd[1]: Stopping FusionDsp Daemon... Jan 14 14:00:34 rivoplus volumio[7460]: Backend configuration: Jan 14 14:00:34 rivoplus volumio[7460]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jan 14 14:00:34 rivoplus volumio[7460]: active_config_txt: null Jan 14 14:00:34 rivoplus volumio[7460]: camilla_host: 127.0.0.1 Jan 14 14:00:34 rivoplus volumio[7460]: camilla_port: 9876 Jan 14 14:00:34 rivoplus volumio[7460]: coeff_dir: /data/INTERNAL/FusionDsp/filters Jan 14 14:00:34 rivoplus volumio[7460]: config_dir: /data/configuration/audio_interface/fusiondsp Jan 14 14:00:34 rivoplus volumio[7460]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jan 14 14:00:34 rivoplus volumio[7460]: log_file: /tmp/camilladsp.log Jan 14 14:00:34 rivoplus volumio[7460]: on_get_active_config: null Jan 14 14:00:34 rivoplus volumio[7460]: on_set_active_config: null Jan 14 14:00:34 rivoplus volumio[7460]: port: 5011 Jan 14 14:00:34 rivoplus volumio[7460]: supported_capture_types: null Jan 14 14:00:34 rivoplus volumio[7460]: supported_playback_types: null Jan 14 14:00:34 rivoplus volumio[7460]: update_config_symlink: false Jan 14 14:00:34 rivoplus volumio[7460]: update_config_txt: false Jan 14 14:00:34 rivoplus volumio[7460]: ======== Running on http://0.0.0.0:5011 ======== Jan 14 14:00:34 rivoplus volumio[7460]: (Press CTRL+C to quit) Jan 14 14:00:34 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:34 rivoplus systemd[1]: fusiondsp.service: Succeeded. Jan 14 14:00:34 rivoplus systemd[1]: Stopped FusionDsp Daemon. Jan 14 14:00:34 rivoplus sudo[7552]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:34 rivoplus volumio[3317]: info: FusionDsp - Reporting Fusion DSP Disabled Jan 14 14:00:34 rivoplus volumio[3317]: info: Removing fusiondspeq DSP Signal Path Element Jan 14 14:00:34 rivoplus volumio[3317]: info: Additional DSP elements updated Jan 14 14:00:34 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:34 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 14:00:34 rivoplus volumio[3317]: info: Updating RAAT Signal Path Jan 14 14:00:35 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:35.118+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:35 rivoplus volumio[3317]: info: Check plugin dependencies Jan 14 14:00:35 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 14:00:35 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:35.220+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:35 rivoplus volumio[3317]: info: Rename folder Jan 14 14:00:35 rivoplus volumio[3317]: info: Folder /tmp/downloaded_plugin.zip removed Jan 14 14:00:35 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:35.293+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:35 rivoplus volumio[3317]: info: Move to category Jan 14 14:00:35 rivoplus volumio[3317]: info: TidalConnect service started! Jan 14 14:00:36 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:36.227+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:36 rivoplus volumio[3317]: info: Checking if install.sh is present Jan 14 14:00:36 rivoplus volumio[3317]: info: Executing install.sh Jan 14 14:00:36 rivoplus sudo[7566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Jan 14 14:00:36 rivoplus sudo[7566]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:36 rivoplus volumio[3317]: info: creating filters folder and copying demo filters Jan 14 14:00:36 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:36.412+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:36 rivoplus volumio[3317]: info: copying demo flters Jan 14 14:00:36 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:36.504+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:36 rivoplus volumio[3317]: info: Installing/fusiondsp dependencies Jan 14 14:00:36 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:36.675+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:36 rivoplus sudo[7587]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Jan 14 14:00:36 rivoplus sudo[7587]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:36 rivoplus sudo[7593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 14 14:00:36 rivoplus sudo[7593]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:36 rivoplus volumio[3317]: info: TidalConnect service started! Jan 14 14:00:36 rivoplus sudo[7596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 14 14:00:36 rivoplus sudo[7596]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:36 rivoplus systemd[1]: Stopping MPD Monitor Service... Jan 14 14:00:37 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 14 14:00:37 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Jan 14 14:00:37 rivoplus systemd[1]: Stopped MPD Monitor Service. Jan 14 14:00:37 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 14:00:37 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:00:37 rivoplus volumio[3317]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 14 14:00:37 rivoplus systemd[1]: Started MPD Monitor Service. Jan 14 14:00:37 rivoplus sudo[7596]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:37 rivoplus sudo[7593]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:37 rivoplus volumio[3317]: info: Successfully started MPD Monitor Jan 14 14:00:37 rivoplus volumio[3317]: info: Successfully started MPD Monitor Jan 14 14:00:37 rivoplus mpd_monitor.sh[7600]: MPD Monitor Service: Starting MPD Monitor Service Jan 14 14:00:37 rivoplus volumio[3317]: info: Hit:1 http://archive.debian.org/debian buster InRelease Jan 14 14:00:37 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:37.352+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:37 rivoplus volumio[3317]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease Jan 14 14:00:37 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:37.549+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:44 rivoplus volumio[3317]: info: Reading package lists... Jan 14 14:00:44 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:44.088+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:44 rivoplus volumio[3317]: info: Building dependency tree... Jan 14 14:00:44 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:44.859+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:44 rivoplus volumio[3317]: info: Reading state information... Jan 14 14:00:44 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:44.870+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:44 rivoplus volumio[3317]: info: All packages are up to date. Jan 14 14:00:44 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:44.947+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:44 rivoplus sudo[7587]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:44 rivoplus sudo[7904]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Jan 14 14:00:44 rivoplus sudo[7904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:45 rivoplus volumio[3317]: info: Reading package lists... Jan 14 14:00:45 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:45.161+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:45 rivoplus volumio[3317]: info: Building dependency tree... Jan 14 14:00:45 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:45.869+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:45 rivoplus volumio[3317]: info: Reading state information... Jan 14 14:00:45 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:45.880+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:47 rivoplus volumio[3317]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Jan 14 14:00:47 rivoplus sudo[7904]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:47 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:47.313+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:47 rivoplus volumio[3317]: info: python3-pip is already the newest version (18.1-5). Jan 14 14:00:47 rivoplus volumio[3317]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Jan 14 14:00:47 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:47.323+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:47 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:47.325+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:47 rivoplus sudo[7908]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jan 14 14:00:47 rivoplus sudo[7908]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:47 rivoplus volumio[3317]: info: lib/systemd/system/fusiondsp.service Jan 14 14:00:47 rivoplus sudo[7908]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:47 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:47.359+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:48 rivoplus volumio[3317]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Jan 14 14:00:48 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:48.915+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:48 rivoplus volumio[3317]: info: more info at http://www.winimage.com/zLibDll/unzip.html Jan 14 14:00:48 rivoplus volumio[3317]: info: Jan 14 14:00:48 rivoplus volumio[3317]: info: cgui-1.0.0.zip opened Jan 14 14:00:48 rivoplus volumio[3317]: info: creating directory: cgui/ Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/LICENSE.txt Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/README.md Jan 14 14:00:48 rivoplus volumio[3317]: info: creating directory: cgui/build/ Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/robots.txt Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/logo512.png Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/asset-manifest.json Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/index.html Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/.put_statics_here Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/favicon.ico Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/logo192.png Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Jan 14 14:00:48 rivoplus sudo[7912]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/manifest.json Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/css-variables.css Jan 14 14:00:48 rivoplus sudo[7912]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/service-worker.js Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Jan 14 14:00:48 rivoplus volumio[3317]: info: creating directory: cgui/build/static/ Jan 14 14:00:48 rivoplus volumio[3317]: info: creating directory: cgui/build/static/css/ Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Jan 14 14:00:48 rivoplus sudo[7912]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/css/main.1503f6cd.css Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Jan 14 14:00:48 rivoplus volumio[3317]: info: creating directory: cgui/build/static/media/ Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Jan 14 14:00:48 rivoplus volumio[3317]: info: creating directory: cgui/build/static/js/ Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/main.71e11955.js Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/main.71e11955.js.map Jan 14 14:00:48 rivoplus sudo[7914]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Jan 14 14:00:48 rivoplus sudo[7914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Jan 14 14:00:48 rivoplus volumio[3317]: info: creating directory: cgui/config/ Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/config/camillagui.yml Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/config/gui-config.yml Jan 14 14:00:48 rivoplus volumio[3317]: info: creating directory: cgui/backend/ Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/backend/filters_test.py Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/backend/version.py Jan 14 14:00:48 rivoplus sudo[7914]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/backend/filters.py Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/backend/settings.py Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/backend/views.py Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/backend/filterdefaults.py Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/backend/__init__.py Jan 14 14:00:48 rivoplus volumio[3317]: info: creating directory: cgui/backend/__pycache__/ Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Jan 14 14:00:48 rivoplus volumio[3317]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Jan 14 14:00:49 rivoplus volumio[3317]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Jan 14 14:00:49 rivoplus volumio[3317]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Jan 14 14:00:49 rivoplus volumio[3317]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Jan 14 14:00:49 rivoplus sudo[7916]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jan 14 14:00:49 rivoplus volumio[3317]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Jan 14 14:00:49 rivoplus volumio[3317]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Jan 14 14:00:49 rivoplus sudo[7916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:00:49 rivoplus volumio[3317]: info: extracting: cgui/backend/routes.py Jan 14 14:00:49 rivoplus volumio[3317]: info: extracting: cgui/backend/filemanagement.py Jan 14 14:00:49 rivoplus volumio[3317]: info: extracting: cgui/main.py Jan 14 14:00:49 rivoplus sudo[7916]: pam_unix(sudo:session): session closed for user root Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.178+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.178+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.179+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.180+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.180+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.182+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.183+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.184+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.185+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.186+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.187+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.187+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.188+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.188+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.189+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.190+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.191+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.192+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.193+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.194+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.195+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.196+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.197+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.198+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.199+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.200+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.202+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.203+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.204+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.204+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.205+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.206+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.207+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.208+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.209+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.210+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.211+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.213+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.214+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.216+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.217+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.218+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.218+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.220+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.221+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.222+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:49 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:49.223+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:55 rivoplus kernel: hdmitx: system: irq 4 0 Jan 14 14:00:55 rivoplus kernel: hdmitx: system: plugout Jan 14 14:00:55 rivoplus kernel: hdmitx: system: update physcial size: 16 9 Jan 14 14:00:55 rivoplus volumio[3317]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Jan 14 14:00:55 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:55.414+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:55 rivoplus volumio[3317]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-s_s1023j Jan 14 14:00:55 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:55.434+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:56 rivoplus kernel: hdmitx: system: irq 2 0 Jan 14 14:00:56 rivoplus kernel: hdmitx: system: plugin Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: EDID Parser: Jan 14 14:00:56 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:56 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:56 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:56 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:56 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:56 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:56 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: change preferred_mode from 0 to 97 Jan 14 14:00:56 rivoplus kernel: hdmitx: hdmitx: get PMT vic: 97 Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: find IEEEOUT Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: update rx hdr info d at edid parsing Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: blk0 raw data Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: 00ffffffffffff004dd905b901010101011f0103809051780a0dc9a057479827 12484c2108008180a9c0714fb300010101010101010108e80030f2705a80b058 8a009f295300001e023a801871382d40582c45009f295300001e000000fc0053 4f4e5920545620202a33300a000000fd0017790e883c000a202020202020016d Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: blk1 raw data Jan 14 14:00:56 rivoplus kernel: hdmitx: edid: 020367f05861605d5e5f621f101405130420223c3e1203110265663f402f0d7f 071507503d07bc570601670403830f00006e030c002000b8442b008001020304 67d85dc401788003eb0146d000481b9288627697e200cbe305df01e40f030030 e6060d01aeae09011d007251d01e206e2855009f295300001e00000000000051 Jan 14 14:00:56 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:00:56 rivoplus kernel: hdmitx: system: get current mode: 1080p60hz Jan 14 14:00:56 rivoplus kernel: hdmitx: system: update rx hdr info d Jan 14 14:00:56 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:00:56 rivoplus kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 Jan 14 14:00:56 rivoplus kernel: hdmitx: hw: div40: 0 Jan 14 14:00:56 rivoplus kernel: hdmitx: frac_rate = 1 Jan 14 14:00:56 rivoplus kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 Jan 14 14:00:56 rivoplus kernel: hdmitx: HPLLv1: 0xdb3a04f7 Jan 14 14:00:56 rivoplus kernel: hdmitx: config HPLL done Jan 14 14:00:56 rivoplus kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 Jan 14 14:00:56 rivoplus kernel: hdmitx: j = 4 vid_clk_div = 1 Jan 14 14:00:56 rivoplus kernel: hdmitx: set enc for VIC: 16 Jan 14 14:00:56 rivoplus kernel: hdmitx: system: irq 80000001 0 Jan 14 14:00:56 rivoplus kernel: hdmitx: [0x10000104]: 0x00000001 Jan 14 14:00:56 rivoplus kernel: hdmitx: [0x10000180]: 0x000000ff Jan 14 14:00:56 rivoplus kernel: hdmitx: [0x10000181]: 0x000000ff Jan 14 14:00:56 rivoplus kernel: hdmitx: [0x10000182]: 0x00000003 Jan 14 14:00:56 rivoplus kernel: hdmitx: [0x10000183]: 0x00000007 Jan 14 14:00:56 rivoplus kernel: hdmitx: [0x10000184]: 0x0000003f Jan 14 14:00:56 rivoplus kernel: hdmitx: [0x10000185]: 0x00000002 Jan 14 14:00:56 rivoplus kernel: hdmitx: [0x10000187]: 0x000000ff Jan 14 14:00:56 rivoplus kernel: hdmitx: [0x10000188]: 0x00000003 Jan 14 14:00:56 rivoplus kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 Jan 14 14:00:56 rivoplus kernel: hdmitx: video: Sink is HDMI device Jan 14 14:00:56 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:00:56 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:00:56 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:00:56 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:00:56 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:00:56 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:00:56 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:00:56 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:00:56 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:00:56 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:00:56 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:00:56 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:00:58 rivoplus volumio[3317]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages Jan 14 14:00:58 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:58.755+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:58 rivoplus volumio[3317]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) Jan 14 14:00:58 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:58.806+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:58 rivoplus volumio[3317]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) Jan 14 14:00:58 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:58.824+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:58 rivoplus volumio[3317]: info: Building wheels for collected packages: camilladsp Jan 14 14:00:58 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:58.856+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:00:58 rivoplus volumio[3317]: info: Running setup.py bdist_wheel for camilladsp: started Jan 14 14:00:58 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:00:58.877+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:00 rivoplus volumio[3317]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Jan 14 14:01:00 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:00.794+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:00 rivoplus volumio[3317]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-048kae0g/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Jan 14 14:01:00 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:00.826+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:00 rivoplus volumio[3317]: info: Successfully built camilladsp Jan 14 14:01:00 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:00.857+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:07 rivoplus kernel: hdmitx: system: irq 4 0 Jan 14 14:01:07 rivoplus kernel: hdmitx: system: plugout Jan 14 14:01:07 rivoplus kernel: hdmitx: system: update physcial size: 16 9 Jan 14 14:01:08 rivoplus volumio[3317]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Jan 14 14:01:08 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:08.782+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:08 rivoplus volumio[3317]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-mh3cz8d2 Jan 14 14:01:08 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:08.808+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:09 rivoplus kernel: hdmitx: system: irq 2 0 Jan 14 14:01:09 rivoplus kernel: hdmitx: system: plugin Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: EDID Parser: Jan 14 14:01:09 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:09 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:09 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:09 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:09 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:09 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:09 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: change preferred_mode from 0 to 97 Jan 14 14:01:09 rivoplus kernel: hdmitx: hdmitx: get PMT vic: 97 Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: find IEEEOUT Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: update rx hdr info d at edid parsing Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: blk0 raw data Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: 00ffffffffffff004dd905b901010101011f0103809051780a0dc9a057479827 12484c2108008180a9c0714fb300010101010101010108e80030f2705a80b058 8a009f295300001e023a801871382d40582c45009f295300001e000000fc0053 4f4e5920545620202a33300a000000fd0017790e883c000a202020202020016d Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: blk1 raw data Jan 14 14:01:09 rivoplus kernel: hdmitx: edid: 020367f05861605d5e5f621f101405130420223c3e1203110265663f402f0d7f 071507503d07bc570601670403830f00006e030c002000b8442b008001020304 67d85dc401788003eb0146d000481b9288627697e200cbe305df01e40f030030 e6060d01aeae09011d007251d01e206e2855009f295300001e00000000000051 Jan 14 14:01:09 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:01:09 rivoplus kernel: hdmitx: system: get current mode: 1080p60hz Jan 14 14:01:09 rivoplus kernel: hdmitx: system: update rx hdr info d Jan 14 14:01:09 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:01:09 rivoplus kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 Jan 14 14:01:09 rivoplus kernel: hdmitx: hw: div40: 0 Jan 14 14:01:09 rivoplus kernel: hdmitx: frac_rate = 1 Jan 14 14:01:09 rivoplus kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 Jan 14 14:01:09 rivoplus kernel: hdmitx: HPLLv1: 0xdb3a04f7 Jan 14 14:01:09 rivoplus kernel: hdmitx: config HPLL done Jan 14 14:01:09 rivoplus kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 Jan 14 14:01:09 rivoplus kernel: hdmitx: j = 4 vid_clk_div = 1 Jan 14 14:01:09 rivoplus kernel: hdmitx: set enc for VIC: 16 Jan 14 14:01:09 rivoplus kernel: hdmitx: system: irq 80000001 0 Jan 14 14:01:09 rivoplus kernel: hdmitx: [0x10000104]: 0x00000001 Jan 14 14:01:09 rivoplus kernel: hdmitx: [0x10000180]: 0x000000ff Jan 14 14:01:09 rivoplus kernel: hdmitx: [0x10000181]: 0x000000ff Jan 14 14:01:09 rivoplus kernel: hdmitx: [0x10000182]: 0x00000003 Jan 14 14:01:09 rivoplus kernel: hdmitx: [0x10000183]: 0x00000007 Jan 14 14:01:09 rivoplus kernel: hdmitx: [0x10000184]: 0x0000003f Jan 14 14:01:09 rivoplus kernel: hdmitx: [0x10000185]: 0x00000002 Jan 14 14:01:09 rivoplus kernel: hdmitx: [0x10000187]: 0x000000ff Jan 14 14:01:09 rivoplus kernel: hdmitx: [0x10000188]: 0x00000003 Jan 14 14:01:09 rivoplus kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 Jan 14 14:01:09 rivoplus kernel: hdmitx: video: Sink is HDMI device Jan 14 14:01:09 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:01:09 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:01:09 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:01:09 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:01:09 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:01:09 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:01:09 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:01:09 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:01:09 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:01:09 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:01:09 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:01:09 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:01:11 rivoplus volumio[3317]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Jan 14 14:01:11 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:11.879+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:11 rivoplus volumio[3317]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Jan 14 14:01:11 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:11.924+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:11 rivoplus volumio[3317]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Jan 14 14:01:11 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:11.948+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:12 rivoplus volumio[3317]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Jan 14 14:01:12 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:12.170+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:12 rivoplus volumio[3317]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Jan 14 14:01:12 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:12.378+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:12 rivoplus volumio[3317]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Jan 14 14:01:12 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:12.403+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:12 rivoplus volumio[3317]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Jan 14 14:01:12 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:12.432+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:12 rivoplus volumio[3317]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Jan 14 14:01:12 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:12.583+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:12 rivoplus volumio[3317]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Jan 14 14:01:12 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:12.627+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:12 rivoplus volumio[3317]: info: Requirement already satisfied: zipp>=0.5 in /usr/local/lib/python3.7/dist-packages (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Jan 14 14:01:12 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:12.694+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:12 rivoplus volumio[3317]: info: Building wheels for collected packages: camilladsp-plot Jan 14 14:01:12 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:12.833+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:12 rivoplus volumio[3317]: info: Running setup.py bdist_wheel for camilladsp-plot: started Jan 14 14:01:12 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:12.861+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:14 rivoplus volumio[3317]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Jan 14 14:01:14 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:14.767+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:14 rivoplus volumio[3317]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-i69q7kau/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Jan 14 14:01:14 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:14.791+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:14 rivoplus volumio[3317]: info: Successfully built camilladsp-plot Jan 14 14:01:14 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:14.849+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:15 rivoplus volumio[3317]: info: copying hw detection script Jan 14 14:01:15 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:15.522+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:15 rivoplus volumio[3317]: info: Detected cpu architecture as aarch64 Jan 14 14:01:15 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:15.575+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:16 rivoplus volumio[3317]: info: camilladsp Jan 14 14:01:16 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:16.380+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:16 rivoplus sudo[8042]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Jan 14 14:01:16 rivoplus sudo[8042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:01:16 rivoplus sudo[8042]: pam_unix(sudo:session): session closed for user root Jan 14 14:01:16 rivoplus sudo[8044]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Jan 14 14:01:16 rivoplus sudo[8044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:01:16 rivoplus sudo[8044]: pam_unix(sudo:session): session closed for user root Jan 14 14:01:16 rivoplus sudo[8046]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Jan 14 14:01:16 rivoplus sudo[8046]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 14:01:17 rivoplus volumio[3317]: info: Reading package lists... Jan 14 14:01:17 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:17.143+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:17 rivoplus volumio[3317]: info: Building dependency tree... Jan 14 14:01:17 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:17.983+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:17 rivoplus volumio[3317]: info: Reading state information... Jan 14 14:01:17 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:17.999+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:19 rivoplus sudo[8046]: pam_unix(sudo:session): session closed for user root Jan 14 14:01:19 rivoplus sudo[7566]: pam_unix(sudo:session): session closed for user root Jan 14 14:01:19 rivoplus volumio[3317]: info: drc is already the newest version (3.2.2~dfsg0-2). Jan 14 14:01:19 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:19.582+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:19 rivoplus volumio[3317]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Jan 14 14:01:19 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:19.596+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:19 rivoplus volumio[3317]: info: Install script completed Jan 14 14:01:19 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:19.616+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:19 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:19.624+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:19 rivoplus volumio[3317]: info: Done installing plugin. Jan 14 14:01:19 rivoplus volumio[3317]: info: Enabling plugin fusiondsp Jan 14 14:01:19 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 14 14:01:19 rivoplus volumio[3317]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.54","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.6","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true}] Jan 14 14:01:19 rivoplus volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 14 14:01:19 rivoplus volumio[3317]: info: Folder /tmp/plugins removed Jan 14 14:01:19 rivoplus volumio[3317]: info: Folder /tmp/downloaded_plugin.zip removed Jan 14 14:01:19 rivoplus volumio[3317]: Plugin install end detected on script Jan 14 14:01:19 rivoplus volumio[3317]: info: Folder /data/temp removed Jan 14 14:01:19 rivoplus volumio[3317]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 14:01:22 rivoplus kernel: hdmitx: system: irq 4 0 Jan 14 14:01:22 rivoplus kernel: hdmitx: system: plugout Jan 14 14:01:22 rivoplus kernel: hdmitx: system: update physcial size: 16 9 Jan 14 14:01:23 rivoplus kernel: hdmitx: system: irq 2 0 Jan 14 14:01:23 rivoplus kernel: hdmitx: system: plugin Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: EDID Parser: Jan 14 14:01:24 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:24 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:24 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:24 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:24 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:24 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:24 rivoplus kernel: hdmitx: hdmitx: reach vesa idx MAX Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: change preferred_mode from 0 to 97 Jan 14 14:01:24 rivoplus kernel: hdmitx: hdmitx: get PMT vic: 97 Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: find IEEEOUT Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: update rx hdr info d at edid parsing Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: check sum valid Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: blk0 raw data Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: 00ffffffffffff004dd905b901010101011f0103809051780a0dc9a057479827 12484c2108008180a9c0714fb300010101010101010108e80030f2705a80b058 8a009f295300001e023a801871382d40582c45009f295300001e000000fc0053 4f4e5920545620202a33300a000000fd0017790e883c000a202020202020016d Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: blk1 raw data Jan 14 14:01:24 rivoplus kernel: hdmitx: edid: 020367f05861605d5e5f621f101405130420223c3e1203110265663f402f0d7f 071507503d07bc570601670403830f00006e030c002000b8442b008001020304 67d85dc401788003eb0146d000481b9288627697e200cbe305df01e40f030030 e6060d01aeae09011d007251d01e206e2855009f295300001e00000000000051 Jan 14 14:01:24 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:01:24 rivoplus kernel: hdmitx: system: get current mode: 1080p60hz Jan 14 14:01:24 rivoplus kernel: hdmitx: system: update rx hdr info d Jan 14 14:01:24 rivoplus kernel: hdmitx: system: update physcial size: 1440 810 Jan 14 14:01:24 rivoplus kernel: hdmitx: video: already init VIC = 0 Now VIC = 16 Jan 14 14:01:24 rivoplus kernel: hdmitx: hw: div40: 0 Jan 14 14:01:24 rivoplus kernel: hdmitx: frac_rate = 1 Jan 14 14:01:24 rivoplus kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 Jan 14 14:01:24 rivoplus kernel: hdmitx: HPLLv1: 0xdb3a04f7 Jan 14 14:01:24 rivoplus kernel: hdmitx: config HPLL done Jan 14 14:01:24 rivoplus kernel: hdmitx: set_hpll_od3_clk_div[610] div = 6 Jan 14 14:01:24 rivoplus kernel: hdmitx: j = 4 vid_clk_div = 1 Jan 14 14:01:24 rivoplus kernel: hdmitx: set enc for VIC: 16 Jan 14 14:01:24 rivoplus kernel: hdmitx: system: irq 80000001 0 Jan 14 14:01:24 rivoplus kernel: hdmitx: [0x10000104]: 0x00000001 Jan 14 14:01:24 rivoplus kernel: hdmitx: [0x10000180]: 0x000000ff Jan 14 14:01:24 rivoplus kernel: hdmitx: [0x10000181]: 0x000000ff Jan 14 14:01:24 rivoplus kernel: hdmitx: [0x10000182]: 0x00000003 Jan 14 14:01:24 rivoplus kernel: hdmitx: [0x10000183]: 0x00000007 Jan 14 14:01:24 rivoplus kernel: hdmitx: [0x10000184]: 0x0000003f Jan 14 14:01:24 rivoplus kernel: hdmitx: [0x10000185]: 0x00000002 Jan 14 14:01:24 rivoplus kernel: hdmitx: [0x10000187]: 0x000000ff Jan 14 14:01:24 rivoplus kernel: hdmitx: [0x10000188]: 0x00000003 Jan 14 14:01:24 rivoplus kernel: hdmitx: hw: config hdmitx IP vic = 16 cd:4 cs: 2 Jan 14 14:01:24 rivoplus kernel: hdmitx: video: Sink is HDMI device Jan 14 14:01:24 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:01:24 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:01:24 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:01:24 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:01:24 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:01:24 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:01:24 rivoplus kernel: hdmitx: hw: set audio Jan 14 14:01:24 rivoplus kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jan 14 14:01:24 rivoplus kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 148352 Jan 14 14:01:24 rivoplus kernel: hdmitx: hw: aud_n_para = 5824 Jan 14 14:01:24 rivoplus kernel: hdmitx: hw: set channel status Jan 14 14:01:24 rivoplus kernel: hdmitx: audio: Audio Type: PCM Jan 14 14:01:26 rivoplus volumio[3317]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jan 14 14:01:26 rivoplus volumio5-onboarding[4244]: time=2026-01-14T14:01:26.593+10:30 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jan 14 14:01:29 rivoplus volumio[3317]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jan 14 14:01:29 rivoplus volumio[3317]: info: FusionDsp - Stopping FusionDsp service Jan 14 14:01:29 rivoplus volumio[3317]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 14:01:29 rivoplus volumio[3317]: TypeError: Cannot read property 'stop' of null Jan 14 14:01:29 rivoplus volumio[3317]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Jan 14 14:01:29 rivoplus volumio[3317]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jan 14 14:01:29 rivoplus volumio[3317]: at /volumio/app/pluginmanager.js:956:12 Jan 14 14:01:29 rivoplus volumio[3317]: at ChildProcess.exithandler (child_process.js:374:7) Jan 14 14:01:29 rivoplus volumio[3317]: at ChildProcess.emit (events.js:400:28) Jan 14 14:01:29 rivoplus volumio[3317]: at maybeClose (internal/child_process.js:1088:16) Jan 14 14:01:29 rivoplus volumio[3317]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) Jan 14 14:01:29 rivoplus volumio[3317]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 14:01:30 rivoplus sudo[8084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-14 14:00 Jan 14 14:01:30 rivoplus sudo[8084]: 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"