-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-04-20 23:13:07 CEST. -- Apr 20 23:12:00 server001 volumio[878]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... Apr 20 23:12:00 server001 volumio[878]: info: Setting up python3-secretstorage (2.3.1-2) ... Apr 20 23:12:00 server001 volumio[878]: info: Setting up python3-dev (3.7.3-1) ... Apr 20 23:12:00 server001 volumio[878]: info: Setting up python3-keyring (17.1.1-1) ... Apr 20 23:12:01 server001 volumio[878]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... Apr 20 23:12:03 server001 sudo[27468]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:03 server001 sudo[28131]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Apr 20 23:12:03 server001 sudo[28131]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:03 server001 volumio[878]: info: lib/systemd/system/fusiondsp.service Apr 20 23:12:03 server001 sudo[28131]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:06 server001 kernel: usb 1-1.5: new high-speed USB device number 6 using dwc_otg Apr 20 23:12:06 server001 kernel: usb 1-1.5: New USB device found, idVendor=2752, idProduct=0011, bcdDevice= 1.75 Apr 20 23:12:06 server001 kernel: usb 1-1.5: New USB device strings: Mfr=1, Product=11, SerialNumber=0 Apr 20 23:12:06 server001 kernel: usb 1-1.5: Product: miniDSP 2x4HD Apr 20 23:12:06 server001 kernel: usb 1-1.5: Manufacturer: miniDSP Apr 20 23:12:06 server001 kernel: hid-generic 0003:2752:0011.0003: hiddev96,hidraw0: USB HID v1.10 Device [miniDSP miniDSP 2x4HD] on usb-3f980000.usb-1.5/input4 Apr 20 23:12:07 server001 volumio[878]: info: Apr 20 23:12:07 server001 volumio[878]: ---------------------------- USB Audio Device Attached Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 20 23:12:07 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::Close All Modals sent Apr 20 23:12:07 server001 volumio[878]: info: Preparing to save Alsa Options, stopping services first Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::volumioGetState Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::volumioPause Apr 20 23:12:07 server001 volumio[878]: info: CoreStateMachine::pause Apr 20 23:12:07 server001 volumio[878]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"miniDSP 2x4HD","alsacard":"m2x4HD"},"i2s":false} Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 20 23:12:07 server001 sudo[28148]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Apr 20 23:12:07 server001 sudo[28148]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:07 server001 sudo[28148]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:07 server001 sudo[28152]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Apr 20 23:12:07 server001 sudo[28152]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:07 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:12:07 server001 sudo[28152]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:07 server001 sudo[28155]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Apr 20 23:12:07 server001 sudo[28155]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:07 server001 sudo[28155]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:07 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 20 23:12:07 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:12:07 server001 volumio[878]: info: Setting mixer miniDSP 2x4HD for card miniDSP 2x4HD Apr 20 23:12:07 server001 vtcs[19217]: [2025-04-20 23:12:07.309] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 20 23:12:07 server001 vtcs[19217]: [2025-04-20 23:12:07.311] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 20 23:12:07 server001 sudo[28162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 20 23:12:07 server001 sudo[28162]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:07 server001 systemd[1]: Stopping Volumio Tidal Connect Service... Apr 20 23:12:07 server001 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 20 23:12:07 server001 systemd[1]: vtcs.service: Succeeded. Apr 20 23:12:07 server001 systemd[1]: Stopped Volumio Tidal Connect Service. Apr 20 23:12:07 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 20 23:12:07 server001 volumio[878]: info: Updating Volume Controller Parameters: Device: 5 Name: miniDSP 2x4HD Mixer: miniDSP 2x4HD Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 20 23:12:07 server001 volumio[878]: info: Disabling external Volume Control Apr 20 23:12:07 server001 sudo[28162]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 23:12:07 server001 volumio[878]: info: Preparing to generate the ALSA configuration file Apr 20 23:12:07 server001 volumio[878]: info: Executing endpoint scanAudioInputs Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: smart_inputs , scanAudioInputs Apr 20 23:12:07 server001 volumio[878]: info: Scanning Audio Inputs Apr 20 23:12:07 server001 sudo[28169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 20 23:12:07 server001 sudo[28169]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:07 server001 sudo[28169]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:07 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:12:07 server001 volumio[878]: info: Checking against Known Cards name Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 23:12:07 server001 volumio[878]: info: [1745183527603] CoreMusicLibrary::Adding element miniDSP 2x4HD Apr 20 23:12:07 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 23:12:07 server001 volumio[878]: Cannot find translation for source QOBUZ Apr 20 23:12:07 server001 volumio[878]: Cannot find translation for source miniDSP 2x4HD Apr 20 23:12:07 server001 volumio[878]: info: Checking against Known Cards name Apr 20 23:12:07 server001 volumio[878]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 20 23:12:07 server001 volumio[878]: info: Reading ALSA contributions from plugins. Apr 20 23:12:07 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 23:12:07 server001 volumio[878]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Apr 20 23:12:07 server001 volumio[878]: info: more info at http://www.winimage.com/zLibDll/unzip.html Apr 20 23:12:07 server001 volumio[878]: info: Apr 20 23:12:07 server001 volumio[878]: info: cgui-1.0.0.zip opened Apr 20 23:12:07 server001 volumio[878]: info: creating directory: cgui/ Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/LICENSE.txt Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/README.md Apr 20 23:12:07 server001 volumio[878]: info: creating directory: cgui/build/ Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/robots.txt Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/logo512.png Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/asset-manifest.json Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/index.html Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/.put_statics_here Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/favicon.ico Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/logo192.png Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/manifest.json Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/css-variables.css Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/service-worker.js Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Apr 20 23:12:07 server001 volumio[878]: info: creating directory: cgui/build/static/ Apr 20 23:12:07 server001 volumio[878]: info: creating directory: cgui/build/static/css/ Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/css/main.1503f6cd.css Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Apr 20 23:12:07 server001 volumio[878]: info: creating directory: cgui/build/static/media/ Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Apr 20 23:12:07 server001 volumio[878]: info: creating directory: cgui/build/static/js/ Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/main.71e11955.js Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/main.71e11955.js.map Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Apr 20 23:12:07 server001 volumio[878]: info: creating directory: cgui/config/ Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/config/camillagui.yml Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/config/gui-config.yml Apr 20 23:12:07 server001 volumio[878]: info: creating directory: cgui/backend/ Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/filters_test.py Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/version.py Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/filters.py Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/settings.py Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/views.py Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/filterdefaults.py Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/__init__.py Apr 20 23:12:07 server001 volumio[878]: info: creating directory: cgui/backend/__pycache__/ Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/routes.py Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/backend/filemanagement.py Apr 20 23:12:07 server001 volumio[878]: info: extracting: cgui/main.py Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 20 23:12:08 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 20 23:12:08 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 20 23:12:08 server001 volumio[878]: info: VolumeController:: Volume=30 Mute =false Apr 20 23:12:08 server001 volumio[878]: info: CoreStateMachine::pushState Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::volumioPushState Apr 20 23:12:08 server001 volumio[878]: info: MRS: Pushing multiroomSync output update for this device Apr 20 23:12:08 server001 volumio[878]: info: MRS: Pushing multiroomSync output Apr 20 23:12:08 server001 volumio[878]: info: Asound.conf file unchanged, so no further update is needed Apr 20 23:12:08 server001 volumio[878]: info: Output device has changed, restarting MPD Apr 20 23:12:08 server001 sudo[28194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 23:12:08 server001 sudo[28194]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:08 server001 volumio[878]: info: Output device has changed, restarting Shairport Sync Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 23:12:08 server001 sudo[28194]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:08 server001 sudo[28197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 23:12:08 server001 sudo[28197]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:08 server001 systemd[1]: Stopping Music Player Daemon... Apr 20 23:12:08 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:12:08 server001 volumio[878]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 23:12:08 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:12:08 server001 volumio[878]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:12:08 server001 sudo[28205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 20 23:12:08 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 23:12:08 server001 sudo[28205]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:08 server001 volumio[878]: info: MPD Permissions set Apr 20 23:12:08 server001 sudo[28207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 20 23:12:08 server001 sudo[28207]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:08 server001 volumio[878]: info: Starting Shairport Sync Apr 20 23:12:08 server001 sudo[28205]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:08 server001 sudo[28212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 23:12:08 server001 sudo[28212]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:12:08 server001 sudo[28207]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:08 server001 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 23:12:08 server001 systemd[1]: shairport-sync.service: Succeeded. Apr 20 23:12:08 server001 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 23:12:08 server001 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 23:12:08 server001 sudo[28212]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:08 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:12:08 server001 systemd[1]: mpd.service: Succeeded. Apr 20 23:12:08 server001 systemd[1]: Stopped Music Player Daemon. Apr 20 23:12:08 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 23:12:08 server001 systemd[1]: Starting Music Player Daemon... Apr 20 23:12:08 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 23:12:08 server001 volumio[878]: info: Shairport-Sync Started Apr 20 23:12:08 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 23:12:08 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 23:12:09 server001 sudo[28221]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 20 23:12:09 server001 sudo[28221]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:09 server001 sudo[28221]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:10 server001 volumio[878]: info: TidalConnect service stoped! Apr 20 23:12:10 server001 volumio[878]: info: TidalConnect service stoped! Apr 20 23:12:10 server001 volumio[878]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 20 23:12:10 server001 volumio[878]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 20 23:12:10 server001 sudo[28235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 20 23:12:10 server001 sudo[28235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:11 server001 systemd[1]: Started Volumio Tidal Connect Service. Apr 20 23:12:11 server001 sudo[28235]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:11 server001 mpd[28225]: Apr 20 23:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 23:12:11 server001 volumio[878]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 20 23:12:11 server001 volumio[878]: info: CoreStateMachine::getcurrentVolume Apr 20 23:12:11 server001 volumio[878]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 23:12:11 server001 volumio[878]: info: Executing endpoint tc_getconfig Apr 20 23:12:11 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 20 23:12:11 server001 vtcs[28237]: STARTING TidalConnect services, version: 1.5.0.45 Apr 20 23:12:11 server001 volumio[878]: info: VolumeController:: Volume=30 Mute =false Apr 20 23:12:11 server001 volumio[878]: info: CoreStateMachine::pushState Apr 20 23:12:11 server001 volumio[878]: info: CoreCommandRouter::volumioPushState Apr 20 23:12:11 server001 volumio[878]: info: MRS: Pushing multiroomSync output update for this device Apr 20 23:12:11 server001 volumio[878]: info: MRS: Pushing multiroomSync output Apr 20 23:12:11 server001 systemd[1]: Started Music Player Daemon. Apr 20 23:12:11 server001 volumio[878]: info: CoreStateMachine::updateTrackBlock Apr 20 23:12:11 server001 volumio[878]: info: CorePlayQueue::getTrackBlock Apr 20 23:12:11 server001 volumio[878]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 23:12:11 server001 sudo[28197]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:11 server001 volumio[878]: error: updateQueue error: null Apr 20 23:12:11 server001 volumio[878]: info: VolumeController:: Volume=30 Mute =false Apr 20 23:12:11 server001 volumio[878]: info: CoreStateMachine::pushState Apr 20 23:12:11 server001 volumio[878]: info: CoreCommandRouter::volumioPushState Apr 20 23:12:11 server001 volumio[878]: info: MRS: Pushing multiroomSync output update for this device Apr 20 23:12:11 server001 volumio[878]: info: MRS: Pushing multiroomSync output Apr 20 23:12:11 server001 volumio[878]: info: TidalConnect service stoped! Apr 20 23:12:11 server001 volumio[878]: info: TidalConnect service stoped! Apr 20 23:12:11 server001 volumio[878]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 20 23:12:11 server001 volumio[878]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 20 23:12:11 server001 sudo[28256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 20 23:12:11 server001 sudo[28256]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:11 server001 sudo[28256]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:11 server001 vtcs[28237]: STARTED TidalConnect services. Apr 20 23:12:11 server001 volumio[878]: info: Executing endpoint tc_connect Apr 20 23:12:11 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 20 23:12:11 server001 volumio[878]: info: Connecting to TidalConnect Apr 20 23:12:11 server001 volumio[878]: info: CoreCommandRouter::servicePushState Apr 20 23:12:11 server001 volumio[878]: info: CoreStateMachine::pushState Apr 20 23:12:11 server001 volumio[878]: info: CoreCommandRouter::volumioPushState Apr 20 23:12:12 server001 volumio[878]: info: MRS: Pushing multiroomSync output update for this device Apr 20 23:12:12 server001 volumio[878]: info: MRS: Pushing multiroomSync output Apr 20 23:12:12 server001 volumio[878]: info: CorePlayQueue::getTrack 10 Apr 20 23:12:12 server001 volumio[878]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 20 23:12:12 server001 volumio[878]: info: CoreCommandRouter::servicePushState Apr 20 23:12:12 server001 volumio[878]: info: CoreStateMachine::pushState Apr 20 23:12:12 server001 volumio[878]: info: CoreCommandRouter::volumioPushState Apr 20 23:12:12 server001 volumio[878]: info: MRS: Pushing multiroomSync output update for this device Apr 20 23:12:12 server001 volumio[878]: info: MRS: Pushing multiroomSync output Apr 20 23:12:12 server001 volumio[878]: info: CorePlayQueue::getTrack 10 Apr 20 23:12:12 server001 volumio[878]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 20 23:12:14 server001 volumio[878]: info: TidalConnect service started! Apr 20 23:12:14 server001 volumio[878]: info: TidalConnect service started! Apr 20 23:12:15 server001 volumio[878]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Apr 20 23:12:15 server001 volumio[878]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Apr 20 23:12:15 server001 volumio[878]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-8_4r4hqc Apr 20 23:12:19 server001 volumio[878]: info: Collecting PyYAML (from camilladsp==1.0.0) Apr 20 23:12:21 server001 volumio[878]: info: Downloading https://www.piwheels.org/simple/pyyaml/PyYAML-6.0.1-cp37-cp37m-linux_armv7l.whl (45kB) Apr 20 23:12:21 server001 volumio[878]: info: Collecting websocket_client (from camilladsp==1.0.0) Apr 20 23:12:22 server001 volumio[878]: info: Downloading https://files.pythonhosted.org/packages/d3/a3/63e9329c8cc9be6153e919e17d0ef5b60d537fed78564872951b95bcc17c/websocket_client-1.6.1-py3-none-any.whl (56kB) Apr 20 23:12:22 server001 volumio[878]: info: Building wheels for collected packages: camilladsp Apr 20 23:12:22 server001 volumio[878]: info: Running setup.py bdist_wheel for camilladsp: started Apr 20 23:12:24 server001 volumio[878]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Apr 20 23:12:24 server001 volumio[878]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-pomnobc5/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Apr 20 23:12:24 server001 volumio[878]: info: Successfully built camilladsp Apr 20 23:12:24 server001 volumio[878]: info: Installing collected packages: PyYAML, websocket-client, camilladsp Apr 20 23:12:25 server001 volumio[878]: info: Successfully installed PyYAML-6.0.1 camilladsp-1.0.0 websocket-client-1.6.1 Apr 20 23:12:32 server001 volumio[878]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Apr 20 23:12:32 server001 volumio[878]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Apr 20 23:12:32 server001 volumio[878]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-dj3j24wh Apr 20 23:12:34 server001 volumio[878]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Apr 20 23:12:34 server001 volumio[878]: info: Collecting jsonschema (from camilladsp-plot==1.0.2) Apr 20 23:12:36 server001 volumio[878]: info: Downloading https://files.pythonhosted.org/packages/c1/97/c698bd9350f307daad79dd740806e1a59becd693bd11443a0f531e3229b3/jsonschema-4.17.3-py3-none-any.whl (90kB) Apr 20 23:12:36 server001 volumio[878]: info: Collecting importlib-resources>=1.4.0; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Apr 20 23:12:37 server001 volumio[878]: info: Downloading https://files.pythonhosted.org/packages/38/71/c13ea695a4393639830bf96baea956538ba7a9d06fcce7cef10bfff20f72/importlib_resources-5.12.0-py3-none-any.whl Apr 20 23:12:37 server001 volumio[878]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Apr 20 23:12:37 server001 volumio[878]: info: Collecting typing-extensions; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Apr 20 23:12:38 server001 volumio[878]: info: Downloading https://files.pythonhosted.org/packages/ec/6b/63cc3df74987c36fe26157ee12e09e8f9db4de771e0f3404263117e75b95/typing_extensions-4.7.1-py3-none-any.whl Apr 20 23:12:38 server001 volumio[878]: info: Collecting importlib-metadata; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Apr 20 23:12:39 server001 volumio[878]: info: Downloading https://files.pythonhosted.org/packages/ff/94/64287b38c7de4c90683630338cf28f129decbba0a44f0c6db35a873c73c4/importlib_metadata-6.7.0-py3-none-any.whl Apr 20 23:12:40 server001 volumio[878]: info: Collecting pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 (from jsonschema->camilladsp-plot==1.0.2) Apr 20 23:12:41 server001 volumio[878]: info: Downloading https://www.piwheels.org/simple/pyrsistent/pyrsistent-0.19.3-cp37-cp37m-linux_armv7l.whl (83kB) Apr 20 23:12:42 server001 volumio[878]: info: Collecting pkgutil-resolve-name>=1.3.10; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Apr 20 23:12:42 server001 volumio[878]: info: Downloading https://files.pythonhosted.org/packages/c9/5c/3d4882ba113fd55bdba9326c1e4c62a15e674a2501de4869e6bd6301f87e/pkgutil_resolve_name-1.3.10-py3-none-any.whl Apr 20 23:12:42 server001 volumio[878]: info: Collecting zipp>=3.1.0; python_version < "3.10" (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) Apr 20 23:12:43 server001 volumio[878]: info: Downloading https://files.pythonhosted.org/packages/5b/fa/c9e82bbe1af6266adf08afb563905eb87cab83fde00a0a08963510621047/zipp-3.15.0-py3-none-any.whl Apr 20 23:12:43 server001 volumio[878]: info: Building wheels for collected packages: camilladsp-plot Apr 20 23:12:43 server001 volumio[878]: info: Running setup.py bdist_wheel for camilladsp-plot: started Apr 20 23:12:44 server001 volumio[878]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Apr 20 23:12:44 server001 volumio[878]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-dhxk7oqt/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Apr 20 23:12:44 server001 volumio[878]: info: Successfully built camilladsp-plot Apr 20 23:12:45 server001 volumio[878]: info: Installing collected packages: zipp, importlib-resources, typing-extensions, importlib-metadata, pyrsistent, pkgutil-resolve-name, jsonschema, camilladsp-plot Apr 20 23:12:46 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 20 23:12:47 server001 volumio[878]: info: Successfully installed camilladsp-plot-1.0.2 importlib-metadata-6.7.0 importlib-resources-5.12.0 jsonschema-4.17.3 pkgutil-resolve-name-1.3.10 pyrsistent-0.19.3 typing-extensions-4.7.1 zipp-3.15.0 Apr 20 23:12:47 server001 volumio[878]: info: copying hw detection script Apr 20 23:12:47 server001 volumio[878]: info: Detected cpu architecture as armv7l Apr 20 23:12:49 server001 sudo[28447]: 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 Apr 20 23:12:49 server001 sudo[28447]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:49 server001 sudo[28447]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:49 server001 sudo[28449]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Apr 20 23:12:49 server001 sudo[28449]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:49 server001 sudo[28449]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:49 server001 sudo[28451]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Apr 20 23:12:49 server001 sudo[28451]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:49 server001 volumio[878]: info: Reading package lists... Apr 20 23:12:50 server001 volumio[878]: info: Building dependency tree... Apr 20 23:12:50 server001 volumio[878]: info: Reading state information... Apr 20 23:12:50 server001 volumio[878]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Apr 20 23:12:51 server001 volumio[878]: info: The following NEW packages will be installed: Apr 20 23:12:51 server001 volumio[878]: info: drc Apr 20 23:12:52 server001 volumio[878]: info: 0 upgraded, 1 newly installed, 0 to remove and 2 not upgraded. Apr 20 23:12:52 server001 volumio[878]: info: Need to get 112 kB of archives. Apr 20 23:12:52 server001 volumio[878]: info: After this operation, 512 kB of additional disk space will be used. Apr 20 23:12:52 server001 volumio[878]: info: Get:1 http://ftp.cica.es/mirrors/Linux/raspbian/raspbian buster/main armhf drc armhf 3.2.2~dfsg0-2 [112 kB] Apr 20 23:12:53 server001 volumio[878]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Apr 20 23:12:53 server001 volumio[878]: info: Folder /tmp/plugins removed Apr 20 23:12:53 server001 volumio[878]: info: Fetched 112 kB in 0s (300 kB/s) Apr 20 23:12:53 server001 volumio[878]: info: Check plugin dependencies Apr 20 23:12:53 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 23:12:53 server001 volumio[878]: info: Checking if plugin already exists Apr 20 23:12:53 server001 volumio[878]: info: Rename folder Apr 20 23:12:53 server001 volumio[878]: info: Folder /tmp/downloaded_plugin.zip removed Apr 20 23:12:53 server001 volumio[878]: info: Selecting previously unselected package drc. Apr 20 23:12:53 server001 volumio[878]: info: Move to category Apr 20 23:12:54 server001 volumio[878]: [620B blob data] Apr 20 23:12:54 server001 volumio[878]: info: Preparing to unpack .../drc_3.2.2~dfsg0-2_armhf.deb ... Apr 20 23:12:54 server001 volumio[878]: info: Unpacking drc (3.2.2~dfsg0-2) ... Apr 20 23:12:55 server001 volumio[878]: info: Checking if install.sh is present Apr 20 23:12:55 server001 volumio[878]: info: Executing install.sh Apr 20 23:12:55 server001 sudo[28485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Apr 20 23:12:55 server001 sudo[28485]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:55 server001 volumio[878]: info: creating filters folder and copying demo filters Apr 20 23:12:55 server001 volumio[878]: info: creating filters folder and copying demo filters Apr 20 23:12:55 server001 volumio[878]: info: copying demo flters Apr 20 23:12:55 server001 volumio[878]: info: copying demo flters Apr 20 23:12:55 server001 volumio[878]: info: Apr 20 23:12:55 server001 volumio[878]: Setting up drc (3.2.2~dfsg0-2) ... Apr 20 23:12:55 server001 volumio[878]: info: Apr 20 23:12:55 server001 volumio[878]: Setting up drc (3.2.2~dfsg0-2) ... Apr 20 23:12:56 server001 sudo[28513]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Apr 20 23:12:56 server001 sudo[28513]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:12:57 server001 sudo[28451]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:57 server001 sudo[27227]: pam_unix(sudo:session): session closed for user root Apr 20 23:12:57 server001 volumio[878]: Plugin install end detected on script Apr 20 23:12:57 server001 volumio[878]: Plugin install end detected on script Apr 20 23:12:57 server001 volumio[878]: info: Install script completed Apr 20 23:12:57 server001 volumio[878]: info: Adding reference to registry Apr 20 23:12:57 server001 volumio[878]: info: Done installing plugin. Apr 20 23:12:57 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 23:12:57 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 20 23:12:57 server001 volumio[878]: info: Folder /tmp/plugins removed Apr 20 23:12:57 server001 volumio[878]: info: Folder /tmp/downloaded_plugin.zip removed Apr 20 23:12:57 server001 volumio[878]: info: Folder /data/temp removed Apr 20 23:13:01 server001 volumio[878]: info: Enabling plugin fusiondsp Apr 20 23:13:01 server001 volumio[878]: info: Loading plugin "fusiondsp"... Apr 20 23:13:01 server001 volumio[878]: info: Preparing to generate the ALSA configuration file Apr 20 23:13:01 server001 volumio[878]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 20 23:13:01 server001 volumio[878]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 20 23:13:01 server001 volumio[878]: info: Reading ALSA contributions from plugins. Apr 20 23:13:01 server001 volumio[878]: info: Asound.conf file written Apr 20 23:13:01 server001 sudo[28742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 20 23:13:01 server001 sudo[28742]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:01 server001 sudo[28742]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:01 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:13:01 server001 volumio[878]: info: Output device has changed, restarting MPD Apr 20 23:13:01 server001 sudo[28747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 23:13:01 server001 sudo[28747]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:01 server001 sudo[28747]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:01 server001 volumio[878]: info: Output device has changed, restarting Shairport Sync Apr 20 23:13:01 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:01 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 23:13:01 server001 sudo[28750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 23:13:01 server001 sudo[28750]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:01 server001 systemd[1]: Stopping Music Player Daemon... Apr 20 23:13:01 server001 systemd[1]: mpd.service: Succeeded. Apr 20 23:13:01 server001 systemd[1]: Stopped Music Player Daemon. Apr 20 23:13:01 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:13:01 server001 volumio[878]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 23:13:01 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:13:01 server001 volumio[878]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 20 23:13:01 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 vtcs[28237]: [2025-04-20 23:13:02.003] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 20 23:13:02 server001 vtcs[28237]: [2025-04-20 23:13:02.004] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 20 23:13:02 server001 vtcs[28237]: [2025-04-20 23:13:02.004] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Apr 20 23:13:02 server001 systemd[1]: Starting Music Player Daemon... Apr 20 23:13:02 server001 volumio[878]: info: PLUGIN START: fusiondsp Apr 20 23:13:02 server001 volumio[878]: info: Loading i18n strings for locale en Apr 20 23:13:02 server001 sudo[28759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 20 23:13:02 server001 sudo[28759]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 volumio[878]: info: FusionDsp - mixtype--------------------- Hardware Apr 20 23:13:02 server001 volumio[878]: info: Preparing to generate the ALSA configuration file Apr 20 23:13:02 server001 volumio[878]: info: Done. Apr 20 23:13:02 server001 systemd[1]: Stopping Volumio Tidal Connect Service... Apr 20 23:13:02 server001 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 20 23:13:02 server001 systemd[1]: vtcs.service: Succeeded. Apr 20 23:13:02 server001 systemd[1]: Stopped Volumio Tidal Connect Service. Apr 20 23:13:02 server001 sudo[28759]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:02 server001 volumio[878]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 20 23:13:02 server001 volumio[878]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 20 23:13:02 server001 volumio[878]: info: Reading ALSA contributions from plugins. Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 23:13:02 server001 sudo[28763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 20 23:13:02 server001 sudo[28755]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 20 23:13:02 server001 sudo[28755]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:02 server001 volumio[878]: info: MPD Permissions set Apr 20 23:13:02 server001 sudo[28763]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 sudo[28755]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 volumio[878]: info: Starting Shairport Sync Apr 20 23:13:02 server001 volumio[878]: info: Asound.conf file unchanged, so no further update is needed Apr 20 23:13:02 server001 sudo[28774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 23:13:02 server001 sudo[28774]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:02 server001 volumio[878]: info: Output device has changed, restarting MPD Apr 20 23:13:02 server001 sudo[28763]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:02 server001 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 23:13:02 server001 systemd[1]: shairport-sync.service: Succeeded. Apr 20 23:13:02 server001 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 23:13:02 server001 sudo[28778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 23:13:02 server001 sudo[28778]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:02 server001 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 23:13:02 server001 sudo[28778]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:02 server001 sudo[28774]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:02 server001 volumio[878]: info: Output device has changed, restarting Shairport Sync Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 23:13:02 server001 sudo[28782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 23:13:02 server001 sudo[28782]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:02 server001 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 20 23:13:02 server001 systemd[1]: mpd.service: Succeeded. Apr 20 23:13:02 server001 systemd[1]: Stopped Music Player Daemon. Apr 20 23:13:02 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:13:02 server001 volumio[878]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 23:13:02 server001 volumio[878]: xcb_connection_has_error() returned true Apr 20 23:13:02 server001 volumio[878]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 sudo[28794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 20 23:13:02 server001 sudo[28794]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:02 server001 systemd[1]: Starting Music Player Daemon... Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 23:13:02 server001 sudo[28796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 20 23:13:02 server001 sudo[28796]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 23:13:02 server001 sudo[28794]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 23:13:02 server001 volumio[878]: info: MPD Permissions set Apr 20 23:13:02 server001 volumio[878]: info: Shairport-Sync Started Apr 20 23:13:02 server001 sudo[28796]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 23:13:02 server001 sudo[28790]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 20 23:13:02 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:02 server001 sudo[28790]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:02 server001 volumio[878]: info: Starting Shairport Sync Apr 20 23:13:02 server001 sudo[28790]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:02 server001 sudo[28808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 23:13:02 server001 sudo[28808]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:02 server001 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 23:13:02 server001 systemd[1]: shairport-sync.service: Succeeded. Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 20 23:13:02 server001 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 23:13:02 server001 volumio[878]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 20 23:13:02 server001 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 23:13:02 server001 sudo[28808]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:02 server001 volumio[878]: info: Shairport-Sync Started Apr 20 23:13:04 server001 volumio[878]: info: camilladsp service started and running in background, instance 1 Apr 20 23:13:04 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 23:13:04 server001 volumio[878]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 20 23:13:04 server001 volumio[878]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 20 23:13:04 server001 volumio[878]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 20 23:13:04 server001 volumio[878]: info: FusionDsp loaded Apr 20 23:13:04 server001 volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 20 23:13:04 server001 sudo[28824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 20 23:13:04 server001 sudo[28824]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:04 server001 volumio[878]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 20 23:13:04 server001 volumio[878]: info: Adding Signal Path Element [object Object] Apr 20 23:13:04 server001 volumio[878]: info: Adding fusiondspeq DSP Signal Path Element Apr 20 23:13:04 server001 volumio[878]: info: FusionDsp - ---- installed callbackRead Apr 20 23:13:04 server001 volumio[878]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 23:13:04 server001 volumio[878]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 20 23:13:04 server001 volumio[878]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 20 23:13:04 server001 volumio[878]: at onErrorNT (internal/child_process.js:465:16) Apr 20 23:13:04 server001 volumio[878]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Apr 20 23:13:04 server001 volumio[878]: errno: -2, Apr 20 23:13:04 server001 volumio[878]: code: 'ENOENT', Apr 20 23:13:04 server001 volumio[878]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 20 23:13:04 server001 volumio[878]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 20 23:13:04 server001 volumio[878]: spawnargs: [ Apr 20 23:13:04 server001 volumio[878]: '-p', Apr 20 23:13:04 server001 volumio[878]: 9876, Apr 20 23:13:04 server001 volumio[878]: '-o', Apr 20 23:13:04 server001 volumio[878]: '/tmp/camilladsp.log', Apr 20 23:13:04 server001 volumio[878]: '-l', Apr 20 23:13:04 server001 volumio[878]: 'warn', Apr 20 23:13:04 server001 volumio[878]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 20 23:13:04 server001 volumio[878]: ] Apr 20 23:13:04 server001 volumio[878]: } Apr 20 23:13:04 server001 volumio[878]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 23:13:04 server001 sudo[28513]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:04 server001 systemd[1]: Started FusionDsp Daemon. Apr 20 23:13:04 server001 sudo[28824]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:04 server001 sudo[28829]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Apr 20 23:13:04 server001 sudo[28829]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:04 server001 mpd[28807]: Apr 20 23:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 23:13:04 server001 systemd[1]: Started Music Player Daemon. Apr 20 23:13:04 server001 sudo[28750]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:04 server001 sudo[28782]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:04 server001 volumio[28826]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 20 23:13:04 server001 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 20 23:13:04 server001 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 20 23:13:06 server001 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 20 23:13:06 server001 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. Apr 20 23:13:06 server001 systemd[1]: Stopped FusionDsp Daemon. Apr 20 23:13:06 server001 systemd[1]: Started FusionDsp Daemon. Apr 20 23:13:06 server001 sudo[28829]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:07 server001 sudo[28842]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Apr 20 23:13:07 server001 sudo[28842]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 23:13:07 server001 sudo[28842]: pam_unix(sudo:session): session closed for user root Apr 20 23:13:07 server001 volumio[28841]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 20 23:13:07 server001 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 20 23:13:07 server001 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 20 23:13:07 server001 sudo[28846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-20 23:12 Apr 20 23:13:07 server001 sudo[28846]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"