-- Logs begin at Mon 2025-12-29 15:31:22 CST, end at Mon 2025-12-29 18:30:09 CST. -- Dec 29 18:30:07 volumio systemd-timedated[1113]: Changed local time to Mon Dec 29 18:30:07 2025 Dec 29 18:30:07 volumio sudo[1107]: pam_unix(sudo:session): session closed for user root Dec 29 18:30:07 volumio volumio-time-update[643]: volumio-time-update-util: System time updated successfully. Dec 29 18:30:07 volumio systemd[1]: Started Volumio Time Update Utility. Dec 29 18:30:07 volumio systemd[1]: Reached target Multi-User System. Dec 29 18:30:07 volumio systemd[1]: Reached target Graphical Interface. Dec 29 18:30:07 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 29 18:30:07 volumio volumio[877]: info: Preparing to generate the ALSA configuration file Dec 29 18:30:07 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 29 18:30:07 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 29 18:30:07 volumio volumio[877]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 29 18:30:07 volumio volumio[877]: info: Reading ALSA contributions from plugins. Dec 29 18:30:07 volumio volumio[877]: info: MPD Permissions set Dec 29 18:30:07 volumio volumio[877]: info: MPD Permissions set Dec 29 18:30:07 volumio volumio[877]: info: VolumeController:: Volume=0 Mute =false Dec 29 18:30:07 volumio volumio[877]: info: CoreStateMachine::pushState Dec 29 18:30:07 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::volumioPushState Dec 29 18:30:07 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: Discovery: this is already registered, 62e24a4f-643e-4bc9-8abc-37929ffd98dd Dec 29 18:30:07 volumio volumio[877]: info: Discovery: Found device Volumio Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Dec 29 18:30:07 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 18:30:07 volumio volumio[877]: info: Discovery: Getting this device information Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Dec 29 18:30:07 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 18:30:07 volumio volumio[877]: info: Discovery: A device disappeared from network Dec 29 18:30:07 volumio volumio[877]: info: Discovery: Device volumio disappeared from network Dec 29 18:30:07 volumio volumio[877]: verbose: New Socket.io Connection to 192.168.50.103:3000 from 192.168.50.245 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 29 18:30:07 volumio volumio[877]: info: Starting Shairport Sync Dec 29 18:30:07 volumio volumio[877]: info: Starting Shairport Sync Dec 29 18:30:07 volumio volumio[877]: info: Starting Shairport Sync Dec 29 18:30:07 volumio sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 29 18:30:07 volumio sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 18:30:07 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 29 18:30:07 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 18:30:07 volumio volumio[877]: info: Discovery: A device disappeared from network Dec 29 18:30:07 volumio sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 29 18:30:07 volumio sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Dec 29 18:30:07 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Dec 29 18:30:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 29 18:30:07 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 29 18:30:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 29 18:30:07 volumio volumio[877]: info: Asound.conf file unchanged, so no further update is needed Dec 29 18:30:07 volumio volumio[877]: info: Output device has changed, restarting MPD Dec 29 18:30:07 volumio volumio[877]: info: Output device has changed, restarting Shairport Sync Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 18:30:07 volumio sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 29 18:30:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 29 18:30:07 volumio volumio[877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 29 18:30:07 volumio sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 18:30:07 volumio sudo[1151]: pam_unix(sudo:session): session closed for user root Dec 29 18:30:07 volumio sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 29 18:30:07 volumio sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 18:30:07 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Dec 29 18:30:07 volumio sudo[1145]: pam_unix(sudo:session): session closed for user root Dec 29 18:30:07 volumio sudo[1140]: pam_unix(sudo:session): session closed for user root Dec 29 18:30:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 29 18:30:07 volumio systemd[1]: mpd.service: Succeeded. Dec 29 18:30:07 volumio systemd[1]: Stopped Music Player Daemon. Dec 29 18:30:07 volumio systemd[1]: Starting Music Player Daemon... Dec 29 18:30:07 volumio volumio[877]: verbose: New Socket.io Connection to 192.168.50.103:3000 from 192.168.50.245 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 29 18:30:07 volumio volumio[877]: info: MPD Permissions set Dec 29 18:30:07 volumio volumio[877]: info: Shairport-Sync Started Dec 29 18:30:07 volumio volumio[877]: Error adding Membership: Error: addMembership EINVAL Dec 29 18:30:07 volumio volumio[877]: info: Shairport-Sync Started Dec 29 18:30:07 volumio volumio[877]: info: Shairport-Sync Started Dec 29 18:30:07 volumio volumio[877]: info: Volumio called home Dec 29 18:30:07 volumio volumio[877]: info: Discovery: adding 62e24a4f-643e-4bc9-8abc-37929ffd98dd Dec 29 18:30:07 volumio volumio[877]: info: Discovery: Found device Volumio Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Dec 29 18:30:07 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio sudo[1163]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 29 18:30:07 volumio sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 18:30:07 volumio sudo[1163]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 29 18:30:07 volumio sudo[1163]: pam_unix(sudo:session): session closed for user root Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:07 volumio volumio[877]: info: Discovery: this is already registered, 62e24a4f-643e-4bc9-8abc-37929ffd98dd Dec 29 18:30:07 volumio volumio[877]: info: Discovery: Found device Volumio Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Dec 29 18:30:07 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 18:30:07 volumio volumio[877]: info: Discovery: Getting this device information Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::volumioGetState Dec 29 18:30:07 volumio volumio[877]: info: CorePlayQueue::getTrack 0 Dec 29 18:30:07 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 18:30:07 volumio volumio[877]: info: Starting Shairport Sync Dec 29 18:30:07 volumio sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 29 18:30:07 volumio sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 18:30:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 29 18:30:07 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 29 18:30:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 29 18:30:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 29 18:30:07 volumio sudo[1171]: pam_unix(sudo:session): session closed for user root Dec 29 18:30:07 volumio volumio[877]: info: Shairport-Sync Started Dec 29 18:30:07 volumio ntpd[772]: Listen normally on 4 eth0 192.168.50.103:123 Dec 29 18:30:07 volumio ntpd[772]: new interface(s) found: waking up resolver Dec 29 18:30:08 volumio volumio-remote-updater[642]: [2025-12-29 18:30:08] [connect] Successful connection Dec 29 18:30:08 volumio volumio-remote-updater[642]: [2025-12-29 18:30:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1767004208 101 Dec 29 18:30:08 volumio volumio[877]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Dec 29 18:30:08 volumio mpd[1169]: Dec 29 18:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 29 18:30:09 volumio volumio[877]: info: camilladsp service started and running in background, instance 1 Dec 29 18:30:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 29 18:30:09 volumio volumio[877]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 29 18:30:09 volumio volumio[877]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 29 18:30:09 volumio volumio[877]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 29 18:30:09 volumio systemd[1]: Started Music Player Daemon. Dec 29 18:30:09 volumio systemd[1]: Startup finished in 12.038s (kernel) + 16.296s (userspace) = 28.334s. Dec 29 18:30:09 volumio sudo[1153]: pam_unix(sudo:session): session closed for user root Dec 29 18:30:09 volumio sudo[1099]: pam_unix(sudo:session): session closed for user root Dec 29 18:30:09 volumio sudo[1089]: pam_unix(sudo:session): session closed for user root Dec 29 18:30:09 volumio volumio[877]: info: FusionDsp loaded Dec 29 18:30:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 18:30:09 volumio sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 29 18:30:09 volumio sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 18:30:09 volumio volumio[877]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 29 18:30:09 volumio volumio[877]: info: Adding Signal Path Element [object Object] Dec 29 18:30:09 volumio volumio[877]: info: Adding fusiondspeq DSP Signal Path Element Dec 29 18:30:09 volumio volumio[877]: info: FusionDsp - ---- installed callbackRead Dec 29 18:30:09 volumio volumio[877]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 18:30:09 volumio sudo[1190]: pam_unix(sudo:session): session closed for user root Dec 29 18:30:09 volumio volumio[877]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Dec 29 18:30:09 volumio volumio[877]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Dec 29 18:30:09 volumio volumio[877]: at onErrorNT (internal/child_process.js:465:16) Dec 29 18:30:09 volumio volumio[877]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Dec 29 18:30:09 volumio volumio[877]: errno: -2, Dec 29 18:30:09 volumio volumio[877]: code: 'ENOENT', Dec 29 18:30:09 volumio volumio[877]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Dec 29 18:30:09 volumio volumio[877]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Dec 29 18:30:09 volumio volumio[877]: spawnargs: [ Dec 29 18:30:09 volumio volumio[877]: '-p', Dec 29 18:30:09 volumio volumio[877]: 9876, Dec 29 18:30:09 volumio volumio[877]: '-o', Dec 29 18:30:09 volumio volumio[877]: '/tmp/camilladsp.log', Dec 29 18:30:09 volumio volumio[877]: '-l', Dec 29 18:30:09 volumio volumio[877]: 'warn', Dec 29 18:30:09 volumio volumio[877]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Dec 29 18:30:09 volumio volumio[877]: ] Dec 29 18:30:09 volumio volumio[877]: } Dec 29 18:30:09 volumio volumio[877]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 18:30:09 volumio sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-29 18:29 Dec 29 18:30:09 volumio sudo[1201]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"