Oct 17 16:37:02 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:02 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:02 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:02 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:02 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:02 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:03 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 16:37:03 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 16:37:03 volumio volumio[1145]: info: Discovery: Getting this device information Oct 17 16:37:03 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:03 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:03 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 16:37:05 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Oct 17 16:37:06 volumio volumio[1145]: info: Reading package lists... Oct 17 16:37:06 volumio dbus-daemon[641]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.38' (uid=0 pid=3170 comm="timedatectl show --property=NTPSynchronized --valu") Oct 17 16:37:06 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Oct 17 16:37:08 volumio volumio[1145]: info: Building dependency tree... Oct 17 16:37:08 volumio volumio[1145]: info: Reading state information... Oct 17 16:37:08 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Oct 17 16:37:08 volumio dbus-daemon[641]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 17 16:37:08 volumio setdatetime-helper.sh[3168]: Time is not synchronized. Attempting to sync... Oct 17 16:37:08 volumio volumio[1145]: info: 109 packages can be upgraded. Run 'apt list --upgradable' to see them. Oct 17 16:37:08 volumio sudo[2937]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:08 volumio sudo[3195]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Fri, 17 Oct 2025 14:37:08 GMT#015' Oct 17 16:37:08 volumio sudo[3194]: root : PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install --no-install-recommends python3-venv drc Oct 17 16:37:08 volumio sudo[3195]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 16:37:08 volumio sudo[3194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 16:37:08 volumio setdatetime-helper.sh[3197]: Fri Oct 17 16:37:08 CEST 2025 Oct 17 16:37:08 volumio systemd-journald[380]: Time jumped backwards, rotating. Oct 17 16:37:08 volumio sudo[3195]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:08 volumio setdatetime-helper.sh[3168]: Time synchronized successfully. Oct 17 16:37:08 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Oct 17 16:37:08 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Oct 17 16:37:08 volumio volumio[1145]: info: Reading package lists... Oct 17 16:37:09 volumio volumio[1145]: info: Building dependency tree... Oct 17 16:37:09 volumio volumio[1145]: info: Reading state information... Oct 17 16:37:10 volumio volumio[1145]: info: The following additional packages will be installed: Oct 17 16:37:10 volumio volumio[1145]: info: python3-pip-whl python3-pkg-resources python3-setuptools Oct 17 16:37:10 volumio volumio[1145]: info: python3-setuptools-whl python3.11-venv Oct 17 16:37:10 volumio volumio[1145]: info: Suggested packages: Oct 17 16:37:10 volumio volumio[1145]: info: python-setuptools-doc Oct 17 16:37:10 volumio volumio[1145]: info: The following NEW packages will be installed: Oct 17 16:37:10 volumio volumio[1145]: info: drc python3-pip-whl python3-setuptools-whl python3-venv python3.11-venv Oct 17 16:37:10 volumio volumio[1145]: info: The following packages will be upgraded: Oct 17 16:37:11 volumio volumio[1145]: info: python3-pkg-resources python3-setuptools Oct 17 16:37:11 volumio volumio[1145]: info: 2 upgraded, 5 newly installed, 0 to remove and 107 not upgraded. Oct 17 16:37:11 volumio volumio[1145]: info: Need to get 3773 kB of archives. Oct 17 16:37:11 volumio volumio[1145]: info: After this operation, 3696 kB of additional disk space will be used. Oct 17 16:37:11 volumio volumio[1145]: info: Get:1 http://archive.raspberrypi.com/debian bookworm/main armhf python3-pip-whl all 23.0.1+dfsg-1+rpt1 [1718 kB] Oct 17 16:37:11 volumio volumio[1145]: info: Get:2 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf drc armhf 3.2.3~dfsg0-1 [118 kB] Oct 17 16:37:11 volumio volumio[1145]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-setuptools all 66.1.1-1+deb12u2 [522 kB] Oct 17 16:37:11 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:11 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:11 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:11 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:11 volumio volumio[1145]: info: Get:7 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-venv armhf 3.11.2-1 [1188 B] Oct 17 16:37:12 volumio volumio[1145]: info: Get:4 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-pkg-resources all 66.1.1-1+deb12u2 [297 kB] Oct 17 16:37:12 volumio volumio[1145]: info: Get:5 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-setuptools-whl all 66.1.1-1+deb12u2 [1112 kB] Oct 17 16:37:14 volumio volumio[1145]: info: Get:6 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3.11-venv armhf 3.11.2-6+deb12u6 [5896 B] Oct 17 16:37:15 volumio volumio[1145]: info: Fetched 3773 kB in 3s (1268 kB/s) Oct 17 16:37:15 volumio volumio[1145]: info: Selecting previously unselected package drc. Oct 17 16:37:17 volumio volumio[1145]: [620B blob data] Oct 17 16:37:17 volumio volumio[1145]: info: Preparing to unpack .../0-drc_3.2.3~dfsg0-1_armhf.deb ... Oct 17 16:37:17 volumio volumio[1145]: info: Unpacking drc (3.2.3~dfsg0-1) ... Oct 17 16:37:20 volumio volumio[1145]: info: Selecting previously unselected package python3-pip-whl. Oct 17 16:37:20 volumio volumio[1145]: info: Preparing to unpack .../1-python3-pip-whl_23.0.1+dfsg-1+rpt1_all.deb ... Oct 17 16:37:20 volumio volumio[1145]: info: Unpacking python3-pip-whl (23.0.1+dfsg-1+rpt1) ... Oct 17 16:37:20 volumio volumio[1145]: info: Preparing to unpack .../2-python3-setuptools_66.1.1-1+deb12u2_all.deb ... Oct 17 16:37:21 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:21 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:21 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:21 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:21 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:21 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:21 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:21 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:22 volumio volumio[1145]: info: Unpacking python3-setuptools (66.1.1-1+deb12u2) over (66.1.1-1+deb12u1) ... Oct 17 16:37:25 volumio volumio[1145]: info: Preparing to unpack .../3-python3-pkg-resources_66.1.1-1+deb12u2_all.deb ... Oct 17 16:37:25 volumio volumio[1145]: info: Unpacking python3-pkg-resources (66.1.1-1+deb12u2) over (66.1.1-1+deb12u1) ... Oct 17 16:37:26 volumio volumio[1145]: info: Selecting previously unselected package python3-setuptools-whl. Oct 17 16:37:26 volumio volumio[1145]: info: Preparing to unpack .../4-python3-setuptools-whl_66.1.1-1+deb12u2_all.deb ... Oct 17 16:37:26 volumio volumio[1145]: info: Unpacking python3-setuptools-whl (66.1.1-1+deb12u2) ... Oct 17 16:37:27 volumio volumio[1145]: info: Selecting previously unselected package python3.11-venv. Oct 17 16:37:27 volumio volumio[1145]: info: Preparing to unpack .../5-python3.11-venv_3.11.2-6+deb12u6_armhf.deb ... Oct 17 16:37:27 volumio volumio[1145]: info: Unpacking python3.11-venv (3.11.2-6+deb12u6) ... Oct 17 16:37:27 volumio volumio[1145]: info: Selecting previously unselected package python3-venv. Oct 17 16:37:27 volumio volumio[1145]: info: Preparing to unpack .../6-python3-venv_3.11.2-1_armhf.deb ... Oct 17 16:37:27 volumio volumio[1145]: info: Unpacking python3-venv (3.11.2-1) ... Oct 17 16:37:27 volumio volumio[1145]: info: Setting up python3-pkg-resources (66.1.1-1+deb12u2) ... Oct 17 16:37:29 volumio volumio[1145]: info: Setting up drc (3.2.3~dfsg0-1) ... Oct 17 16:37:29 volumio volumio[1145]: info: Setting up python3-setuptools-whl (66.1.1-1+deb12u2) ... Oct 17 16:37:29 volumio volumio[1145]: info: Setting up python3-setuptools (66.1.1-1+deb12u2) ... Oct 17 16:37:31 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:31 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:31 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:31 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 16:37:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 16:37:32 volumio volumio[1145]: info: Discovery: Getting this device information Oct 17 16:37:32 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:32 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 16:37:32 volumio volumio[1145]: info: Setting up python3-pip-whl (23.0.1+dfsg-1+rpt1) ... Oct 17 16:37:32 volumio volumio[1145]: info: Setting up python3.11-venv (3.11.2-6+deb12u6) ... Oct 17 16:37:32 volumio volumio[1145]: info: Setting up python3-venv (3.11.2-1) ... Oct 17 16:37:35 volumio sudo[3194]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:35 volumio volumio[1145]: info: creating sysytemd service Oct 17 16:37:35 volumio sudo[3359]: root : PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Oct 17 16:37:35 volumio sudo[3359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 16:37:35 volumio systemd[1]: Reloading. Oct 17 16:37:36 volumio sudo[3359]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:36 volumio volumio[1145]: info: copying hw detection script Oct 17 16:37:36 volumio volumio[1145]: info: Detected cpu architecture as armv7l Oct 17 16:37:36 volumio systemd[1]: Starting fstrim.service - Discard unused blocks on filesystems from /etc/fstab... Oct 17 16:37:37 volumio fstrim[3380]: /boot: 276.4 MiB (289841152 bytes) trimmed on /dev/mmcblk0p1 Oct 17 16:37:37 volumio systemd[1]: fstrim.service: Deactivated successfully. Oct 17 16:37:37 volumio systemd[1]: Finished fstrim.service - Discard unused blocks on filesystems from /etc/fstab. Oct 17 16:37:37 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Oct 17 16:37:38 volumio sudo[3406]: root : PWD=/tmp ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Oct 17 16:37:38 volumio sudo[3406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 16:37:38 volumio sudo[3406]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:38 volumio sudo[3408]: root : PWD=/tmp ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Oct 17 16:37:38 volumio sudo[3408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 16:37:38 volumio sudo[3408]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:38 volumio volumio[1145]: info: Downloading camillagui ... Please wait! Oct 17 16:37:41 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:41 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:41 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:41 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:41 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:41 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:41 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:41 volumio volumio[1145]: info: Listing playlists Oct 17 16:37:43 volumio volumio[1145]: info: Extracting camillagui ... Oct 17 16:37:47 volumio sudo[2912]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:47 volumio volumio[1145]: Plugin install end detected on script Oct 17 16:37:47 volumio volumio[1145]: info: Install script completed Oct 17 16:37:47 volumio volumio[1145]: info: Adding reference to registry Oct 17 16:37:47 volumio volumio[1145]: info: Done installing plugin. Oct 17 16:37:47 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 16:37:47 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 17 16:37:47 volumio volumio[1145]: info: Folder /tmp/plugins removed Oct 17 16:37:47 volumio volumio[1145]: info: Folder /tmp/downloaded_plugin.zip removed Oct 17 16:37:47 volumio volumio[1145]: info: Folder /data/temp removed Oct 17 16:37:51 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:51 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:51 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:37:51 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:37:52 volumio volumio[1145]: info: Enabling plugin fusiondsp Oct 17 16:37:52 volumio volumio[1145]: info: Loading plugin "fusiondsp"... Oct 17 16:37:53 volumio volumio[1145]: info: Preparing to generate the ALSA configuration file Oct 17 16:37:53 volumio volumio[1145]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 17 16:37:53 volumio volumio[1145]: info: Reading ALSA contributions from plugins. Oct 17 16:37:53 volumio volumio[1145]: info: Asound.conf file written Oct 17 16:37:53 volumio sudo[3433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 17 16:37:53 volumio sudo[3433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 16:37:53 volumio sudo[3433]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:53 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 17 16:37:53 volumio volumio[1145]: No state is present for card b1 Oct 17 16:37:53 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 17 16:37:53 volumio volumio[1145]: /usr/share/alsa/init/bcm2835:14: control element not found Oct 17 16:37:53 volumio volumio[1145]: No state is present for card b1 Oct 17 16:37:53 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 17 16:37:53 volumio volumio[1145]: No state is present for card Headphones Oct 17 16:37:53 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 17 16:37:53 volumio volumio[1145]: /usr/share/alsa/init/bcm2835:20: control element not found Oct 17 16:37:53 volumio volumio[1145]: No state is present for card Headphones Oct 17 16:37:53 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 17 16:37:53 volumio volumio[1145]: No state is present for card sndrpihifiberry Oct 17 16:37:53 volumio volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 17 16:37:53 volumio volumio[1145]: Found hardware: "HifiberryDacp" "" "" "" "" Oct 17 16:37:53 volumio volumio[1145]: Hardware is initialized using a generic method Oct 17 16:37:53 volumio volumio[1145]: No state is present for card sndrpihifiberry Oct 17 16:37:53 volumio volumio[1145]: info: Output device has changed, restarting MPD Oct 17 16:37:53 volumio volumio[1145]: info: Output device has changed, restarting Shairport Sync Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 16:37:53 volumio sudo[3440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 16:37:53 volumio sudo[3440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 16:37:53 volumio sudo[3440]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:53 volumio sudo[3442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 16:37:53 volumio sudo[3442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 16:37:53 volumio volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 16:37:53 volumio volumio[1145]: info: PLUGIN START: fusiondsp Oct 17 16:37:53 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 16:37:53 volumio volumio[1145]: info: Loading i18n strings for locale nl Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: FusionDsp - mixtype--------------------- Hardware Oct 17 16:37:53 volumio volumio[1145]: info: Preparing to generate the ALSA configuration file Oct 17 16:37:53 volumio volumio[1145]: info: Done. Oct 17 16:37:53 volumio volumio[1145]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 17 16:37:53 volumio volumio[1145]: info: Reading ALSA contributions from plugins. Oct 17 16:37:53 volumio volumio[1145]: info: MPD Permissions set Oct 17 16:37:53 volumio volumio[1145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: Starting Shairport Sync Oct 17 16:37:53 volumio volumio[1145]: info: Asound.conf file unchanged, so no further update is needed Oct 17 16:37:53 volumio volumio[1145]: info: Output device has changed, restarting MPD Oct 17 16:37:53 volumio sudo[3457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 16:37:53 volumio sudo[3457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 16:37:53 volumio volumio[1145]: info: Output device has changed, restarting Shairport Sync Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 16:37:53 volumio sudo[3459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 16:37:53 volumio sudo[3459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 16:37:53 volumio sudo[3459]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:53 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 16:37:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 16:37:53 volumio systemd[1]: mpd.service: Consumed 3.268s CPU time. Oct 17 16:37:53 volumio sudo[3464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 16:37:53 volumio sudo[3464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 16:37:53 volumio volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 16:37:53 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 16:37:53 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 16:37:53 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 16:37:53 volumio volumio[1145]: info: MPD Permissions set Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:53 volumio volumio[1145]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Oct 17 16:37:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 16:37:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 16:37:53 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 16:37:53 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 16:37:53 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 16:37:53 volumio systemd[1]: shairport-sync.service: Consumed 2.947s CPU time. Oct 17 16:37:53 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 17 16:37:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 16:37:53 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 17 16:37:53 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 16:37:53 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 16:37:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 16:37:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 16:37:53 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 16:37:53 volumio sudo[3457]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:53 volumio volumio[1145]: info: Shairport-Sync Started Oct 17 16:37:53 volumio volumio[1145]: info: Starting Shairport Sync Oct 17 16:37:54 volumio sudo[3494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 16:37:54 volumio sudo[3494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 16:37:54 volumio sudo[3479]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 16:37:54 volumio sudo[3479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 16:37:54 volumio sudo[3479]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 16:37:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 16:37:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 16:37:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 16:37:54 volumio sudo[3494]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:54 volumio volumio[1145]: info: Shairport-Sync Started Oct 17 16:37:55 volumio volumio[1145]: info: camilladsp service started and running in background, instance 1 Oct 17 16:37:55 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 16:37:55 volumio volumio[1145]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Oct 17 16:37:55 volumio volumio[1145]: info: FusionDsp loaded Oct 17 16:37:55 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 16:37:55 volumio volumio[1145]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 17 16:37:55 volumio volumio[1145]: info: Adding Signal Path Element [object Object] Oct 17 16:37:55 volumio volumio[1145]: info: Adding fusiondspeq DSP Signal Path Element Oct 17 16:37:55 volumio volumio[1145]: info: FusionDsp - ---- installed callbackRead Oct 17 16:37:55 volumio volumio[1145]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 17 16:37:55 volumio sudo[3529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 17 16:37:55 volumio sudo[3529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 16:37:55 volumio volumio[1145]: error: FusionDsp - Reload WebSocket error: [object Object] Oct 17 16:37:55 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:37:55 volumio sudo[3529]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:55 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:37:56 volumio volumio[1145]: 0+0 records in Oct 17 16:37:56 volumio volumio[1145]: 0+0 records out Oct 17 16:37:56 volumio volumio[1145]: 0 bytes copied, 0.000355125 s, 0.0 kB/s Oct 17 16:37:57 volumio mpd[3499]: 2025-10-17T16:37:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 16:37:57 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 16:37:57 volumio sudo[3464]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:57 volumio sudo[3442]: pam_unix(sudo:session): session closed for user root Oct 17 16:37:57 volumio volumio[1145]: error: MPD error: The expression evaluated to a falsy value: Oct 17 16:37:57 volumio volumio[1145]: assert.ok(self.idling) Oct 17 16:37:57 volumio volumio[1145]: error: The expression evaluated to a falsy value: Oct 17 16:37:57 volumio volumio[1145]: assert.ok(self.idling) Oct 17 16:37:57 volumio volumio[1145]: error: updateQueue error: null Oct 17 16:37:57 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. Oct 17 16:37:57 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:37:57 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:37:57 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:00 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 2. Oct 17 16:38:00 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:00 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:00 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:01 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:01 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:01 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:01 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:01 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:01 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:01 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:01 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:02 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 3. Oct 17 16:38:02 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:02 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:02 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 16:38:02 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 16:38:02 volumio volumio[1145]: info: Discovery: Getting this device information Oct 17 16:38:02 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:02 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 16:38:02 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:04 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 4. Oct 17 16:38:04 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:04 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:04 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:06 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Oct 17 16:38:06 volumio dbus-daemon[641]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.45' (uid=0 pid=3564 comm="timedatectl show --property=NTPSynchronized --valu") Oct 17 16:38:06 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Oct 17 16:38:06 volumio dbus-daemon[641]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 17 16:38:06 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Oct 17 16:38:06 volumio setdatetime-helper.sh[3563]: Time is not synchronized. Attempting to sync... Oct 17 16:38:06 volumio sudo[3573]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Fri, 17 Oct 2025 14:38:07 GMT#015' Oct 17 16:38:06 volumio sudo[3573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 16:38:07 volumio setdatetime-helper.sh[3574]: Fri Oct 17 16:38:07 CEST 2025 Oct 17 16:38:07 volumio sudo[3573]: pam_unix(sudo:session): session closed for user root Oct 17 16:38:07 volumio setdatetime-helper.sh[3563]: Time synchronized successfully. Oct 17 16:38:07 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Oct 17 16:38:07 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Oct 17 16:38:07 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 5. Oct 17 16:38:07 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:07 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:07 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:09 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 6. Oct 17 16:38:09 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:09 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:09 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:11 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 7. Oct 17 16:38:11 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:11 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:11 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:12 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:12 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:12 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:12 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:13 volumio volumio[1145]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf Oct 17 16:38:13 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 8. Oct 17 16:38:13 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:14 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:14 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:15 volumio volumio[1145]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf Oct 17 16:38:15 volumio volumio[1145]: info: Folder /tmp/plugins removed Oct 17 16:38:16 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 9. Oct 17 16:38:16 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:16 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:16 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:16 volumio volumio[1145]: info: Check plugin dependencies Oct 17 16:38:16 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 16:38:16 volumio volumio[1145]: info: Checking if plugin already exists Oct 17 16:38:16 volumio volumio[1145]: info: Rename folder Oct 17 16:38:16 volumio volumio[1145]: info: Folder /tmp/downloaded_plugin.zip removed Oct 17 16:38:16 volumio volumio[1145]: info: Move to category Oct 17 16:38:18 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 10. Oct 17 16:38:18 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:18 volumio volumio[1145]: info: Checking if install.sh is present Oct 17 16:38:18 volumio volumio[1145]: info: Check return the error Error: ENOENT: no such file or directory, stat '/data/plugins/music_service/ytmusic/install.sh' Oct 17 16:38:18 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:18 volumio volumio[1145]: info: An error occurred installing the plugin. Rolling back config Oct 17 16:38:18 volumio volumio[1145]: info: Plugin folders cleanup Oct 17 16:38:18 volumio volumio[1145]: info: Scanning into folder /volumio/app/plugins/ Oct 17 16:38:18 volumio volumio[1145]: info: Scanning category audio_interface Oct 17 16:38:18 volumio volumio[1145]: info: Scanning category miscellanea Oct 17 16:38:18 volumio volumio[1145]: info: Scanning category music_service Oct 17 16:38:18 volumio volumio[1145]: info: Scanning category plugins.json Oct 17 16:38:18 volumio volumio[1145]: info: Scanning category system_controller Oct 17 16:38:18 volumio volumio[1145]: info: Scanning category user_interface Oct 17 16:38:18 volumio volumio[1145]: info: Scanning into folder /data/plugins/ Oct 17 16:38:18 volumio volumio[1145]: info: Scanning category audio_interface Oct 17 16:38:18 volumio volumio[1145]: info: Scanning category music_service Oct 17 16:38:18 volumio volumio[1145]: info: Removing /data/plugins//music_service/README.md Oct 17 16:38:18 volumio volumio[1145]: info: Removing /data/plugins//music_service/config.json Oct 17 16:38:18 volumio volumio[1145]: info: Removing /data/plugins//music_service/eslint.config.mjs Oct 17 16:38:18 volumio volumio[1145]: info: Removing /data/plugins//music_service/fixup.sh Oct 17 16:38:18 volumio volumio[1145]: info: Removing /data/plugins//music_service/gitignore Oct 17 16:38:18 volumio volumio[1145]: info: Removing /data/plugins//music_service/install.sh Oct 17 16:38:18 volumio volumio[1145]: info: Removing /data/plugins//music_service/package-lock.json Oct 17 16:38:18 volumio volumio[1145]: info: Removing /data/plugins//music_service/package.json Oct 17 16:38:18 volumio volumio[1145]: info: Removing /data/plugins//music_service/tsconfig.json Oct 17 16:38:18 volumio volumio[1145]: info: Removing /data/plugins//music_service/uninstall.sh Oct 17 16:38:18 volumio volumio[1145]: info: Plugin folders cleanup completed Oct 17 16:38:18 volumio volumio[1145]: info: Error: Error Oct 17 16:38:18 volumio volumio[1145]: info: Folder /tmp/plugins removed Oct 17 16:38:18 volumio volumio[1145]: info: Folder /tmp/downloaded_plugin.zip removed Oct 17 16:38:18 volumio volumio[1145]: info: Folder /data/temp removed Oct 17 16:38:18 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:20 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 11. Oct 17 16:38:20 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:20 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:20 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:22 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:22 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:22 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:22 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:22 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:22 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:22 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:22 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:22 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 12. Oct 17 16:38:22 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:23 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:23 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:25 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 13. Oct 17 16:38:25 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:25 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:25 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:27 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 14. Oct 17 16:38:27 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:27 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:27 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:29 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 15. Oct 17 16:38:29 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:29 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:29 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:31 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 16. Oct 17 16:38:31 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:32 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:32 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:32 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:32 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:32 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:32 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 16:38:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 16:38:32 volumio volumio[1145]: info: Discovery: Getting this device information Oct 17 16:38:32 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:32 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 16:38:34 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 17. Oct 17 16:38:34 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:34 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:34 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:36 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 18. Oct 17 16:38:36 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:36 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:36 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:36 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Oct 17 16:38:38 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 19. Oct 17 16:38:38 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:38 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:38 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:40 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 20. Oct 17 16:38:40 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:41 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:41 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:42 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:42 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:42 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Oct 17 16:38:42 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Oct 17 16:38:42 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:42 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:42 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:42 volumio volumio[1145]: info: Listing playlists Oct 17 16:38:43 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 21. Oct 17 16:38:43 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:43 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:43 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:45 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 22. Oct 17 16:38:45 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:45 volumio volumio[1145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 16:38:45 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 17 16:38:45 volumio volumio[1145]: TypeError: Cannot read properties of undefined (reading 'plugin_type') Oct 17 16:38:45 volumio volumio[1145]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Oct 17 16:38:45 volumio volumio[1145]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1540:29) Oct 17 16:38:45 volumio volumio[1145]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1342:45) Oct 17 16:38:45 volumio volumio[1145]: at Socket.emit (node:events:514:28) Oct 17 16:38:45 volumio volumio[1145]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 17 16:38:45 volumio volumio[1145]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 17 16:38:45 volumio volumio[1145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 16:38:45 volumio systemd[1]: fusiondsp.service: Deactivated successfully. Oct 17 16:38:46 volumio sudo[3699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-17 16:37' Oct 17 16:38:46 volumio sudo[3699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"