Jul 11 17:36:05 volumio volumio[1126]: info: Get:3 http://deb.debian.org/debian bookworm/main amd64 python3-setuptools-whl all 66.1.1-1+deb12u1 [1112 kB] Jul 11 17:36:05 volumio volumio[1126]: info: Get:4 http://deb.debian.org/debian bookworm/main amd64 python3.11-venv amd64 3.11.2-6+deb12u6 [5896 B] Jul 11 17:36:05 volumio volumio[1126]: info: Get:5 http://deb.debian.org/debian bookworm/main amd64 python3-venv amd64 3.11.2-1+b1 [1200 B] Jul 11 17:36:06 volumio volumio[1126]: info: Fetched 2979 kB in 17s (176 kB/s) Jul 11 17:36:07 volumio volumio[1126]: info: Selecting previously unselected package drc. Jul 11 17:36:08 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 11 17:36:14 volumio volumio[1126]: [620B blob data] Jul 11 17:36:14 volumio volumio[1126]: info: Preparing to unpack .../drc_3.2.3~dfsg0-1_amd64.deb ... Jul 11 17:36:14 volumio volumio[1126]: info: Unpacking drc (3.2.3~dfsg0-1) ... Jul 11 17:37:10 volumio ntpd[1066]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 11 17:37:11 volumio ntpd[1066]: CLOCK: time stepped by 0.910375 Jul 11 17:37:11 volumio ntpd[1066]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Jul 11 17:37:11 volumio ntpd[1066]: CLOCK: frequency error 2985 PPM exceeds tolerance 500 PPM Jul 11 17:37:11 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 11 17:37:11 volumio dbus-daemon[898]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.49' (uid=0 pid=4041 comm="timedatectl show --property=NTPSynchronized --valu") Jul 11 17:37:11 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 11 17:37:11 volumio dbus-daemon[898]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 11 17:37:11 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 11 17:37:11 volumio setdatetime-helper.sh[4040]: Time is not synchronized. Attempting to sync... Jul 11 17:37:11 volumio volumio[1126]: info: Selecting previously unselected package python3-pip-whl. Jul 11 17:37:11 volumio volumio[1126]: info: Preparing to unpack .../python3-pip-whl_23.0.1+dfsg-1_all.deb ... Jul 11 17:37:11 volumio volumio[1126]: info: Unpacking python3-pip-whl (23.0.1+dfsg-1) ... Jul 11 17:37:13 volumio volumio[1126]: info: Selecting previously unselected package python3-setuptools-whl. Jul 11 17:37:13 volumio volumio[1126]: info: Preparing to unpack .../python3-setuptools-whl_66.1.1-1+deb12u1_all.deb ... Jul 11 17:37:13 volumio volumio[1126]: info: Unpacking python3-setuptools-whl (66.1.1-1+deb12u1) ... Jul 11 17:37:13 volumio volumio[1126]: info: Selecting previously unselected package python3.11-venv. Jul 11 17:37:13 volumio volumio[1126]: info: Preparing to unpack .../python3.11-venv_3.11.2-6+deb12u6_amd64.deb ... Jul 11 17:37:13 volumio volumio[1126]: info: Unpacking python3.11-venv (3.11.2-6+deb12u6) ... Jul 11 17:37:13 volumio volumio[1126]: info: Selecting previously unselected package python3-venv. Jul 11 17:37:13 volumio volumio[1126]: info: Preparing to unpack .../python3-venv_3.11.2-1+b1_amd64.deb ... Jul 11 17:37:13 volumio volumio[1126]: info: Unpacking python3-venv (3.11.2-1+b1) ... Jul 11 17:37:16 volumio volumio[1126]: info: Setting up drc (3.2.3~dfsg0-1) ... Jul 11 17:37:16 volumio volumio[1126]: info: Setting up python3-setuptools-whl (66.1.1-1+deb12u1) ... Jul 11 17:37:16 volumio volumio[1126]: info: Setting up python3-pip-whl (23.0.1+dfsg-1) ... Jul 11 17:37:16 volumio volumio[1126]: info: Setting up python3.11-venv (3.11.2-6+deb12u6) ... Jul 11 17:37:16 volumio setdatetime-helper.sh[4040]: Sync attempt failed. Jul 11 17:37:16 volumio systemd[1]: setdatetime-helper.timer.service: Main process exited, code=exited, status=1/FAILURE Jul 11 17:37:16 volumio systemd[1]: setdatetime-helper.timer.service: Failed with result 'exit-code'. Jul 11 17:37:16 volumio systemd[1]: Failed to start setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 11 17:37:16 volumio volumio[1126]: info: Setting up python3-venv (3.11.2-1+b1) ... Jul 11 17:37:16 volumio volumio[1126]: info: Processing triggers for man-db (2.11.2-2) ... Jul 11 17:37:17 volumio sudo[3815]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:17 volumio volumio[1126]: info: creating sysytemd service Jul 11 17:37:17 volumio sudo[4138]: root : PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Jul 11 17:37:17 volumio sudo[4138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 17:37:17 volumio systemd[1]: Reloading. Jul 11 17:37:17 volumio systemd-gpt-auto-generator[4146]: EFI loader partition unknown, exiting. Jul 11 17:37:17 volumio systemd-gpt-auto-generator[4146]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Jul 11 17:37:17 volumio sudo[4138]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:17 volumio volumio[1126]: info: copying hw detection script Jul 11 17:37:17 volumio volumio[1126]: info: Detected cpu architecture as x86_64 Jul 11 17:37:23 volumio volumio[1126]: info: Downloading camillagui ... Please wait! Jul 11 17:37:28 volumio volumio[1126]: info: Extracting camillagui ... Jul 11 17:37:28 volumio sudo[3466]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:28 volumio volumio[1126]: Plugin install end detected on script Jul 11 17:37:28 volumio volumio[1126]: info: Install script completed Jul 11 17:37:28 volumio volumio[1126]: info: Adding reference to registry Jul 11 17:37:28 volumio volumio[1126]: info: Done installing plugin. Jul 11 17:37:28 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 11 17:37:28 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 11 17:37:28 volumio volumio[1126]: info: Folder /tmp/plugins removed Jul 11 17:37:28 volumio volumio[1126]: info: Folder /tmp/downloaded_plugin.zip removed Jul 11 17:37:28 volumio volumio[1126]: info: Folder /data/temp removed Jul 11 17:37:41 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 11 17:37:42 volumio volumio[1126]: info: Enabling plugin fusiondsp Jul 11 17:37:42 volumio volumio[1126]: info: Loading plugin "fusiondsp"... Jul 11 17:37:42 volumio volumio[1126]: info: Preparing to generate the ALSA configuration file Jul 11 17:37:42 volumio volumio[1126]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 11 17:37:42 volumio volumio[1126]: info: Reading ALSA contributions from plugins. Jul 11 17:37:42 volumio volumio[1126]: info: Asound.conf file written Jul 11 17:37:42 volumio sudo[4234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 11 17:37:42 volumio sudo[4234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:43 volumio sudo[4234]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:44 volumio volumio[1126]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jul 11 17:37:44 volumio volumio[1126]: No state is present for card DMX6Fire Jul 11 17:37:44 volumio volumio[1126]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jul 11 17:37:44 volumio volumio[1126]: Found hardware: "ICE1712" "ICE1712 - multitrack" "" "0x153b" "0x1138" Jul 11 17:37:44 volumio volumio[1126]: Hardware is initialized using a generic method Jul 11 17:37:44 volumio volumio[1126]: No state is present for card DMX6Fire Jul 11 17:37:44 volumio volumio[1126]: info: Output device has changed, restarting MPD Jul 11 17:37:44 volumio sudo[4256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 17:37:44 volumio volumio[1126]: info: Output device has changed, restarting Shairport Sync Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:44 volumio sudo[4256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:44 volumio sudo[4256]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:44 volumio sudo[4258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 17:37:44 volumio sudo[4258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:44 volumio volumio[1126]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 17:37:44 volumio volumio[1126]: info: PLUGIN START: fusiondsp Jul 11 17:37:44 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jul 11 17:37:44 volumio volumio[1126]: info: Loading i18n strings for locale en Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: FusionDsp - mixtype--------------------- Hardware Jul 11 17:37:44 volumio volumio[1126]: info: Preparing to generate the ALSA configuration file Jul 11 17:37:44 volumio systemd[1]: mpd.service: Deactivated successfully. Jul 11 17:37:44 volumio volumio[1126]: info: Done. Jul 11 17:37:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 11 17:37:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Jul 11 17:37:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 11 17:37:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 11 17:37:44 volumio volumio[1126]: info: Enabling plugin fusiondsp Jul 11 17:37:44 volumio volumio[1126]: info: Loading plugin "fusiondsp"... Jul 11 17:37:44 volumio volumio[1126]: info: Applying required configuration parameters for plugin fusiondsp Jul 11 17:37:44 volumio volumio[1126]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 11 17:37:44 volumio volumio[1126]: info: Reading ALSA contributions from plugins. Jul 11 17:37:44 volumio volumio[1126]: info: MPD Permissions set Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 17:37:44 volumio volumio[1126]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 11 17:37:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 11 17:37:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 17:37:44 volumio volumio[1126]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 17:37:44 volumio sudo[4273]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 11 17:37:44 volumio sudo[4273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 17:37:44 volumio sudo[4273]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:44 volumio volumio[1126]: info: Starting Shairport Sync Jul 11 17:37:44 volumio volumio[1126]: info: Asound.conf file unchanged, so no further update is needed Jul 11 17:37:44 volumio volumio[1126]: info: Output device has changed, restarting MPD Jul 11 17:37:44 volumio sudo[4278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 17:37:44 volumio sudo[4278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:44 volumio volumio[1126]: info: Output device has changed, restarting Shairport Sync Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:44 volumio sudo[4283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 17:37:44 volumio sudo[4280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 17:37:44 volumio sudo[4280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:44 volumio sudo[4283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:44 volumio sudo[4280]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:44 volumio volumio[1126]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 17:37:44 volumio volumio[1126]: info: Preparing to generate the ALSA configuration file Jul 11 17:37:44 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 11 17:37:44 volumio systemd[1]: mpd.service: Deactivated successfully. Jul 11 17:37:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 11 17:37:44 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jul 11 17:37:44 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:44 volumio systemd[1]: shairport-sync.service: Consumed 3.250s CPU time. Jul 11 17:37:44 volumio volumio[1126]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 17:37:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Jul 11 17:37:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 11 17:37:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 11 17:37:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 11 17:37:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jul 11 17:37:44 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:44 volumio sudo[4278]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:44 volumio volumio[1126]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 17:37:44 volumio sudo[4293]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 11 17:37:44 volumio sudo[4293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 17:37:44 volumio sudo[4293]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:44 volumio volumio[1126]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 17:37:44 volumio volumio[1126]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 11 17:37:44 volumio volumio[1126]: info: Reading ALSA contributions from plugins. Jul 11 17:37:44 volumio volumio[1126]: info: MPD Permissions set Jul 11 17:37:44 volumio volumio[1126]: info: Shairport-Sync Started Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: Starting Shairport Sync Jul 11 17:37:44 volumio sudo[4330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 17:37:44 volumio sudo[4330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:44 volumio volumio[1126]: info: Asound.conf file unchanged, so no further update is needed Jul 11 17:37:44 volumio volumio[1126]: info: Output device has changed, restarting MPD Jul 11 17:37:44 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 11 17:37:44 volumio volumio[1126]: info: Output device has changed, restarting Shairport Sync Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:44 volumio sudo[4333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 17:37:44 volumio sudo[4333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:44 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jul 11 17:37:44 volumio sudo[4333]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:44 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:44 volumio sudo[4335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 17:37:44 volumio sudo[4335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:44 volumio volumio[1126]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 17:37:44 volumio volumio[1126]: info: PLUGIN START: fusiondsp Jul 11 17:37:44 volumio volumio[1126]: info: Loading i18n strings for locale en Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: FusionDsp - mixtype--------------------- Hardware Jul 11 17:37:44 volumio volumio[1126]: info: Preparing to generate the ALSA configuration file Jul 11 17:37:44 volumio volumio[1126]: info: Done. Jul 11 17:37:44 volumio volumio[1126]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 11 17:37:44 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:44 volumio volumio[1126]: info: Reading ALSA contributions from plugins. Jul 11 17:37:44 volumio sudo[4330]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:44 volumio volumio[1126]: info: MPD Permissions set Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 17:37:44 volumio systemd[1]: mpd.service: Deactivated successfully. Jul 11 17:37:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 11 17:37:44 volumio volumio[1126]: info: Shairport-Sync Started Jul 11 17:37:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Jul 11 17:37:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 11 17:37:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 11 17:37:44 volumio volumio[1126]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 11 17:37:44 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:44 volumio volumio[1126]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 17:37:44 volumio sudo[4346]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 11 17:37:44 volumio sudo[4346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 17:37:44 volumio sudo[4346]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:44 volumio volumio[1126]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 17:37:44 volumio volumio[1126]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 17:37:44 volumio volumio[1126]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 11 17:37:45 volumio volumio[1126]: info: Starting Shairport Sync Jul 11 17:37:45 volumio sudo[4371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 17:37:45 volumio sudo[4371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:45 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 11 17:37:45 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jul 11 17:37:45 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:45 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:45 volumio sudo[4371]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:45 volumio volumio[1126]: info: Shairport-Sync Started Jul 11 17:37:45 volumio volumio[1126]: info: Asound.conf file unchanged, so no further update is needed Jul 11 17:37:45 volumio volumio[1126]: info: Output device has changed, restarting MPD Jul 11 17:37:45 volumio volumio[1126]: info: Output device has changed, restarting Shairport Sync Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:45 volumio sudo[4389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 17:37:45 volumio sudo[4389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:45 volumio sudo[4389]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:45 volumio volumio[1126]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 17:37:45 volumio volumio[1126]: info: MPD Permissions set Jul 11 17:37:45 volumio sudo[4392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 17:37:45 volumio sudo[4392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:45 volumio systemd[1]: mpd.service: Deactivated successfully. Jul 11 17:37:45 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 11 17:37:45 volumio systemd[1]: mpd.socket: Deactivated successfully. Jul 11 17:37:45 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 11 17:37:45 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 11 17:37:45 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:45 volumio volumio[1126]: info: Starting Shairport Sync Jul 11 17:37:45 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 11 17:37:45 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jul 11 17:37:45 volumio sudo[4411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 17:37:45 volumio sudo[4411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:45 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 11 17:37:45 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jul 11 17:37:45 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:45 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:45 volumio sudo[4411]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:45 volumio volumio[1126]: info: Shairport-Sync Started Jul 11 17:37:45 volumio sudo[4412]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 11 17:37:45 volumio sudo[4412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 11 17:37:45 volumio sudo[4412]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:47 volumio volumio[1126]: info: camilladsp service started and running in background, instance 1 Jul 11 17:37:47 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:47 volumio mpd[4431]: 2025-07-11T17:37:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 11 17:37:47 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jul 11 17:37:47 volumio sudo[4335]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:47 volumio sudo[4283]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:47 volumio sudo[4392]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:47 volumio sudo[4258]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:49 volumio volumio[1126]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 <-AAAAAAAAAAAAA Jul 11 17:37:49 volumio volumio[1126]: info: FusionDsp loaded Jul 11 17:37:49 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 17:37:49 volumio volumio[1126]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 11 17:37:49 volumio volumio[1126]: info: Adding Signal Path Element [object Object] Jul 11 17:37:49 volumio volumio[1126]: info: Adding fusiondspeq DSP Signal Path Element Jul 11 17:37:49 volumio volumio[1126]: info: FusionDsp - ---- installed callbackRead Jul 11 17:37:49 volumio volumio[1126]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 17:37:49 volumio volumio[1126]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 11 17:37:49 volumio volumio[1126]: at ChildProcess._handle.onexit (node:internal/child_process:286:19) Jul 11 17:37:49 volumio volumio[1126]: at onErrorNT (node:internal/child_process:484:16) Jul 11 17:37:49 volumio volumio[1126]: at processTicksAndRejections (node:internal/process/task_queues:82:21) Jul 11 17:37:49 volumio volumio[1126]: at runNextTicks (node:internal/process/task_queues:64:3) Jul 11 17:37:49 volumio volumio[1126]: at listOnTimeout (node:internal/timers:540:9) Jul 11 17:37:49 volumio volumio[1126]: at process.processTimers (node:internal/timers:514:7) { Jul 11 17:37:49 volumio volumio[1126]: errno: -2, Jul 11 17:37:49 volumio volumio[1126]: code: 'ENOENT', Jul 11 17:37:49 volumio volumio[1126]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 11 17:37:49 volumio volumio[1126]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 11 17:37:49 volumio volumio[1126]: spawnargs: [ Jul 11 17:37:49 volumio volumio[1126]: '-p', Jul 11 17:37:49 volumio volumio[1126]: 9876, Jul 11 17:37:49 volumio volumio[1126]: '-o', Jul 11 17:37:49 volumio volumio[1126]: '/tmp/camilladsp.log', Jul 11 17:37:49 volumio volumio[1126]: '-l', Jul 11 17:37:49 volumio volumio[1126]: 'warn', Jul 11 17:37:49 volumio volumio[1126]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 11 17:37:49 volumio volumio[1126]: ] Jul 11 17:37:49 volumio volumio[1126]: } Jul 11 17:37:49 volumio volumio[1126]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 17:37:49 volumio sudo[4449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 11 17:37:49 volumio sudo[4449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 11 17:37:49 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 11 17:37:49 volumio sudo[4449]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:49 volumio sudo[4467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-11 17:36' Jul 11 17:37:49 volumio sudo[4467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4f980d5cf693b4272997a2fa2da8bb8036ddf1ab" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 5 02:28:04 UTC 2025" VOLUMIO_VERSION="4.015" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="fd2f0c5aa414951a829ccaf1ecae9bb1"