-- Logs begin at Sat 2025-07-19 11:48:31 CEST, end at Sat 2025-07-19 11:59:10 CEST. -- Jul 19 11:58:08 rivovcx go-librespot[4695]: time="2025-07-19T11:58:08+02:00" level=debug msg="fetched chunk 10/15, size: 524288" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Jul 19 11:58:15 rivovcx go-librespot[4695]: time="2025-07-19T11:58:15+02:00" level=trace msg="sent dealer ping" Jul 19 11:58:15 rivovcx go-librespot[4695]: time="2025-07-19T11:58:15+02:00" level=trace msg="received dealer pong" Jul 19 11:58:21 rivovcx go-librespot[4695]: time="2025-07-19T11:58:21+02:00" level=debug msg="fetched chunk 11/15, size: 524288" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Jul 19 11:58:28 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 11:58:28 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 11:58:28 rivovcx volumio[3114]: info: Discovery: Getting this device information Jul 19 11:58:28 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:58:28 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 11:58:35 rivovcx go-librespot[4695]: time="2025-07-19T11:58:35+02:00" level=debug msg="fetched chunk 12/15, size: 524288" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Jul 19 11:58:39 rivovcx ntpd[3327]: Soliciting pool server 162.159.200.1 Jul 19 11:58:44 rivovcx volumio[3114]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jul 19 11:58:45 rivovcx go-librespot[4695]: time="2025-07-19T11:58:45+02:00" level=trace msg="sent dealer ping" Jul 19 11:58:45 rivovcx go-librespot[4695]: time="2025-07-19T11:58:45+02:00" level=trace msg="received dealer pong" Jul 19 11:58:47 rivovcx volumio[3114]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jul 19 11:58:47 rivovcx volumio[3114]: info: Folder /tmp/plugins removed Jul 19 11:58:47 rivovcx volumio[3114]: info: Check plugin dependencies Jul 19 11:58:47 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 11:58:47 rivovcx volumio[3114]: info: Checking if plugin already exists Jul 19 11:58:47 rivovcx volumio[3114]: info: Rename folder Jul 19 11:58:47 rivovcx volumio[3114]: info: Folder /tmp/downloaded_plugin.zip removed Jul 19 11:58:47 rivovcx volumio[3114]: info: Move to category Jul 19 11:58:48 rivovcx volumio[3114]: info: Checking if install.sh is present Jul 19 11:58:48 rivovcx volumio[3114]: info: Executing install.sh Jul 19 11:58:48 rivovcx sudo[5482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Jul 19 11:58:48 rivovcx sudo[5482]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:48 rivovcx volumio[3114]: info: creating filters folder and copying demo filters Jul 19 11:58:48 rivovcx volumio[3114]: info: copying demo flters Jul 19 11:58:48 rivovcx volumio[3114]: info: Installing/fusiondsp dependencies Jul 19 11:58:48 rivovcx sudo[5503]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Jul 19 11:58:48 rivovcx sudo[5503]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:48 rivovcx volumio[3114]: info: Ign:1 http://deb.debian.org/debian buster InRelease Jul 19 11:58:48 rivovcx volumio[3114]: info: Err:2 http://deb.debian.org/debian buster Release Jul 19 11:58:48 rivovcx volumio[3114]: info: 404 Not Found [IP: 151.101.2.132 80] Jul 19 11:58:48 rivovcx volumio[3114]: info: Get:3 https://deb.nodesource.com/node_14.x buster InRelease [4584 B] Jul 19 11:58:49 rivovcx go-librespot[4695]: time="2025-07-19T11:58:49+02:00" level=debug msg="fetched chunk 13/15, size: 524288" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Jul 19 11:58:49 rivovcx volumio[3114]: info: Get:4 https://deb.nodesource.com/node_14.x buster/main armhf Packages [786 B] Jul 19 11:58:49 rivovcx volumio[3114]: info: Reading package lists... Jul 19 11:58:49 rivovcx sudo[5503]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:49 rivovcx sudo[5624]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Jul 19 11:58:49 rivovcx sudo[5624]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:49 rivovcx volumio[3114]: info: Reading package lists... Jul 19 11:58:49 rivovcx volumio[3114]: info: Building dependency tree... Jul 19 11:58:49 rivovcx volumio[3114]: info: Reading state information... Jul 19 11:58:49 rivovcx sudo[5624]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:49 rivovcx sudo[5628]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jul 19 11:58:49 rivovcx sudo[5628]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:50 rivovcx volumio[3114]: info: lib/systemd/system/fusiondsp.service Jul 19 11:58:50 rivovcx sudo[5628]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:50 rivovcx ntpd[3327]: Soliciting pool server 213.222.217.11 Jul 19 11:58:52 rivovcx volumio[3114]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Jul 19 11:58:52 rivovcx volumio[3114]: info: more info at http://www.winimage.com/zLibDll/unzip.html Jul 19 11:58:52 rivovcx volumio[3114]: info: Jul 19 11:58:52 rivovcx volumio[3114]: info: cgui-1.0.0.zip opened Jul 19 11:58:52 rivovcx volumio[3114]: info: creating directory: cgui/ Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/LICENSE.txt Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/README.md Jul 19 11:58:52 rivovcx volumio[3114]: info: creating directory: cgui/build/ Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/robots.txt Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/logo512.png Jul 19 11:58:52 rivovcx sudo[5649]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jul 19 11:58:52 rivovcx sudo[5649]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/asset-manifest.json Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/index.html Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/.put_statics_here Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/favicon.ico Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/logo192.png Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/manifest.json Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/css-variables.css Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/service-worker.js Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Jul 19 11:58:52 rivovcx sudo[5649]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:52 rivovcx volumio[3114]: info: creating directory: cgui/build/static/ Jul 19 11:58:52 rivovcx volumio[3114]: info: creating directory: cgui/build/static/css/ Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/css/main.1503f6cd.css Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Jul 19 11:58:52 rivovcx volumio[3114]: info: creating directory: cgui/build/static/media/ Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Jul 19 11:58:52 rivovcx sudo[5651]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jul 19 11:58:52 rivovcx volumio[3114]: info: creating directory: cgui/build/static/js/ Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Jul 19 11:58:52 rivovcx sudo[5651]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/main.71e11955.js Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Jul 19 11:58:52 rivovcx sudo[5651]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/main.71e11955.js.map Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Jul 19 11:58:52 rivovcx volumio[3114]: info: creating directory: cgui/config/ Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/config/camillagui.yml Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/config/gui-config.yml Jul 19 11:58:52 rivovcx volumio[3114]: info: creating directory: cgui/backend/ Jul 19 11:58:52 rivovcx sudo[5653]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jul 19 11:58:52 rivovcx sudo[5653]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/filters_test.py Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/version.py Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/filters.py Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/settings.py Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/views.py Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/filterdefaults.py Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/__init__.py Jul 19 11:58:52 rivovcx volumio[3114]: info: creating directory: cgui/backend/__pycache__/ Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Jul 19 11:58:52 rivovcx sudo[5653]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/routes.py Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/backend/filemanagement.py Jul 19 11:58:52 rivovcx volumio[3114]: info: extracting: cgui/main.py Jul 19 11:58:52 rivovcx volumio[3114]: info: copying hw detection script Jul 19 11:58:52 rivovcx volumio[3114]: info: Detected cpu architecture as aarch64 Jul 19 11:58:53 rivovcx volumio[3114]: info: camilladsp Jul 19 11:58:53 rivovcx sudo[5666]: 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 Jul 19 11:58:53 rivovcx sudo[5666]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:53 rivovcx sudo[5666]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:53 rivovcx sudo[5668]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Jul 19 11:58:53 rivovcx sudo[5668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:53 rivovcx sudo[5668]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:53 rivovcx sudo[5670]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Jul 19 11:58:53 rivovcx sudo[5670]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:53 rivovcx volumio[3114]: info: Reading package lists... Jul 19 11:58:53 rivovcx volumio[3114]: info: Building dependency tree... Jul 19 11:58:53 rivovcx volumio[3114]: info: Reading state information... Jul 19 11:58:53 rivovcx sudo[5670]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:53 rivovcx sudo[5482]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:53 rivovcx volumio[3114]: Plugin install end detected on script Jul 19 11:58:53 rivovcx volumio[3114]: info: Install script completed Jul 19 11:58:53 rivovcx volumio[3114]: info: Adding reference to registry Jul 19 11:58:53 rivovcx volumio[3114]: info: Done installing plugin. Jul 19 11:58:53 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 11:58:53 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 19 11:58:54 rivovcx volumio[3114]: info: Folder /tmp/plugins removed Jul 19 11:58:54 rivovcx volumio[3114]: info: Folder /tmp/downloaded_plugin.zip removed Jul 19 11:58:54 rivovcx volumio[3114]: info: Folder /data/temp removed Jul 19 11:58:57 rivovcx volumio[3114]: info: Enabling plugin fusiondsp Jul 19 11:58:57 rivovcx volumio[3114]: info: Loading plugin "fusiondsp"... Jul 19 11:58:57 rivovcx ntpd[3327]: Soliciting pool server 79.190.98.149 Jul 19 11:58:57 rivovcx volumio[3114]: info: Preparing to generate the ALSA configuration file Jul 19 11:58:57 rivovcx volumio[3114]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 19 11:58:57 rivovcx volumio[3114]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 19 11:58:57 rivovcx volumio[3114]: info: Reading ALSA contributions from plugins. Jul 19 11:58:57 rivovcx volumio[3114]: info: Asound.conf file written Jul 19 11:58:57 rivovcx sudo[5675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 19 11:58:57 rivovcx sudo[5675]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:57 rivovcx sudo[5675]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:57 rivovcx kernel: Fine tdm clk setting range (0~2000000), 12287994 Jul 19 11:58:57 rivovcx kernel: Fine spdif sysclk setting range(0~2000000), 6143997 Jul 19 11:58:57 rivovcx kernel: out of value, fixed it Jul 19 11:58:57 rivovcx kernel: id=0 set inskew=0 Jul 19 11:58:57 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:58:57 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jul 19 11:58:57 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jul 19 11:58:57 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jul 19 11:58:57 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jul 19 11:58:57 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Jul 19 11:58:57 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Gustard Internal Clock Validity:0' : Operation not permitted Jul 19 11:58:57 rivovcx volumio[3114]: info: Output device has changed, restarting MPD Jul 19 11:58:57 rivovcx sudo[5680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 11:58:57 rivovcx sudo[5680]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:57 rivovcx sudo[5680]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:57 rivovcx volumio[3114]: info: Output device has changed, restarting Shairport Sync Jul 19 11:58:57 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:57 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 11:58:57 rivovcx sudo[5683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 11:58:57 rivovcx sudo[5683]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:57 rivovcx systemd[1]: Stopping Music Player Daemon... Jul 19 11:58:57 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:58:57 rivovcx volumio[3114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 11:58:57 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:58:57 rivovcx volumio[3114]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 19 11:58:57 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:57 rivovcx systemd[1]: mpd.service: Succeeded. Jul 19 11:58:57 rivovcx systemd[1]: Stopped Music Player Daemon. Jul 19 11:58:57 rivovcx systemd[1]: Starting Music Player Daemon... Jul 19 11:58:58 rivovcx volumio[3114]: info: QobuzConnect: setDeactiveState invoked Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:58:58 rivovcx volumio[3114]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: Relinquishing Volumio State Jul 19 11:58:58 rivovcx vtcs[3668]: [2025-07-19 11:58:58.015] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jul 19 11:58:58 rivovcx vtcs[3668]: [close:85] Entering Jul 19 11:58:58 rivovcx vtcs[3668]: [close:100] Exiting Jul 19 11:58:58 rivovcx volumio[3114]: info: PLUGIN START: fusiondsp Jul 19 11:58:58 rivovcx sudo[5702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 11:58:58 rivovcx sudo[5702]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:58 rivovcx systemd[1]: Stopping Volumio Tidal Connect Service... Jul 19 11:58:58 rivovcx systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jul 19 11:58:58 rivovcx volumio[3114]: info: Loading i18n strings for locale pl Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: FusionDsp - mixtype--------------------- None Jul 19 11:58:58 rivovcx volumio[3114]: info: Preparing to generate the ALSA configuration file Jul 19 11:58:58 rivovcx volumio[3114]: info: Done. Jul 19 11:58:58 rivovcx systemd[1]: vtcs.service: Succeeded. Jul 19 11:58:58 rivovcx systemd[1]: Stopped Volumio Tidal Connect Service. Jul 19 11:58:58 rivovcx sudo[5702]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:58 rivovcx sudo[5689]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 11:58:58 rivovcx sudo[5689]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:58 rivovcx sudo[5689]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:58 rivovcx sudo[5708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 11:58:58 rivovcx sudo[5708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:58 rivovcx sudo[5708]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:58 rivovcx volumio[3114]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 19 11:58:58 rivovcx volumio[3114]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 19 11:58:58 rivovcx volumio[3114]: info: Reading ALSA contributions from plugins. Jul 19 11:58:58 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:58:58 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:58:58 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:58:58 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:58:58 rivovcx sudo[5718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 19 11:58:58 rivovcx sudo[5718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 11:58:58 rivovcx volumio[3114]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 19 11:58:58 rivovcx sudo[5718]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:58 rivovcx volumio[3114]: info: MPD Permissions set Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreStateMachine::pushState Jul 19 11:58:58 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::volumioPushState Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:58:58 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:58:58 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output update for this device Jul 19 11:58:58 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output Jul 19 11:58:58 rivovcx sudo[5720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 19 11:58:58 rivovcx sudo[5720]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:58 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx qobuz-connect[3528]: 20250719 11:58:58.439 [3528.3528] INFO SampleApp: Stopping Local configuration server Jul 19 11:58:58 rivovcx systemd[1]: Stopping Volumio Qobuz Connect Service... Jul 19 11:58:58 rivovcx qobuz-connect[3528]: 20250719 11:58:58.450 [3528.3528] INFO SampleApp: shat down connection on UNIX socket Jul 19 11:58:58 rivovcx systemd[1]: qobuz-connect.service: Succeeded. Jul 19 11:58:58 rivovcx systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 19 11:58:58 rivovcx systemd[1]: Started Volumio Qobuz Connect Service. Jul 19 11:58:58 rivovcx sudo[5720]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:58 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 11:58:59 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:58:59 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:58:59 rivovcx volumio[3114]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jul 19 11:58:59 rivovcx volumio[3114]: info: QobuzConnect: setDeactiveState invoked Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:58:59 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:58:59 rivovcx volumio[3114]: info: MCU Signalled Playback Inactive Jul 19 11:58:59 rivovcx volumio[3114]: info: Enabling plugin fusiondsp Jul 19 11:58:59 rivovcx volumio[3114]: info: Loading plugin "fusiondsp"... Jul 19 11:58:59 rivovcx volumio[3114]: info: Applying required configuration parameters for plugin fusiondsp Jul 19 11:58:59 rivovcx volumio[3114]: info: Executing endpoint qc_getconfig Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 19 11:58:59 rivovcx qobuz-connect[5724]: 20250719 11:58:59.259 [5724.5724] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 19 11:58:59 rivovcx qobuz-connect[5724]: 20250719 11:58:59.267 [5724.5724] INFO VolumeManager: [0xab80a920]: Setting new playback volume: 75 Jul 19 11:58:59 rivovcx qobuz-connect[5724]: 20250719 11:58:59.267 [5724.5724] INFO VolumeManager: [0xab80a920]: Setting new mute state: 0 Jul 19 11:58:59 rivovcx qobuz-connect[5724]: 20250719 11:58:59.267 [5724.5724] INFO QobuzConnect: [0xab80b2f0]: Client initialized! Jul 19 11:58:59 rivovcx qobuz-connect[5724]: 20250719 11:58:59.267 [5724.5724] INFO SampleApp: Starting Avahi advertising, name: RivoVcx, service name: _qobuz-connect._tcp Jul 19 11:58:59 rivovcx volumio[3114]: info: Starting Shairport Sync Jul 19 11:58:59 rivovcx qobuz-connect[5724]: 20250719 11:58:59.284 [5724.5724] INFO LocalConfigManager: [0xab80a200]: Starting Local Configuration server Jul 19 11:58:59 rivovcx qobuz-connect[5724]: 20250719 11:58:59.284 [5724.5724] INFO SampleApp: Starting Local configuration server Jul 19 11:58:59 rivovcx qobuz-connect[5724]: 20250719 11:58:59.285 [5724.5724] INFO SampleApp: Connected to UNIX socket client 0xab7ffed8 Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 11:58:59 rivovcx volumio[3114]: info: Discovery: Getting this device information Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:58:59 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 11:58:59 rivovcx sudo[5738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 11:58:59 rivovcx sudo[5738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:59 rivovcx volumio[3114]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 19 11:58:59 rivovcx volumio[3114]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 19 11:58:59 rivovcx systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 11:58:59 rivovcx systemd[1]: shairport-sync.service: Succeeded. Jul 19 11:58:59 rivovcx systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 11:58:59 rivovcx systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 11:58:59 rivovcx sudo[5738]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:59 rivovcx qobuz-connect[5724]: 20250719 11:58:59.413 [5724.5724] INFO SampleApp: Playback volume changed: 75 Jul 19 11:58:59 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:58:59 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:58:59 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:58:59 rivovcx volumio[3114]: info: Asound.conf file unchanged, so no further update is needed Jul 19 11:58:59 rivovcx volumio[3114]: info: Output device has changed, restarting MPD Jul 19 11:58:59 rivovcx sudo[5746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 19 11:58:59 rivovcx sudo[5746]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:59 rivovcx sudo[5746]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:59 rivovcx sudo[5750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 19 11:58:59 rivovcx sudo[5750]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:59 rivovcx sudo[5751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 11:58:59 rivovcx sudo[5751]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:59 rivovcx volumio[3114]: info: Output device has changed, restarting Shairport Sync Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 11:58:59 rivovcx sudo[5751]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:59 rivovcx systemd[1]: Stopping RAAT DAEMON... Jul 19 11:58:59 rivovcx sudo[5755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 11:58:59 rivovcx sudo[5755]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:59 rivovcx systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 19 11:58:59 rivovcx systemd[1]: raat-daemon.service: Succeeded. Jul 19 11:58:59 rivovcx systemd[1]: Stopped RAAT DAEMON. Jul 19 11:58:59 rivovcx systemd[1]: Started RAAT DAEMON. Jul 19 11:58:59 rivovcx sudo[5750]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:59 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:58:59 rivovcx volumio[3114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 11:58:59 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:58:59 rivovcx volumio[3114]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:59 rivovcx systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 19 11:58:59 rivovcx systemd[1]: mpd.service: Succeeded. Jul 19 11:58:59 rivovcx systemd[1]: Stopped Music Player Daemon. Jul 19 11:58:59 rivovcx systemd[1]: Starting Music Player Daemon... Jul 19 11:58:59 rivovcx volumio[3114]: info: QobuzConnect: setDeactiveState invoked Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:58:59 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:58:59 rivovcx volumio[3114]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:58:59 rivovcx volumio[3114]: info: Preparing to generate the ALSA configuration file Jul 19 11:58:59 rivovcx sudo[5775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 11:58:59 rivovcx sudo[5775]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:59 rivovcx sudo[5766]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 11:58:59 rivovcx sudo[5766]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:59 rivovcx sudo[5775]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:59 rivovcx sudo[5766]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:59 rivovcx sudo[5780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 11:58:59 rivovcx sudo[5780]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:59 rivovcx sudo[5780]: pam_unix(sudo:session): session closed for user root Jul 19 11:58:59 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:58:59 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:58:59 rivovcx volumio[3114]: info: Shairport-Sync Started Jul 19 11:58:59 rivovcx volumio[3114]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 19 11:58:59 rivovcx volumio[3114]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 19 11:58:59 rivovcx volumio[3114]: info: Reading ALSA contributions from plugins. Jul 19 11:58:59 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:58:59 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:58:59 rivovcx sudo[5788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 19 11:58:59 rivovcx sudo[5788]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:58:59 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:58:59 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:58:59 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:58:59 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:00 rivovcx sudo[5788]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:00 rivovcx volumio[3114]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 19 11:59:00 rivovcx volumio[3114]: info: MPD Permissions set Jul 19 11:59:00 rivovcx volumio[3114]: info: Raat Daemon started successfully Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreStateMachine::pushState Jul 19 11:59:00 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::volumioPushState Jul 19 11:59:00 rivovcx sudo[5791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 19 11:59:00 rivovcx sudo[5791]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:00 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:00 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output update for this device Jul 19 11:59:00 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output Jul 19 11:59:00 rivovcx volumio[3114]: info: Executing endpoint restartRAATSocket Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 19 11:59:00 rivovcx qobuz-connect[5724]: 20250719 11:59:00.073 [5724.5724] INFO SampleApp: Stopping Local configuration server Jul 19 11:59:00 rivovcx systemd[1]: Stopping Volumio Qobuz Connect Service... Jul 19 11:59:00 rivovcx qobuz-connect[5724]: 20250719 11:59:00.083 [5724.5724] INFO SampleApp: shat down connection on UNIX socket Jul 19 11:59:00 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:00 rivovcx systemd[1]: qobuz-connect.service: Succeeded. Jul 19 11:59:00 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:00 rivovcx systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 19 11:59:00 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:00 rivovcx systemd[1]: Started Volumio Qobuz Connect Service. Jul 19 11:59:00 rivovcx sudo[5791]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:00 rivovcx volumio[3114]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jul 19 11:59:00 rivovcx volumio[3114]: info: QobuzConnect: setDeactiveState invoked Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:00 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:00 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 5821, instance 1, run: true Jul 19 11:59:00 rivovcx volumio[3114]: info: camilladsp service started and running in background, instance 1 Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:00 rivovcx volumio[3114]: cannot open device 'volumioHw': Device or resource busy Jul 19 11:59:00 rivovcx volumio[3114]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 19 11:59:00 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:00 rivovcx volumio[3114]: cannot open device 'volumioHw': Device or resource busy Jul 19 11:59:00 rivovcx volumio[3114]: info: FusionDsp loaded Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 11:59:00 rivovcx sudo[5828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 19 11:59:00 rivovcx sudo[5828]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:00 rivovcx volumio[3114]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 19 11:59:00 rivovcx volumio[3114]: info: Adding Signal Path Element [object Object] Jul 19 11:59:00 rivovcx volumio[3114]: info: Adding fusiondspeq DSP Signal Path Element Jul 19 11:59:00 rivovcx volumio[3114]: info: Additional DSP elements updated Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: Updating RAAT Signal Path Jul 19 11:59:00 rivovcx volumio[3114]: info: FusionDsp - ---- installed callbackRead Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx systemd[1]: Started FusionDsp Daemon. Jul 19 11:59:00 rivovcx sudo[5828]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:00 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:00 rivovcx volumio[5831]: Traceback (most recent call last): Jul 19 11:59:00 rivovcx volumio[5831]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 19 11:59:00 rivovcx volumio[5831]: from aiohttp import web Jul 19 11:59:00 rivovcx volumio[5831]: ModuleNotFoundError: No module named 'aiohttp' Jul 19 11:59:00 rivovcx systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 19 11:59:00 rivovcx systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 19 11:59:00 rivovcx volumio[3114]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 11:59:00 rivovcx volumio[3114]: info: Executing endpoint qc_getconfig Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 19 11:59:00 rivovcx qobuz-connect[5797]: 20250719 11:59:00.674 [5797.5797] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 19 11:59:00 rivovcx qobuz-connect[5797]: 20250719 11:59:00.681 [5797.5797] INFO VolumeManager: [0xac168920]: Setting new playback volume: 75 Jul 19 11:59:00 rivovcx qobuz-connect[5797]: 20250719 11:59:00.682 [5797.5797] INFO VolumeManager: [0xac168920]: Setting new mute state: 0 Jul 19 11:59:00 rivovcx qobuz-connect[5797]: 20250719 11:59:00.682 [5797.5797] INFO QobuzConnect: [0xac1692f0]: Client initialized! Jul 19 11:59:00 rivovcx qobuz-connect[5797]: 20250719 11:59:00.682 [5797.5797] INFO SampleApp: Starting Avahi advertising, name: RivoVcx, service name: _qobuz-connect._tcp Jul 19 11:59:00 rivovcx volumio[3114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jul 19 11:59:00 rivovcx volumio[3114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jul 19 11:59:00 rivovcx volumio[3114]: error: FusionDsp - WebSocket error: [object Object] Jul 19 11:59:00 rivovcx qobuz-connect[5797]: 20250719 11:59:00.700 [5797.5797] INFO LocalConfigManager: [0xac168200]: Starting Local Configuration server Jul 19 11:59:00 rivovcx qobuz-connect[5797]: 20250719 11:59:00.700 [5797.5797] INFO SampleApp: Starting Local configuration server Jul 19 11:59:00 rivovcx qobuz-connect[5797]: 20250719 11:59:00.701 [5797.5797] INFO SampleApp: Connected to UNIX socket client 0xac15ded8 Jul 19 11:59:00 rivovcx volumio[3114]: info: Starting Shairport Sync Jul 19 11:59:00 rivovcx volumio[3114]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 19 11:59:00 rivovcx volumio[3114]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 19 11:59:00 rivovcx sudo[5844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 11:59:00 rivovcx sudo[5844]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 19 11:59:00 rivovcx systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 11:59:00 rivovcx systemd[1]: shairport-sync.service: Succeeded. Jul 19 11:59:00 rivovcx systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 11:59:00 rivovcx qobuz-connect[5797]: 20250719 11:59:00.824 [5797.5797] INFO SampleApp: Playback volume changed: 75 Jul 19 11:59:00 rivovcx systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 11:59:00 rivovcx sudo[5844]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:00 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:00 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:00 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:00 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:00 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:00 rivovcx sudo[5852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 19 11:59:00 rivovcx volumio[3114]: info: Shairport-Sync Started Jul 19 11:59:00 rivovcx sudo[5852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:00 rivovcx sudo[5852]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:00 rivovcx volumio[3114]: info: Asound.conf file written Jul 19 11:59:01 rivovcx sudo[5857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 19 11:59:01 rivovcx sudo[5857]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:01 rivovcx sudo[5860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 19 11:59:01 rivovcx sudo[5860]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:01 rivovcx systemd[1]: Stopping RAAT DAEMON... Jul 19 11:59:01 rivovcx systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 19 11:59:01 rivovcx systemd[1]: raat-daemon.service: Succeeded. Jul 19 11:59:01 rivovcx systemd[1]: Stopped RAAT DAEMON. Jul 19 11:59:01 rivovcx sudo[5860]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:01 rivovcx systemd[1]: Started RAAT DAEMON. Jul 19 11:59:01 rivovcx sudo[5857]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:01 rivovcx kernel: Fine tdm clk setting range (0~2000000), 12287994 Jul 19 11:59:01 rivovcx kernel: Fine spdif sysclk setting range(0~2000000), 6143997 Jul 19 11:59:01 rivovcx kernel: out of value, fixed it Jul 19 11:59:01 rivovcx kernel: id=0 set inskew=0 Jul 19 11:59:01 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:01 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jul 19 11:59:01 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jul 19 11:59:01 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jul 19 11:59:01 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jul 19 11:59:01 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Jul 19 11:59:01 rivovcx volumio[3114]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Gustard Internal Clock Validity:0' : Operation not permitted Jul 19 11:59:01 rivovcx volumio[3114]: info: Output device has changed, restarting MPD Jul 19 11:59:01 rivovcx sudo[5874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 11:59:01 rivovcx sudo[5874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:01 rivovcx sudo[5874]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:01 rivovcx volumio[3114]: info: Output device has changed, restarting Shairport Sync Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 11:59:01 rivovcx sudo[5878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 11:59:01 rivovcx sudo[5878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:01 rivovcx systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 19 11:59:01 rivovcx systemd[1]: mpd.service: Succeeded. Jul 19 11:59:01 rivovcx systemd[1]: Stopped Music Player Daemon. Jul 19 11:59:01 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:01 rivovcx volumio[3114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 11:59:01 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:01 rivovcx volumio[3114]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:01 rivovcx systemd[1]: Starting Music Player Daemon... Jul 19 11:59:01 rivovcx volumio[3114]: info: QobuzConnect: setDeactiveState invoked Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:01 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:01 rivovcx volumio[3114]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:01 rivovcx volumio[3114]: info: Preparing to generate the ALSA configuration file Jul 19 11:59:01 rivovcx sudo[5887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 11:59:01 rivovcx sudo[5887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:01 rivovcx volumio[3114]: info: PLUGIN START: fusiondsp Jul 19 11:59:01 rivovcx volumio[3114]: info: Loading i18n strings for locale pl Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:01 rivovcx volumio[3114]: info: FusionDsp - mixtype--------------------- None Jul 19 11:59:01 rivovcx volumio[3114]: info: Done. Jul 19 11:59:01 rivovcx sudo[5887]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:01 rivovcx sudo[5883]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 11:59:01 rivovcx sudo[5883]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:01 rivovcx sudo[5891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 11:59:01 rivovcx sudo[5883]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:01 rivovcx sudo[5891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:01 rivovcx sudo[5891]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:01 rivovcx sudo[5899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 19 11:59:01 rivovcx sudo[5899]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:01 rivovcx volumio[3114]: 0+0 records in Jul 19 11:59:01 rivovcx volumio[3114]: 0+0 records out Jul 19 11:59:01 rivovcx volumio[3114]: 0 bytes copied, 0.000181167 s, 0.0 kB/s Jul 19 11:59:01 rivovcx sudo[5899]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:01 rivovcx sudo[5904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 19 11:59:01 rivovcx sudo[5904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:01 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 5905, instance 1, run: true Jul 19 11:59:01 rivovcx volumio[3114]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 19 11:59:01 rivovcx volumio[3114]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 19 11:59:01 rivovcx volumio[3114]: info: Reading ALSA contributions from plugins. Jul 19 11:59:01 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:01 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:01 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:01 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:01 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:01 rivovcx systemd[1]: Stopping Volumio Qobuz Connect Service... Jul 19 11:59:01 rivovcx qobuz-connect[5797]: 20250719 11:59:01.673 [5797.5797] INFO SampleApp: Stopping Local configuration server Jul 19 11:59:01 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:01 rivovcx qobuz-connect[5797]: 20250719 11:59:01.684 [5797.5797] INFO SampleApp: shat down connection on UNIX socket Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 11:59:01 rivovcx systemd[1]: qobuz-connect.service: Succeeded. Jul 19 11:59:01 rivovcx systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 19 11:59:01 rivovcx volumio[3114]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 19 11:59:01 rivovcx volumio[3114]: info: MPD Permissions set Jul 19 11:59:01 rivovcx volumio[3114]: info: TidalConnect service stoped! Jul 19 11:59:01 rivovcx systemd[1]: Started Volumio Qobuz Connect Service. Jul 19 11:59:01 rivovcx sudo[5904]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:01 rivovcx volumio[3114]: info: TidalConnect service stoped! Jul 19 11:59:01 rivovcx volumio[3114]: info: Raat Daemon started successfully Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreStateMachine::pushState Jul 19 11:59:01 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::volumioPushState Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:01 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:01 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output update for this device Jul 19 11:59:01 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output Jul 19 11:59:01 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:01 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:01 rivovcx volumio[3114]: info: Executing endpoint restartRAATSocket Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 19 11:59:01 rivovcx volumio[3114]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jul 19 11:59:01 rivovcx volumio[3114]: info: QobuzConnect: setDeactiveState invoked Jul 19 11:59:01 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:01 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:01 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 5923, instance 1, run: true Jul 19 11:59:01 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:01 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:01 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:02 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:02 rivovcx volumio[3114]: info: Executing endpoint qc_getconfig Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 19 11:59:02 rivovcx qobuz-connect[5913]: 20250719 11:59:02.151 [5913.5913] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 19 11:59:02 rivovcx volumio[3114]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 19 11:59:02 rivovcx volumio[3114]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 19 11:59:02 rivovcx qobuz-connect[5913]: 20250719 11:59:02.158 [5913.5913] INFO VolumeManager: [0xac44b920]: Setting new playback volume: 75 Jul 19 11:59:02 rivovcx qobuz-connect[5913]: 20250719 11:59:02.158 [5913.5913] INFO VolumeManager: [0xac44b920]: Setting new mute state: 0 Jul 19 11:59:02 rivovcx qobuz-connect[5913]: 20250719 11:59:02.158 [5913.5913] INFO QobuzConnect: [0xac44c2f0]: Client initialized! Jul 19 11:59:02 rivovcx qobuz-connect[5913]: 20250719 11:59:02.158 [5913.5913] INFO SampleApp: Starting Avahi advertising, name: RivoVcx, service name: _qobuz-connect._tcp Jul 19 11:59:02 rivovcx ntpd[3327]: Soliciting pool server 156.17.20.254 Jul 19 11:59:02 rivovcx qobuz-connect[5913]: 20250719 11:59:02.173 [5913.5913] INFO LocalConfigManager: [0xac44b200]: Starting Local Configuration server Jul 19 11:59:02 rivovcx qobuz-connect[5913]: 20250719 11:59:02.173 [5913.5913] INFO SampleApp: Starting Local configuration server Jul 19 11:59:02 rivovcx qobuz-connect[5913]: 20250719 11:59:02.174 [5913.5913] INFO SampleApp: Connected to UNIX socket client 0xac440ed8 Jul 19 11:59:02 rivovcx sudo[5948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 19 11:59:02 rivovcx sudo[5948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:02 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 5949, instance 1, run: true Jul 19 11:59:02 rivovcx systemd[1]: Started Volumio Tidal Connect Service. Jul 19 11:59:02 rivovcx sudo[5948]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:02 rivovcx qobuz-connect[5913]: 20250719 11:59:02.306 [5913.5913] INFO SampleApp: Playback volume changed: 75 Jul 19 11:59:02 rivovcx systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 19 11:59:02 rivovcx systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. Jul 19 11:59:02 rivovcx systemd[1]: Stopped FusionDsp Daemon. Jul 19 11:59:02 rivovcx systemd[1]: Started FusionDsp Daemon. Jul 19 11:59:02 rivovcx volumio[3114]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 19 11:59:02 rivovcx volumio[3114]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 19 11:59:02 rivovcx volumio[3114]: info: RAAT: Requesting Headphone Status Jul 19 11:59:02 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 19 11:59:02 rivovcx go-librespot[4695]: time="2025-07-19T11:59:02+02:00" level=debug msg="fetched chunk 14/15, size: 524288" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Jul 19 11:59:02 rivovcx volumio[5961]: Traceback (most recent call last): Jul 19 11:59:02 rivovcx volumio[5961]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 19 11:59:02 rivovcx volumio[5961]: from aiohttp import web Jul 19 11:59:02 rivovcx volumio[5961]: ModuleNotFoundError: No module named 'aiohttp' Jul 19 11:59:02 rivovcx systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 19 11:59:02 rivovcx systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 19 11:59:03 rivovcx volumio[3114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jul 19 11:59:03 rivovcx volumio[3114]: info: Executing endpoint tc_getconfig Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:03 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:03 rivovcx vtcs[5957]: STARTING TidalConnect services, version: 1.5.1-beta1 Jul 19 11:59:03 rivovcx volumio[3114]: info: Starting Shairport Sync Jul 19 11:59:03 rivovcx vtcs[5957]: STARTED TidalConnect services. Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 19 11:59:03 rivovcx sudo[5970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 11:59:03 rivovcx sudo[5970]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:03 rivovcx systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 11:59:03 rivovcx systemd[1]: shairport-sync.service: Succeeded. Jul 19 11:59:03 rivovcx systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 11:59:03 rivovcx systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 11:59:03 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:03 rivovcx sudo[5970]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:03 rivovcx sudo[5977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 19 11:59:03 rivovcx sudo[5977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:03 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:03 rivovcx sudo[5977]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:03 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:03 rivovcx volumio[3114]: info: TidalConnect service stoped! Jul 19 11:59:03 rivovcx sudo[5981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 19 11:59:03 rivovcx sudo[5981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:03 rivovcx volumio[3114]: info: TidalConnect service stoped! Jul 19 11:59:03 rivovcx systemd[1]: Stopping RAAT DAEMON... Jul 19 11:59:03 rivovcx systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 19 11:59:03 rivovcx systemd[1]: raat-daemon.service: Succeeded. Jul 19 11:59:03 rivovcx systemd[1]: Stopped RAAT DAEMON. Jul 19 11:59:03 rivovcx systemd[1]: Started RAAT DAEMON. Jul 19 11:59:03 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 5987, instance 1, run: true Jul 19 11:59:03 rivovcx sudo[5981]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:03 rivovcx volumio[3114]: info: MCU Signalled Headphone Mode Disabled Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 19 11:59:03 rivovcx volumio[3114]: info: Reporting Headphone State: false Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:03 rivovcx volumio[3114]: info: Updating RAAT Signal Path Jul 19 11:59:03 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:03 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:03 rivovcx volumio[3114]: info: Shairport-Sync Started Jul 19 11:59:03 rivovcx volumio[3114]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 19 11:59:03 rivovcx volumio[3114]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jul 19 11:59:03 rivovcx volumio[3114]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 19 11:59:03 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6014, instance 2, run: true Jul 19 11:59:03 rivovcx volumio[3114]: info: camilladsp service started and running in background, instance 2 Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:03 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:03 rivovcx volumio[3114]: cannot open device 'volumioHw': Device or resource busy Jul 19 11:59:03 rivovcx volumio[3114]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 19 11:59:03 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:03 rivovcx volumio[3114]: cannot open device 'volumioHw': Device or resource busy Jul 19 11:59:03 rivovcx volumio[3114]: info: FusionDsp loaded Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 11:59:03 rivovcx volumio[3114]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 19 11:59:03 rivovcx volumio[3114]: info: Adding Signal Path Element [object Object] Jul 19 11:59:03 rivovcx volumio[3114]: info: Updating fusiondspeq DSP Signal Path Element Jul 19 11:59:03 rivovcx volumio[3114]: info: Additional DSP elements updated Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:03 rivovcx volumio[3114]: info: Updating RAAT Signal Path Jul 19 11:59:03 rivovcx volumio[3114]: info: FusionDsp - ---- installed callbackRead Jul 19 11:59:03 rivovcx volumio[3114]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 19 11:59:03 rivovcx sudo[6024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 19 11:59:03 rivovcx sudo[6024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:03 rivovcx volumio[3114]: info: Asound.conf file unchanged, so no further update is needed Jul 19 11:59:03 rivovcx volumio[3114]: info: Output device has changed, restarting MPD Jul 19 11:59:03 rivovcx sudo[6027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 11:59:03 rivovcx sudo[6027]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:03 rivovcx volumio[3114]: info: Output device has changed, restarting Shairport Sync Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 11:59:03 rivovcx sudo[6027]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:03 rivovcx sudo[6030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 11:59:03 rivovcx sudo[6030]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:03 rivovcx systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 19 11:59:03 rivovcx systemd[1]: mpd.service: Succeeded. Jul 19 11:59:03 rivovcx systemd[1]: Stopped Music Player Daemon. Jul 19 11:59:03 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:03 rivovcx volumio[3114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 11:59:03 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:03 rivovcx volumio[3114]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:03 rivovcx systemd[1]: Starting Music Player Daemon... Jul 19 11:59:03 rivovcx volumio[3114]: info: QobuzConnect: setDeactiveState invoked Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:03 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:03 rivovcx volumio[3114]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 19 11:59:03 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:03 rivovcx volumio[3114]: info: Preparing to generate the ALSA configuration file Jul 19 11:59:04 rivovcx sudo[6040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 11:59:04 rivovcx sudo[6040]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:04 rivovcx systemd[1]: Stopping Volumio Tidal Connect Service... Jul 19 11:59:04 rivovcx systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jul 19 11:59:04 rivovcx systemd[1]: vtcs.service: Succeeded. Jul 19 11:59:04 rivovcx systemd[1]: Stopped Volumio Tidal Connect Service. Jul 19 11:59:04 rivovcx sudo[6040]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:04 rivovcx sudo[6036]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 11:59:04 rivovcx sudo[6043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 11:59:04 rivovcx sudo[6036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:04 rivovcx sudo[6043]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:04 rivovcx sudo[6036]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:04 rivovcx volumio[3114]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jul 19 11:59:04 rivovcx sudo[6043]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:04 rivovcx sudo[6052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 19 11:59:04 rivovcx sudo[6052]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:04 rivovcx volumio[3114]: info: FusionDsp - ---- read samplerate, raw: Jul 19 11:59:04 rivovcx volumio[3114]: error: FusionDsp - invalid sample rate Jul 19 11:59:04 rivovcx volumio[3114]: info: FusionDsp - ---- read samplerate, raw: Jul 19 11:59:04 rivovcx volumio[3114]: error: FusionDsp - invalid sample rate Jul 19 11:59:04 rivovcx sudo[6052]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:04 rivovcx volumio[3114]: info: Executing endpoint tc_connect Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 19 11:59:04 rivovcx volumio[3114]: info: Connecting to TidalConnect Jul 19 11:59:04 rivovcx volumio[3114]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 19 11:59:04 rivovcx volumio[3114]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 19 11:59:04 rivovcx sudo[6057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 19 11:59:04 rivovcx sudo[6057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:04 rivovcx volumio[3114]: info: Raat Daemon started successfully Jul 19 11:59:04 rivovcx volumio[3114]: info: RAAT: Requesting Headphone Status Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 19 11:59:04 rivovcx systemd[1]: Stopping Volumio Qobuz Connect Service... Jul 19 11:59:04 rivovcx qobuz-connect[5913]: 20250719 11:59:04.269 [5913.5913] INFO SampleApp: Stopping Local configuration server Jul 19 11:59:04 rivovcx qobuz-connect[5913]: 20250719 11:59:04.280 [5913.5913] INFO SampleApp: shat down connection on UNIX socket Jul 19 11:59:04 rivovcx systemd[1]: qobuz-connect.service: Succeeded. Jul 19 11:59:04 rivovcx systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 19 11:59:04 rivovcx systemd[1]: Started Volumio Qobuz Connect Service. Jul 19 11:59:04 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6061, instance 2, run: true Jul 19 11:59:04 rivovcx sudo[6057]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:04 rivovcx sudo[6060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 19 11:59:04 rivovcx sudo[6060]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:04 rivovcx systemd[1]: Started Volumio Tidal Connect Service. Jul 19 11:59:04 rivovcx sudo[6060]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:04 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6073, instance 1, run: true Jul 19 11:59:04 rivovcx volumio[3114]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 19 11:59:04 rivovcx volumio[3114]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 19 11:59:04 rivovcx volumio[3114]: info: Reading ALSA contributions from plugins. Jul 19 11:59:04 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:04 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:04 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:04 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:04 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:04 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:04 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:04 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:04 rivovcx volumio[3114]: error: FusionDsp - WebSocket error: [object Object] Jul 19 11:59:04 rivovcx volumio[3114]: info: Executing endpoint restartRAATSocket Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 19 11:59:04 rivovcx volumio[3114]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 19 11:59:04 rivovcx volumio[3114]: info: MPD Permissions set Jul 19 11:59:04 rivovcx volumio[3114]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jul 19 11:59:04 rivovcx volumio[3114]: info: QobuzConnect: setDeactiveState invoked Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:04 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6087, instance 2, run: true Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreStateMachine::pushState Jul 19 11:59:04 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::volumioPushState Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:04 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:04 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output update for this device Jul 19 11:59:04 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output Jul 19 11:59:04 rivovcx volumio[3114]: info: MCU Signalled Headphone Mode Disabled Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 19 11:59:04 rivovcx volumio[3114]: info: Reporting Headphone State: false Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: Updating RAAT Signal Path Jul 19 11:59:04 rivovcx volumio[3114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jul 19 11:59:04 rivovcx volumio[3114]: info: Executing endpoint qc_getconfig Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 19 11:59:04 rivovcx qobuz-connect[6062]: 20250719 11:59:04.694 [6062.6062] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 19 11:59:04 rivovcx volumio[3114]: info: TidalConnect service stoped! Jul 19 11:59:04 rivovcx qobuz-connect[6062]: 20250719 11:59:04.710 [6062.6062] INFO VolumeManager: [0xabb69920]: Setting new playback volume: 75 Jul 19 11:59:04 rivovcx qobuz-connect[6062]: 20250719 11:59:04.711 [6062.6062] INFO VolumeManager: [0xabb69920]: Setting new mute state: 0 Jul 19 11:59:04 rivovcx qobuz-connect[6062]: 20250719 11:59:04.712 [6062.6062] INFO QobuzConnect: [0xabb6a2f0]: Client initialized! Jul 19 11:59:04 rivovcx qobuz-connect[6062]: 20250719 11:59:04.712 [6062.6062] INFO SampleApp: Starting Avahi advertising, name: RivoVcx, service name: _qobuz-connect._tcp Jul 19 11:59:04 rivovcx qobuz-connect[6062]: 20250719 11:59:04.728 [6062.6062] INFO LocalConfigManager: [0xabb69200]: Starting Local Configuration server Jul 19 11:59:04 rivovcx qobuz-connect[6062]: 20250719 11:59:04.729 [6062.6062] INFO SampleApp: Starting Local configuration server Jul 19 11:59:04 rivovcx qobuz-connect[6062]: 20250719 11:59:04.730 [6062.6062] INFO SampleApp: Connected to UNIX socket client 0xabb5eed8 Jul 19 11:59:04 rivovcx volumio[3114]: info: TidalConnect service stoped! Jul 19 11:59:04 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6100, instance 1, run: true Jul 19 11:59:04 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:04 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:04 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:04 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:04 rivovcx qobuz-connect[6062]: 20250719 11:59:04.844 [6062.6062] INFO SampleApp: Playback volume changed: 75 Jul 19 11:59:04 rivovcx volumio[3114]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 19 11:59:04 rivovcx volumio[3114]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 19 11:59:04 rivovcx systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 19 11:59:04 rivovcx systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 2. Jul 19 11:59:04 rivovcx systemd[1]: Stopped FusionDsp Daemon. Jul 19 11:59:04 rivovcx systemd[1]: Started FusionDsp Daemon. Jul 19 11:59:04 rivovcx sudo[6024]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:04 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6114, instance 2, run: true Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:04 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 19 11:59:05 rivovcx volumio[6113]: Traceback (most recent call last): Jul 19 11:59:05 rivovcx volumio[6113]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 19 11:59:05 rivovcx volumio[6113]: from aiohttp import web Jul 19 11:59:05 rivovcx volumio[6113]: ModuleNotFoundError: No module named 'aiohttp' Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:05 rivovcx systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 19 11:59:05 rivovcx systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 19 11:59:05 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:05 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:05 rivovcx volumio[3114]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 19 11:59:05 rivovcx volumio[3114]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 19 11:59:05 rivovcx sudo[6128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 19 11:59:05 rivovcx sudo[6128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:05 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6129, instance 1, run: true Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 19 11:59:05 rivovcx sudo[6128]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:05 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:05 rivovcx volumio[3114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jul 19 11:59:05 rivovcx volumio[3114]: info: Executing endpoint tc_getconfig Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 19 11:59:05 rivovcx sudo[6141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 19 11:59:05 rivovcx sudo[6141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:05 rivovcx vtcs[6071]: STARTING TidalConnect services, version: 1.5.1-beta1 Jul 19 11:59:05 rivovcx vtcs[6071]: STARTED TidalConnect services. Jul 19 11:59:05 rivovcx sudo[6141]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:05 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6150, instance 2, run: true Jul 19 11:59:05 rivovcx sudo[6151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 19 11:59:05 rivovcx sudo[6151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:05 rivovcx volumio[3114]: info: TidalConnect service started! Jul 19 11:59:05 rivovcx systemd[1]: Stopping RAAT DAEMON... Jul 19 11:59:05 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6159, instance 1, run: true Jul 19 11:59:05 rivovcx volumio[3114]: info: Starting Shairport Sync Jul 19 11:59:05 rivovcx systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 19 11:59:05 rivovcx systemd[1]: raat-daemon.service: Succeeded. Jul 19 11:59:05 rivovcx systemd[1]: Stopped RAAT DAEMON. Jul 19 11:59:05 rivovcx systemd[1]: Started RAAT DAEMON. Jul 19 11:59:05 rivovcx sudo[6151]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:05 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:05 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:05 rivovcx volumio[3114]: info: Executing endpoint tc_connect Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 19 11:59:05 rivovcx sudo[6168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 11:59:05 rivovcx sudo[6168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:05 rivovcx volumio[3114]: info: Connecting to TidalConnect Jul 19 11:59:05 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6177, instance 2, run: true Jul 19 11:59:05 rivovcx volumio[3114]: info: Raat Daemon started successfully Jul 19 11:59:05 rivovcx systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 11:59:05 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6188, instance 1, run: true Jul 19 11:59:05 rivovcx systemd[1]: shairport-sync.service: Succeeded. Jul 19 11:59:05 rivovcx volumio[3114]: info: Asound.conf file unchanged, so no further update is needed Jul 19 11:59:05 rivovcx volumio[3114]: info: Output device has changed, restarting MPD Jul 19 11:59:05 rivovcx systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 11:59:05 rivovcx systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 11:59:05 rivovcx sudo[6168]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:05 rivovcx sudo[6203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 11:59:05 rivovcx sudo[6203]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:05 rivovcx sudo[6203]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:05 rivovcx volumio[3114]: info: Output device has changed, restarting Shairport Sync Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 11:59:05 rivovcx sudo[6209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 11:59:05 rivovcx sudo[6209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:05 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:05 rivovcx volumio[3114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 11:59:05 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:05 rivovcx volumio[3114]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:05 rivovcx systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 19 11:59:05 rivovcx systemd[1]: mpd.service: Succeeded. Jul 19 11:59:05 rivovcx systemd[1]: Stopped Music Player Daemon. Jul 19 11:59:05 rivovcx systemd[1]: Starting Music Player Daemon... Jul 19 11:59:05 rivovcx volumio[3114]: info: QobuzConnect: setDeactiveState invoked Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:05 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:05 rivovcx volumio[3114]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 19 11:59:05 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:05 rivovcx vtcs[6071]: [2025-07-19 11:59:05.905] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=7 errorno=104 error=Connection reset by peer Jul 19 11:59:05 rivovcx vtcs[6071]: [2025-07-19 11:59:05.906] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jul 19 11:59:05 rivovcx vtcs[6071]: [2025-07-19 11:59:05.907] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jul 19 11:59:05 rivovcx sudo[6218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 11:59:05 rivovcx sudo[6218]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:05 rivovcx sudo[6221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 11:59:05 rivovcx sudo[6221]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:05 rivovcx systemd[1]: Stopping Volumio Tidal Connect Service... Jul 19 11:59:06 rivovcx systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jul 19 11:59:06 rivovcx systemd[1]: vtcs.service: Succeeded. Jul 19 11:59:06 rivovcx systemd[1]: Stopped Volumio Tidal Connect Service. Jul 19 11:59:06 rivovcx sudo[6218]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:06 rivovcx sudo[6221]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:06 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:06 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:06 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:06 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:06 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:06 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:06 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:06 rivovcx sudo[6214]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 11:59:06 rivovcx sudo[6214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:06 rivovcx sudo[6228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 19 11:59:06 rivovcx sudo[6228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:06 rivovcx sudo[6214]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:06 rivovcx volumio[3114]: info: Executing endpoint restartRAATSocket Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 19 11:59:06 rivovcx sudo[6228]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:06 rivovcx volumio[3114]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 19 11:59:06 rivovcx volumio[3114]: info: MPD Permissions set Jul 19 11:59:06 rivovcx volumio[3114]: info: Shairport-Sync Started Jul 19 11:59:06 rivovcx sudo[6232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 19 11:59:06 rivovcx sudo[6232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:06 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6235, instance 1, run: true Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreStateMachine::pushState Jul 19 11:59:06 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::volumioPushState Jul 19 11:59:06 rivovcx qobuz-connect[6062]: 20250719 11:59:06.207 [6062.6062] INFO SampleApp: Stopping Local configuration server Jul 19 11:59:06 rivovcx systemd[1]: Stopping Volumio Qobuz Connect Service... Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:06 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:06 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output update for this device Jul 19 11:59:06 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output Jul 19 11:59:06 rivovcx qobuz-connect[6062]: 20250719 11:59:06.218 [6062.6062] INFO SampleApp: shat down connection on UNIX socket Jul 19 11:59:06 rivovcx systemd[1]: qobuz-connect.service: Succeeded. Jul 19 11:59:06 rivovcx systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 19 11:59:06 rivovcx systemd[1]: Started Volumio Qobuz Connect Service. Jul 19 11:59:06 rivovcx sudo[6232]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:06 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:06 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:06 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6244, instance 2, run: true Jul 19 11:59:06 rivovcx volumio[3114]: info: RAAT: Requesting Headphone Status Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 19 11:59:06 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:06 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:06 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:06 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:06 rivovcx volumio[3114]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jul 19 11:59:06 rivovcx volumio[3114]: info: QobuzConnect: setDeactiveState invoked Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:06 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:06 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6253, instance 1, run: true Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6270, instance 2, run: true Jul 19 11:59:06 rivovcx volumio[3114]: info: MCU Signalled Headphone Mode Disabled Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 19 11:59:06 rivovcx volumio[3114]: info: Reporting Headphone State: false Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:06 rivovcx volumio[3114]: info: Updating RAAT Signal Path Jul 19 11:59:06 rivovcx volumio[3114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jul 19 11:59:06 rivovcx volumio[3114]: info: Executing endpoint qc_getconfig Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 19 11:59:06 rivovcx qobuz-connect[6243]: 20250719 11:59:06.663 [6243.6243] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 19 11:59:06 rivovcx qobuz-connect[6243]: 20250719 11:59:06.671 [6243.6243] INFO VolumeManager: [0xabd12920]: Setting new playback volume: 75 Jul 19 11:59:06 rivovcx qobuz-connect[6243]: 20250719 11:59:06.671 [6243.6243] INFO VolumeManager: [0xabd12920]: Setting new mute state: 0 Jul 19 11:59:06 rivovcx qobuz-connect[6243]: 20250719 11:59:06.671 [6243.6243] INFO QobuzConnect: [0xabd132f0]: Client initialized! Jul 19 11:59:06 rivovcx qobuz-connect[6243]: 20250719 11:59:06.671 [6243.6243] INFO SampleApp: Starting Avahi advertising, name: RivoVcx, service name: _qobuz-connect._tcp Jul 19 11:59:06 rivovcx volumio[3114]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 19 11:59:06 rivovcx volumio[3114]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 19 11:59:06 rivovcx qobuz-connect[6243]: 20250719 11:59:06.698 [6243.6243] INFO LocalConfigManager: [0xabd12200]: Starting Local Configuration server Jul 19 11:59:06 rivovcx qobuz-connect[6243]: 20250719 11:59:06.699 [6243.6243] INFO SampleApp: Starting Local configuration server Jul 19 11:59:06 rivovcx qobuz-connect[6243]: 20250719 11:59:06.700 [6243.6243] INFO SampleApp: Connected to UNIX socket client 0xabd07ed8 Jul 19 11:59:06 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6278, instance 1, run: true Jul 19 11:59:06 rivovcx volumio[3114]: info: Starting Shairport Sync Jul 19 11:59:06 rivovcx sudo[6286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 11:59:06 rivovcx sudo[6286]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:06 rivovcx qobuz-connect[6243]: 20250719 11:59:06.813 [6243.6243] INFO SampleApp: Playback volume changed: 75 Jul 19 11:59:06 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6287, instance 2, run: true Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 19 11:59:06 rivovcx systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 11:59:06 rivovcx systemd[1]: shairport-sync.service: Succeeded. Jul 19 11:59:06 rivovcx systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 11:59:06 rivovcx systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 11:59:06 rivovcx sudo[6286]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:06 rivovcx volumio[3114]: xcb_connection_has_error() returned true Jul 19 11:59:06 rivovcx volumio[3114]: info: MCU Signalled Playback Active Jul 19 11:59:06 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:06 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:07 rivovcx sudo[6300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 19 11:59:07 rivovcx sudo[6300]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:07 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6302, instance 1, run: true Jul 19 11:59:07 rivovcx sudo[6300]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:07 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:07 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:07 rivovcx volumio[3114]: info: Shairport-Sync Started Jul 19 11:59:07 rivovcx sudo[6310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 19 11:59:07 rivovcx sudo[6310]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:07 rivovcx systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 19 11:59:07 rivovcx systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 3. Jul 19 11:59:07 rivovcx systemd[1]: Stopped FusionDsp Daemon. Jul 19 11:59:07 rivovcx systemd[1]: Started FusionDsp Daemon. Jul 19 11:59:07 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6313, instance 2, run: true Jul 19 11:59:07 rivovcx systemd[1]: Stopping RAAT DAEMON... Jul 19 11:59:07 rivovcx systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 19 11:59:07 rivovcx systemd[1]: raat-daemon.service: Succeeded. Jul 19 11:59:07 rivovcx systemd[1]: Stopped RAAT DAEMON. Jul 19 11:59:07 rivovcx volumio[3114]: info: TidalConnect service stoped! Jul 19 11:59:07 rivovcx systemd[1]: Started RAAT DAEMON. Jul 19 11:59:07 rivovcx sudo[6310]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:07 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6322, instance 1, run: true Jul 19 11:59:07 rivovcx volumio[6314]: Traceback (most recent call last): Jul 19 11:59:07 rivovcx volumio[6314]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 19 11:59:07 rivovcx volumio[6314]: from aiohttp import web Jul 19 11:59:07 rivovcx volumio[6314]: ModuleNotFoundError: No module named 'aiohttp' Jul 19 11:59:07 rivovcx systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 19 11:59:07 rivovcx systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 19 11:59:07 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 11:59:07 rivovcx volumio[3114]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 11:59:07 rivovcx volumio[3114]: info: Raat Daemon started successfully Jul 19 11:59:07 rivovcx volumio[3114]: info: TidalConnect service stoped! Jul 19 11:59:07 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6342, instance 2, run: true Jul 19 11:59:07 rivovcx volumio[3114]: info: Executing endpoint restartRAATSocket Jul 19 11:59:07 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 19 11:59:07 rivovcx volumio[3114]: info: TidalConnect service started! Jul 19 11:59:07 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6354, instance 1, run: true Jul 19 11:59:07 rivovcx volumio[3114]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 19 11:59:07 rivovcx volumio[3114]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 19 11:59:07 rivovcx sudo[6362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 19 11:59:07 rivovcx sudo[6362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:07 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6365, instance 2, run: true Jul 19 11:59:07 rivovcx systemd[1]: Started Volumio Tidal Connect Service. Jul 19 11:59:07 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:07 rivovcx sudo[6362]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:07 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6371, instance 1, run: true Jul 19 11:59:07 rivovcx volumio[3114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jul 19 11:59:07 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6382, instance 2, run: true Jul 19 11:59:07 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6389, instance 1, run: true Jul 19 11:59:07 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6396, instance 2, run: true Jul 19 11:59:08 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6405, instance 1, run: true Jul 19 11:59:08 rivovcx volumio[3114]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/rivoplus/buster/armhf Jul 19 11:59:08 rivovcx volumio[3114]: info: FusionDsp - Stopping FusionDsp service Jul 19 11:59:08 rivovcx volumio[3114]: info: camilladsp service terminated, instance 2 Jul 19 11:59:08 rivovcx sudo[6414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jul 19 11:59:08 rivovcx sudo[6414]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:08 rivovcx volumio[3114]: info: Executing endpoint tc_getconfig Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 19 11:59:08 rivovcx vtcs[6366]: STARTING TidalConnect services, version: 1.5.1-beta1 Jul 19 11:59:08 rivovcx volumio[3114]: info: TidalConnect service started! Jul 19 11:59:08 rivovcx vtcs[6366]: STARTED TidalConnect services. Jul 19 11:59:08 rivovcx systemd[1]: Stopped FusionDsp Daemon. Jul 19 11:59:08 rivovcx sudo[6414]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:08 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6425, instance 1, run: true Jul 19 11:59:08 rivovcx volumio[3114]: info: FusionDsp - Reporting Fusion DSP Disabled Jul 19 11:59:08 rivovcx volumio[3114]: info: Removing fusiondspeq DSP Signal Path Element Jul 19 11:59:08 rivovcx volumio[3114]: info: Additional DSP elements updated Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:08 rivovcx volumio[3114]: info: Updating RAAT Signal Path Jul 19 11:59:08 rivovcx volumio[3114]: info: RAAT: Requesting Headphone Status Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 19 11:59:08 rivovcx volumio[3114]: info: Executing endpoint tc_connect Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 19 11:59:08 rivovcx volumio[3114]: info: Connecting to TidalConnect Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::servicePushState Jul 19 11:59:08 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:08 rivovcx volumio[3114]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"A Beautiful Day","artist":"Schiller","album":"Timeline","albumart":"https://resources.tidal.com/images/03c52978/c0dd/4f51/a9bc/6c66a1751892/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":100354,"duration":224,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":1411200,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Jul 19 11:59:08 rivovcx volumio[3114]: verbose: CURRENT POSITION 0 Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreStateMachine::syncState stateService pause Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreStateMachine::syncState currentStatus play Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreStateMachine::pushState Jul 19 11:59:08 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::volumioPushState Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:08 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:08 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output update for this device Jul 19 11:59:08 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::servicePushState Jul 19 11:59:08 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:08 rivovcx volumio[3114]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"A Beautiful Day","artist":"Schiller","album":"Timeline","albumart":"https://resources.tidal.com/images/03c52978/c0dd/4f51/a9bc/6c66a1751892/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":100354,"duration":224,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":1411200,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Jul 19 11:59:08 rivovcx volumio[3114]: verbose: CURRENT POSITION 0 Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreStateMachine::syncState stateService pause Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreStateMachine::syncState currentStatus play Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreStateMachine::pushState Jul 19 11:59:08 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::volumioPushState Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::volumioGetState Jul 19 11:59:08 rivovcx volumio[3114]: info: CorePlayQueue::getTrack 0 Jul 19 11:59:08 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output update for this device Jul 19 11:59:08 rivovcx volumio[3114]: info: MRS: Pushing multiroomSync output Jul 19 11:59:08 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:08 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:08 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:08 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:08 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:08 rivovcx volumio[3114]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 19 11:59:08 rivovcx volumio[3114]: info: MCU Signalled Headphone Mode Disabled Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 19 11:59:08 rivovcx volumio[3114]: info: Reporting Headphone State: false Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:08 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 11:59:08 rivovcx volumio[3114]: info: Updating RAAT Signal Path Jul 19 11:59:08 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6440, instance 1, run: true Jul 19 11:59:08 rivovcx volumio[3114]: info: MCU Signalled Playback Inactive Jul 19 11:59:08 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6447, instance 1, run: true Jul 19 11:59:08 rivovcx mpd[6234]: Jul 19 11:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 11:59:08 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6454, instance 1, run: true Jul 19 11:59:08 rivovcx systemd[1]: Started Music Player Daemon. Jul 19 11:59:08 rivovcx sudo[5755]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:08 rivovcx sudo[5878]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:08 rivovcx sudo[6209]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:08 rivovcx sudo[5683]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:08 rivovcx sudo[6030]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:08 rivovcx volumio[3114]: error: MPD error: The expression evaluated to a falsy value: Jul 19 11:59:08 rivovcx volumio[3114]: assert.ok(self.idling) Jul 19 11:59:08 rivovcx volumio[3114]: error: The expression evaluated to a falsy value: Jul 19 11:59:08 rivovcx volumio[3114]: assert.ok(self.idling) Jul 19 11:59:08 rivovcx volumio[3114]: error: MPD error: The expression evaluated to a falsy value: Jul 19 11:59:08 rivovcx volumio[3114]: assert.ok(self.idling) Jul 19 11:59:08 rivovcx volumio[3114]: error: The expression evaluated to a falsy value: Jul 19 11:59:08 rivovcx volumio[3114]: assert.ok(self.idling) Jul 19 11:59:08 rivovcx volumio[3114]: error: MPD error: The expression evaluated to a falsy value: Jul 19 11:59:08 rivovcx volumio[3114]: assert.ok(self.idling) Jul 19 11:59:08 rivovcx volumio[3114]: error: The expression evaluated to a falsy value: Jul 19 11:59:08 rivovcx volumio[3114]: assert.ok(self.idling) Jul 19 11:59:08 rivovcx volumio[3114]: error: MPD error: The expression evaluated to a falsy value: Jul 19 11:59:08 rivovcx volumio[3114]: assert.ok(self.idling) Jul 19 11:59:08 rivovcx volumio[3114]: error: The expression evaluated to a falsy value: Jul 19 11:59:08 rivovcx volumio[3114]: assert.ok(self.idling) Jul 19 11:59:08 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6464, instance 1, run: true Jul 19 11:59:08 rivovcx volumio[3114]: error: updateQueue error: null Jul 19 11:59:08 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6471, instance 1, run: true Jul 19 11:59:08 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6478, instance 1, run: true Jul 19 11:59:08 rivovcx volumio[3114]: info: Check plugin dependencies Jul 19 11:59:09 rivovcx volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 11:59:09 rivovcx volumio[3114]: info: Rename folder Jul 19 11:59:09 rivovcx volumio[3114]: info: TidalConnect service stoped! Jul 19 11:59:09 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6490, instance 1, run: true Jul 19 11:59:09 rivovcx volumio[3114]: info: Folder /tmp/downloaded_plugin.zip removed Jul 19 11:59:09 rivovcx volumio[3114]: info: TidalConnect service stoped! Jul 19 11:59:09 rivovcx volumio[3114]: info: Move to category Jul 19 11:59:09 rivovcx volumio[3114]: info: camilladsp spawned new process with pid 6505, instance 1, run: true Jul 19 11:59:09 rivovcx volumio[3114]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 19 11:59:09 rivovcx volumio[3114]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 19 11:59:09 rivovcx sudo[6514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 19 11:59:09 rivovcx sudo[6514]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 11:59:09 rivovcx sudo[6514]: pam_unix(sudo:session): session closed for user root Jul 19 11:59:09 rivovcx volumio[3114]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jul 19 11:59:09 rivovcx volumio[3114]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 11:59:09 rivovcx volumio[3114]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 19 11:59:09 rivovcx volumio[3114]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Jul 19 11:59:09 rivovcx volumio[3114]: at onErrorNT (internal/child_process.js:472:16) Jul 19 11:59:09 rivovcx volumio[3114]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Jul 19 11:59:09 rivovcx volumio[3114]: errno: -2, Jul 19 11:59:09 rivovcx volumio[3114]: code: 'ENOENT', Jul 19 11:59:09 rivovcx volumio[3114]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 19 11:59:09 rivovcx volumio[3114]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 19 11:59:09 rivovcx volumio[3114]: spawnargs: [ Jul 19 11:59:09 rivovcx volumio[3114]: '-p', Jul 19 11:59:09 rivovcx volumio[3114]: 9876, Jul 19 11:59:09 rivovcx volumio[3114]: '-o', Jul 19 11:59:09 rivovcx volumio[3114]: '/tmp/camilladsp.log', Jul 19 11:59:09 rivovcx volumio[3114]: '-l', Jul 19 11:59:09 rivovcx volumio[3114]: 'warn', Jul 19 11:59:09 rivovcx volumio[3114]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 19 11:59:09 rivovcx volumio[3114]: ] Jul 19 11:59:09 rivovcx volumio[3114]: } Jul 19 11:59:09 rivovcx volumio[3114]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 11:59:10 rivovcx sudo[6527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-19 11:58 Jul 19 11:59:10 rivovcx sudo[6527]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:55:44 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="66719bc94798fca30ca68a8e6d955a83"