-- Logs begin at Thu 2019-02-14 18:11:58 AWST, end at Sun 2025-09-14 08:48:22 AWST. -- Sep 14 08:46:59 denafrips sudo[9671]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:00 denafrips volumio[9488]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:47:00 denafrips volumio[9488]: info: Output device has changed, restarting MPD Sep 14 08:47:00 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:00 denafrips sudo[9665]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:00 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:00 denafrips systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:00 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:00 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:00 denafrips volumio[9488]: info: Output device has changed, restarting Shairport Sync Sep 14 08:47:00 denafrips volumio[9488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:00 denafrips volumio[9488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:00 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:00 denafrips sudo[9667]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:00 denafrips sudo[9675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:00 denafrips sudo[9675]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:00 denafrips sudo[9675]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:00 denafrips sudo[9671]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:00 denafrips sudo[9678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:00 denafrips sudo[9678]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:00 denafrips volumio[9488]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:00 denafrips systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:00 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:00 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:00 denafrips volumio[9488]: info: MPD Permissions set Sep 14 08:47:00 denafrips volumio[9488]: info: Shairport-Sync Started Sep 14 08:47:00 denafrips volumio[9488]: Error adding Membership: Error: addMembership EINVAL Sep 14 08:47:00 denafrips volumio[9488]: info: Shairport-Sync Started Sep 14 08:47:00 denafrips volumio[9488]: info: Shairport-Sync Started Sep 14 08:47:00 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:00 denafrips volumio[9488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:00 denafrips volumio[9488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:00 denafrips volumio[9488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:00 denafrips sudo[9686]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:00 denafrips sudo[9686]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:00 denafrips sudo[9686]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:00 denafrips volumio[9488]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:47:00 denafrips volumio[9488]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 14 08:47:00 denafrips volumio[9488]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:47:00 denafrips volumio[9488]: info: camilladsp service started and running in background, instance 1 Sep 14 08:47:00 denafrips volumio[9488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:00 denafrips volumio[9488]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:47:00 denafrips volumio[9488]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 14 08:47:00 denafrips volumio[9488]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:47:00 denafrips volumio[9488]: info: FusionDsp loaded Sep 14 08:47:00 denafrips volumio[9488]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 08:47:00 denafrips sudo[9704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 14 08:47:00 denafrips sudo[9704]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:00 denafrips volumio[9488]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 14 08:47:00 denafrips volumio[9488]: info: Adding Signal Path Element [object Object] Sep 14 08:47:00 denafrips volumio[9488]: info: Adding fusiondspeq DSP Signal Path Element Sep 14 08:47:00 denafrips volumio[9488]: info: FusionDsp - ---- installed callbackRead Sep 14 08:47:00 denafrips volumio[9488]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:47:00 denafrips volumio[9488]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 14 08:47:00 denafrips volumio[9488]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 14 08:47:00 denafrips volumio[9488]: at onErrorNT (internal/child_process.js:465:16) Sep 14 08:47:00 denafrips volumio[9488]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 14 08:47:00 denafrips volumio[9488]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 14 08:47:00 denafrips volumio[9488]: at listOnTimeout (internal/timers.js:523:9) Sep 14 08:47:00 denafrips volumio[9488]: at processTimers (internal/timers.js:497:7) { Sep 14 08:47:00 denafrips volumio[9488]: errno: -2, Sep 14 08:47:00 denafrips volumio[9488]: code: 'ENOENT', Sep 14 08:47:00 denafrips volumio[9488]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:47:00 denafrips volumio[9488]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:47:00 denafrips volumio[9488]: spawnargs: [ Sep 14 08:47:00 denafrips volumio[9488]: '-p', Sep 14 08:47:00 denafrips volumio[9488]: 9876, Sep 14 08:47:00 denafrips volumio[9488]: '-o', Sep 14 08:47:00 denafrips volumio[9488]: '/tmp/camilladsp.log', Sep 14 08:47:00 denafrips volumio[9488]: '-l', Sep 14 08:47:00 denafrips volumio[9488]: 'warn', Sep 14 08:47:00 denafrips volumio[9488]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 14 08:47:00 denafrips volumio[9488]: ] Sep 14 08:47:00 denafrips volumio[9488]: } Sep 14 08:47:00 denafrips volumio[9488]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:47:01 denafrips sudo[9715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 08:46 Sep 14 08:47:01 denafrips sudo[9715]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:01 denafrips sudo[9715]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:01 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 14 08:47:01 denafrips systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:01 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 14 08:47:01 denafrips sudo[9596]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:01 denafrips sudo[9606]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:01 denafrips sudo[9678]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:01 denafrips sudo[9704]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:01 denafrips systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 14 08:47:01 denafrips systemd[1]: Started dynamicswap service. Sep 14 08:47:01 denafrips systemd[1]: dynamicswap.service: Succeeded. Sep 14 08:47:01 denafrips systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 14 08:47:01 denafrips systemd[1]: volumio.service: Scheduled restart job, restart counter is at 34. Sep 14 08:47:01 denafrips systemd[1]: Started dynamicswap service. Sep 14 08:47:01 denafrips systemd[1]: Stopped Volumio Backend Module. Sep 14 08:47:01 denafrips systemd[1]: Started Volumio Backend Module. Sep 14 08:47:01 denafrips systemd[1]: dynamicswap.service: Succeeded. Sep 14 08:47:01 denafrips mpd[9694]: Sep 14 08:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:47:01 denafrips systemd[1]: Started Music Player Daemon. Sep 14 08:47:01 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:01 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 109. Sep 14 08:47:01 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:01 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:03 denafrips volumio[9741]: Traceback (most recent call last): Sep 14 08:47:03 denafrips volumio[9741]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:03 denafrips volumio[9741]: from camilladsp import CamillaConnection Sep 14 08:47:03 denafrips volumio[9741]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:03 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:03 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:03 denafrips volumio[9727]: info: ------------------------------------------- Sep 14 08:47:03 denafrips volumio[9727]: info: ----- Volumio3 ---- Sep 14 08:47:03 denafrips volumio[9727]: info: ------------------------------------------- Sep 14 08:47:03 denafrips volumio[9727]: info: ----- System startup ---- Sep 14 08:47:03 denafrips volumio[9727]: info: ------------------------------------------- Sep 14 08:47:04 denafrips volumio[9727]: info: MYVOLUMIO Environment detected Sep 14 08:47:04 denafrips volumio[9727]: info: Plugin folders cleanup Sep 14 08:47:04 denafrips volumio[9727]: info: Scanning into folder /volumio/app/plugins/ Sep 14 08:47:04 denafrips volumio[9727]: info: Scanning category audio_interface Sep 14 08:47:04 denafrips volumio[9727]: info: Scanning category miscellanea Sep 14 08:47:04 denafrips volumio[9727]: info: Scanning category music_service Sep 14 08:47:04 denafrips volumio[9727]: info: Scanning category plugins.json Sep 14 08:47:04 denafrips volumio[9727]: info: Scanning category system_controller Sep 14 08:47:04 denafrips volumio[9727]: info: Scanning category user_interface Sep 14 08:47:04 denafrips volumio[9727]: info: Scanning into folder /data/plugins/ Sep 14 08:47:04 denafrips volumio[9727]: info: Scanning category audio_interface Sep 14 08:47:04 denafrips volumio[9727]: info: Scanning category music_service Sep 14 08:47:04 denafrips volumio[9727]: info: Plugin folders cleanup completed Sep 14 08:47:04 denafrips volumio[9727]: info: ------------------------------------------- Sep 14 08:47:04 denafrips volumio[9727]: info: ----- Core plugins startup ---- Sep 14 08:47:04 denafrips volumio[9727]: info: ------------------------------------------- Sep 14 08:47:04 denafrips volumio[9727]: info: Loading plugins from folder /volumio/app/plugins/ Sep 14 08:47:04 denafrips volumio[9727]: info: Adding plugin upnp to MyMusic Plugins Sep 14 08:47:04 denafrips volumio[9727]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 14 08:47:04 denafrips volumio[9727]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 14 08:47:04 denafrips volumio[9727]: info: Loading plugins from folder /data/plugins/ Sep 14 08:47:04 denafrips volumio[9727]: info: Loading plugin "system"... Sep 14 08:47:04 denafrips volumio[9727]: info: Loading plugin "appearance"... Sep 14 08:47:05 denafrips volumio[9727]: info: Loading plugin "network"... Sep 14 08:47:05 denafrips volumio[9727]: info: Refreshing Cached IP Addresses Sep 14 08:47:05 denafrips sudo[9756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 08:47:05 denafrips sudo[9756]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:05 denafrips sudo[9756]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:05 denafrips sudo[9758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 08:47:05 denafrips sudo[9758]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:05 denafrips sudo[9758]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:05 denafrips volumio[9727]: info: Loading plugin "services"... Sep 14 08:47:05 denafrips volumio[9727]: info: Loading plugin "alsa_controller"... Sep 14 08:47:05 denafrips sudo[9767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 14 08:47:05 denafrips sudo[9767]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:05 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:05 denafrips volumio[9727]: info: Loading plugin "wizard"... Sep 14 08:47:05 denafrips volumio[9727]: info: Loading plugin "networkfs"... Sep 14 08:47:05 denafrips volumio[9727]: info: Cannot mount NAS SDCard at system boot, trial number 1 ,retrying in 5 seconds Sep 14 08:47:05 denafrips volumio[9727]: info: Starting Udev Watcher for removable devices Sep 14 08:47:05 denafrips volumio[9727]: info: Ignoring mount for partition: boot Sep 14 08:47:05 denafrips volumio[9727]: info: Ignoring mount for partition: volumio Sep 14 08:47:05 denafrips volumio[9727]: info: Ignoring mount for partition: volumio_data Sep 14 08:47:05 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:05 denafrips volumio[9727]: info: Loading plugin "volumio_command_line_client"... Sep 14 08:47:05 denafrips volumio[9727]: info: Loading plugin "upnp"... Sep 14 08:47:05 denafrips volumio[9727]: info: [1757810825421] Starting Upmpd Daemon Sep 14 08:47:05 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:05 denafrips volumio[9727]: info: Loading plugin "my_music"... Sep 14 08:47:05 denafrips volumio[9727]: info: Loading plugin "mpd"... Sep 14 08:47:05 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:05 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 110. Sep 14 08:47:05 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:05 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:05 denafrips volumio[9727]: info: Loading plugin "upnp_browser"... Sep 14 08:47:06 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:06] [connect] Successful connection Sep 14 08:47:06 denafrips volumio[9727]: info: Loading plugin "alarm-clock"... Sep 14 08:47:06 denafrips volumio[9727]: info: Loading plugin "airplay_emulation"... Sep 14 08:47:06 denafrips volumio[9727]: info: Starting Shairport Sync Sep 14 08:47:06 denafrips volumio[9727]: info: Loading plugin "last_100"... Sep 14 08:47:06 denafrips volumio[9727]: info: Loading plugin "webradio"... Sep 14 08:47:06 denafrips volumio[9727]: info: Loading plugin "i2s_dacs"... Sep 14 08:47:06 denafrips volumio[9727]: info: I2S DAC not set, start Auto-detection Sep 14 08:47:06 denafrips volumio[9727]: info: Loading plugin "volumiodiscovery"... Sep 14 08:47:06 denafrips volumio[9727]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:06 denafrips volumio[9727]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:06 denafrips node[9727]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:06 denafrips volumio[9727]: *** WARNING *** For more information see Sep 14 08:47:06 denafrips volumio[9727]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:06 denafrips volumio[9727]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:06 denafrips volumio[9727]: *** WARNING *** For more information see Sep 14 08:47:06 denafrips node[9727]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:06 denafrips node[9727]: *** WARNING *** For more information see Sep 14 08:47:06 denafrips node[9727]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:06 denafrips node[9727]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:06 denafrips node[9727]: *** WARNING *** For more information see Sep 14 08:47:06 denafrips volumio[9727]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 14 08:47:06 denafrips volumio[9727]: info: Discovery: Started advertising with name: Denafrips Sep 14 08:47:06 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:06 denafrips volumio[9727]: info: Plugin spop is not enabled Sep 14 08:47:06 denafrips volumio[9727]: info: Loading plugin "outputs"... Sep 14 08:47:06 denafrips volumio[9727]: info: Loading plugin "albumart"... Sep 14 08:47:06 denafrips volumio[9727]: info: Plugin example_plugin is not enabled Sep 14 08:47:06 denafrips volumio[9727]: info: Loading plugin "inputs"... Sep 14 08:47:06 denafrips volumio[9727]: info: Loading plugin "updater_comm"... Sep 14 08:47:07 denafrips volumio[9727]: info: Plugin mpdemulation is not enabled Sep 14 08:47:07 denafrips volumio[9727]: info: Loading plugin "rest_api"... Sep 14 08:47:07 denafrips volumio[9727]: info: Loading plugin "websocket"... Sep 14 08:47:07 denafrips volumio[9781]: Traceback (most recent call last): Sep 14 08:47:07 denafrips volumio[9781]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:07 denafrips volumio[9781]: from camilladsp import CamillaConnection Sep 14 08:47:07 denafrips volumio[9781]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:07 denafrips volumio[9727]: info: Starting Socket.io Server version 2.3.0 Sep 14 08:47:07 denafrips volumio[9727]: info: Loading plugin "fusiondsp"... Sep 14 08:47:07 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:07 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:07 denafrips volumio[9727]: Forking 3 albumart workers Sep 14 08:47:07 denafrips volumio[9727]: info: Applying required configuration parameters for plugin fusiondsp Sep 14 08:47:07 denafrips volumio[9727]: info: Loading i18n strings for locale en Sep 14 08:47:07 denafrips volumio[9727]: Updating browse sources language Sep 14 08:47:07 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::initPlayerControls Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:08 denafrips volumio[9727]: Express server listening on port 3000 Sep 14 08:47:08 denafrips volumio[9727]: [Metrics] WebUI: 5s 414.89ms Sep 14 08:47:08 denafrips volumio[9727]: info: CoreStateMachine::resetVolumioState Sep 14 08:47:08 denafrips volumio[9727]: info: CoreStateMachine::getcurrentVolume Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:08 denafrips volumio[9727]: info: Volumio Network Manager: Network status updated: 2 Sep 14 08:47:08 denafrips volumio[9727]: Starting albumart workers Sep 14 08:47:08 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757810826 101 Sep 14 08:47:08 denafrips volumio[9727]: 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: 1 Sep 14 08:47:08 denafrips volumio[9727]: verbose: New Socket.io Connection to 192.168.0.71 from 192.168.0.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 2 Sep 14 08:47:08 denafrips volumio[9727]: verbose: New Socket.io Connection to 192.168.0.71:3000 from 192.168.0.51 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 14 08:47:08 denafrips volumio[9727]: info: CoreStateMachine::pushState Sep 14 08:47:08 denafrips volumio[9727]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:08 denafrips volumio[9727]: info: CoreStateMachine::updateTrackBlock Sep 14 08:47:08 denafrips volumio[9727]: info: CorePlayQueue::getTrackBlock Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:47:08 denafrips volumio[9727]: Starting albumart workers Sep 14 08:47:08 denafrips volumio[9727]: Starting albumart workers Sep 14 08:47:08 denafrips volumio[9727]: info: Reloading queue from file Sep 14 08:47:08 denafrips volumio[9727]: info: CoreStateMachine::setRepeat null single undefined Sep 14 08:47:08 denafrips volumio[9727]: info: CoreStateMachine::pushState Sep 14 08:47:08 denafrips volumio[9727]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:08 denafrips volumio[9727]: info: CoreStateMachine::setRandom null Sep 14 08:47:08 denafrips volumio[9727]: info: CoreStateMachine::pushState Sep 14 08:47:08 denafrips volumio[9727]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:08 denafrips volumio[9727]: info: Setting Device type: Raspberry PI Sep 14 08:47:08 denafrips volumio[9727]: info: CoreStateMachine::pushState Sep 14 08:47:08 denafrips volumio[9727]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:08 denafrips volumio[9727]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 14 08:47:08 denafrips volumio[9727]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Sep 14 08:47:08 denafrips volumio[9727]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 14 08:47:08 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 14 08:47:09 denafrips volumio[9727]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:09 denafrips volumio[9727]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:09 denafrips volumio[9727]: info: Completed loading Core Plugins Sep 14 08:47:09 denafrips volumio[9727]: info: Preparing to generate the ALSA configuration file Sep 14 08:47:09 denafrips volumio[9727]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:47:09 denafrips volumio[9727]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 08:47:09 denafrips volumio[9727]: info: Reading ALSA contributions from plugins. Sep 14 08:47:09 denafrips volumio[9727]: info: Discovery: adding 7d557128-016d-42d3-b02b-91b53d86abcc Sep 14 08:47:09 denafrips volumio[9727]: info: Discovery: Found device Denafrips Sep 14 08:47:09 denafrips volumio[9727]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:09 denafrips volumio[9727]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:09 denafrips volumio[9727]: info: Discovery: adding b390528d-acdb-4aca-b80a-77dcc5fa3e8b Sep 14 08:47:09 denafrips volumio[9727]: info: Discovery: Found device Bedtime Sep 14 08:47:09 denafrips volumio[9727]: info: Discovery: Connecting to remote: 192.168.0.51 Sep 14 08:47:09 denafrips volumio[9727]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:47:09 denafrips volumio[9727]: info: Output device has changed, restarting MPD Sep 14 08:47:09 denafrips volumio[9727]: info: Output device has changed, restarting Shairport Sync Sep 14 08:47:09 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:09 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:09 denafrips sudo[9841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:09 denafrips sudo[9841]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:09 denafrips sudo[9767]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:09 denafrips sudo[9841]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:09 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:09 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 111. Sep 14 08:47:09 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:09 denafrips sudo[9847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:09 denafrips sudo[9847]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:09 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:09 denafrips volumio[9727]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:09 denafrips systemd[1]: Stopping Music Player Daemon... Sep 14 08:47:09 denafrips volumio[9727]: info: ___________ START PLUGINS ___________ Sep 14 08:47:09 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:09 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:09 denafrips volumio[9727]: info: ControllerMpd::onStart: Initializing MPD Sep 14 08:47:09 denafrips volumio[9727]: info: Creating MPD Configuration file Sep 14 08:47:09 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:09 denafrips sudo[9856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:09 denafrips sudo[9856]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:09 denafrips sudo[9856]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:09 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:09 denafrips volumio[9727]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:09 denafrips volumio[9727]: info: [1757810829719] CoreMusicLibrary::Adding element Media Servers Sep 14 08:47:09 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:09 denafrips sudo[9859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:09 denafrips sudo[9859]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:09 denafrips sudo[9854]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:09 denafrips sudo[9854]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:09 denafrips sudo[9854]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:09 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:09 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:09 denafrips systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:09 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:09 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:09 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:09 denafrips volumio[9727]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:09 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:09 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:10 denafrips volumio[9727]: info: [1757810830060] CoreMusicLibrary::Adding element Last_100 Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:10 denafrips volumio[9727]: info: [1757810830067] CoreMusicLibrary::Adding element Webradio Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:10 denafrips volumio[9727]: info: Initializing BBC Radios Sep 14 08:47:10 denafrips sudo[9872]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:10 denafrips sudo[9872]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:10 denafrips sudo[9872]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: Loading i18n strings for locale en Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: FusionDsp - mixtype--------------------- Software Sep 14 08:47:10 denafrips volumio[9727]: info: Volumio Calling Home Sep 14 08:47:10 denafrips volumio[9727]: info: Preparing to generate the ALSA configuration file Sep 14 08:47:10 denafrips volumio[9727]: info: Cannot mount NAS SDCard at system boot, trial number 2 ,retrying in 5 seconds Sep 14 08:47:10 denafrips volumio[9727]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:47:10 denafrips volumio[9727]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 08:47:10 denafrips volumio[9727]: info: Reading ALSA contributions from plugins. Sep 14 08:47:10 denafrips volumio[9727]: info: MPD Permissions set Sep 14 08:47:10 denafrips volumio[9727]: info: MPD Permissions set Sep 14 08:47:10 denafrips volumio[9727]: info: Discovery: Connected to remote: 192.168.0.51 Sep 14 08:47:10 denafrips volumio[9727]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:10 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:11 denafrips volumio[9852]: Traceback (most recent call last): Sep 14 08:47:11 denafrips volumio[9852]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:11 denafrips volumio[9852]: from camilladsp import CamillaConnection Sep 14 08:47:11 denafrips volumio[9852]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:11 denafrips volumio[9727]: info: Volumio called home Sep 14 08:47:11 denafrips volumio[9727]: info: Starting Shairport Sync Sep 14 08:47:11 denafrips volumio[9727]: info: Starting Shairport Sync Sep 14 08:47:11 denafrips volumio[9727]: info: Starting Shairport Sync Sep 14 08:47:11 denafrips sudo[9911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:11 denafrips sudo[9911]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:11 denafrips sudo[9913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:11 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:11 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:11 denafrips sudo[9913]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:11 denafrips volumio[9727]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:11 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:11 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:11 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:11 denafrips sudo[9917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:11 denafrips sudo[9917]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:11 denafrips volumio[9727]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:47:11 denafrips volumio[9727]: info: Output device has changed, restarting MPD Sep 14 08:47:11 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:11 denafrips sudo[9913]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:11 denafrips volumio[9727]: info: Output device has changed, restarting Shairport Sync Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:11 denafrips sudo[9911]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:11 denafrips sudo[9923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:11 denafrips sudo[9923]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:11 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:11 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:11 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:11 denafrips sudo[9921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:11 denafrips sudo[9921]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:11 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:11 denafrips sudo[9921]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:11 denafrips sudo[9917]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:11 denafrips systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:11 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:11 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:11 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:11 denafrips volumio[9727]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:11 denafrips volumio[9727]: info: MPD Permissions set Sep 14 08:47:11 denafrips volumio[9727]: info: Shairport-Sync Started Sep 14 08:47:11 denafrips volumio[9727]: Error adding Membership: Error: addMembership EINVAL Sep 14 08:47:11 denafrips volumio[9727]: info: Shairport-Sync Started Sep 14 08:47:11 denafrips volumio[9727]: info: Shairport-Sync Started Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:11 denafrips volumio[9727]: info: Starting Shairport Sync Sep 14 08:47:11 denafrips sudo[9931]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:11 denafrips sudo[9931]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:11 denafrips sudo[9931]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:11 denafrips sudo[9939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:11 denafrips sudo[9939]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:11 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:11 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:11 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:11 denafrips volumio[9727]: verbose: New Socket.io Connection to 192.168.0.71 from 192.168.0.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Sep 14 08:47:11 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:11 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 08:47:11 denafrips sudo[9939]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:12 denafrips volumio[9727]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 08:47:12 denafrips volumio[9727]: info: Received Get System Info Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 08:47:12 denafrips volumio[9727]: info: Discovery: Getting this device information Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:12 denafrips volumio[9727]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:12 denafrips volumio[9727]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:12 denafrips volumio[9727]: info: Listing playlists Sep 14 08:47:12 denafrips volumio[9727]: info: Shairport-Sync Started Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:47:12 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:12 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:12 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:13 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:13 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:13 denafrips mpd[9941]: Sep 14 08:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:47:13 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:13 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 112. Sep 14 08:47:13 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:13 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:13 denafrips systemd[1]: Started Music Player Daemon. Sep 14 08:47:13 denafrips sudo[9847]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:13 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:13 denafrips sudo[9923]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:13 denafrips sudo[9859]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:13 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:14 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:14 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:14 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:14 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:14 denafrips volumio[9947]: Traceback (most recent call last): Sep 14 08:47:14 denafrips volumio[9947]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:14 denafrips volumio[9947]: from camilladsp import CamillaConnection Sep 14 08:47:14 denafrips volumio[9947]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:15 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:15 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:15 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:15 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:15 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:15 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:15 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:15 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:15 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:16 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:16 denafrips volumio[9727]: info: FusionDsp - Sep 14 08:47:16 denafrips volumio[9727]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:47:16 denafrips volumio[9727]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 14 08:47:16 denafrips volumio[9727]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:47:16 denafrips volumio[9727]: info: camilladsp service started and running in background, instance 1 Sep 14 08:47:16 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:16 denafrips volumio[9727]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:47:16 denafrips volumio[9727]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 14 08:47:16 denafrips volumio[9727]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:47:16 denafrips volumio[9727]: info: FusionDsp loaded Sep 14 08:47:16 denafrips volumio[9727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 08:47:16 denafrips sudo[9974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 14 08:47:16 denafrips sudo[9974]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:16 denafrips volumio[9727]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 14 08:47:16 denafrips volumio[9727]: info: Adding Signal Path Element [object Object] Sep 14 08:47:16 denafrips volumio[9727]: info: Adding fusiondspeq DSP Signal Path Element Sep 14 08:47:16 denafrips volumio[9727]: info: FusionDsp - ---- installed callbackRead Sep 14 08:47:16 denafrips volumio[9727]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:47:16 denafrips volumio[9727]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 14 08:47:16 denafrips volumio[9727]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 14 08:47:16 denafrips volumio[9727]: at onErrorNT (internal/child_process.js:465:16) Sep 14 08:47:16 denafrips volumio[9727]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 14 08:47:16 denafrips volumio[9727]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 14 08:47:16 denafrips volumio[9727]: at listOnTimeout (internal/timers.js:523:9) Sep 14 08:47:16 denafrips volumio[9727]: at processTimers (internal/timers.js:497:7) { Sep 14 08:47:16 denafrips volumio[9727]: errno: -2, Sep 14 08:47:16 denafrips volumio[9727]: code: 'ENOENT', Sep 14 08:47:16 denafrips volumio[9727]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:47:16 denafrips volumio[9727]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:47:16 denafrips volumio[9727]: spawnargs: [ Sep 14 08:47:16 denafrips volumio[9727]: '-p', Sep 14 08:47:16 denafrips volumio[9727]: 9876, Sep 14 08:47:16 denafrips volumio[9727]: '-o', Sep 14 08:47:16 denafrips volumio[9727]: '/tmp/camilladsp.log', Sep 14 08:47:16 denafrips volumio[9727]: '-l', Sep 14 08:47:16 denafrips volumio[9727]: 'warn', Sep 14 08:47:16 denafrips volumio[9727]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 14 08:47:16 denafrips volumio[9727]: ] Sep 14 08:47:16 denafrips volumio[9727]: } Sep 14 08:47:16 denafrips volumio[9727]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:47:16 denafrips sudo[9985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 08:46 Sep 14 08:47:16 denafrips sudo[9985]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:16 denafrips sudo[9985]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:16 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 14 08:47:16 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 14 08:47:16 denafrips systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:16 denafrips sudo[9974]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:16 denafrips systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 14 08:47:16 denafrips systemd[1]: Started dynamicswap service. Sep 14 08:47:16 denafrips systemd[1]: dynamicswap.service: Succeeded. Sep 14 08:47:16 denafrips systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 14 08:47:16 denafrips systemd[1]: volumio.service: Scheduled restart job, restart counter is at 35. Sep 14 08:47:16 denafrips systemd[1]: Started dynamicswap service. Sep 14 08:47:16 denafrips systemd[1]: Stopped Volumio Backend Module. Sep 14 08:47:16 denafrips systemd[1]: Started Volumio Backend Module. Sep 14 08:47:16 denafrips systemd[1]: dynamicswap.service: Succeeded. Sep 14 08:47:17 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:17 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 113. Sep 14 08:47:17 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:17 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:18 denafrips volumio[10008]: Traceback (most recent call last): Sep 14 08:47:18 denafrips volumio[10008]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:18 denafrips volumio[10008]: from camilladsp import CamillaConnection Sep 14 08:47:18 denafrips volumio[10008]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:18 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:18 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:19 denafrips volumio[9996]: info: ------------------------------------------- Sep 14 08:47:19 denafrips volumio[9996]: info: ----- Volumio3 ---- Sep 14 08:47:19 denafrips volumio[9996]: info: ------------------------------------------- Sep 14 08:47:19 denafrips volumio[9996]: info: ----- System startup ---- Sep 14 08:47:19 denafrips volumio[9996]: info: ------------------------------------------- Sep 14 08:47:20 denafrips volumio[9996]: info: MYVOLUMIO Environment detected Sep 14 08:47:20 denafrips volumio[9996]: info: Plugin folders cleanup Sep 14 08:47:20 denafrips volumio[9996]: info: Scanning into folder /volumio/app/plugins/ Sep 14 08:47:20 denafrips volumio[9996]: info: Scanning category audio_interface Sep 14 08:47:20 denafrips volumio[9996]: info: Scanning category miscellanea Sep 14 08:47:20 denafrips volumio[9996]: info: Scanning category music_service Sep 14 08:47:20 denafrips volumio[9996]: info: Scanning category plugins.json Sep 14 08:47:20 denafrips volumio[9996]: info: Scanning category system_controller Sep 14 08:47:20 denafrips volumio[9996]: info: Scanning category user_interface Sep 14 08:47:20 denafrips volumio[9996]: info: Scanning into folder /data/plugins/ Sep 14 08:47:20 denafrips volumio[9996]: info: Scanning category audio_interface Sep 14 08:47:20 denafrips volumio[9996]: info: Scanning category music_service Sep 14 08:47:20 denafrips volumio[9996]: info: Plugin folders cleanup completed Sep 14 08:47:20 denafrips volumio[9996]: info: ------------------------------------------- Sep 14 08:47:20 denafrips volumio[9996]: info: ----- Core plugins startup ---- Sep 14 08:47:20 denafrips volumio[9996]: info: ------------------------------------------- Sep 14 08:47:20 denafrips volumio[9996]: info: Loading plugins from folder /volumio/app/plugins/ Sep 14 08:47:20 denafrips volumio[9996]: info: Adding plugin upnp to MyMusic Plugins Sep 14 08:47:20 denafrips volumio[9996]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 14 08:47:20 denafrips volumio[9996]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 14 08:47:20 denafrips volumio[9996]: info: Loading plugins from folder /data/plugins/ Sep 14 08:47:20 denafrips volumio[9996]: info: Loading plugin "system"... Sep 14 08:47:20 denafrips volumio[9996]: info: Loading plugin "appearance"... Sep 14 08:47:20 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:20 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 114. Sep 14 08:47:20 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:20 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:21 denafrips volumio[9996]: info: Loading plugin "network"... Sep 14 08:47:21 denafrips volumio[9996]: info: Refreshing Cached IP Addresses Sep 14 08:47:21 denafrips sudo[10025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 08:47:21 denafrips sudo[10025]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:21 denafrips sudo[10025]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:21 denafrips sudo[10027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 08:47:21 denafrips sudo[10027]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:21 denafrips sudo[10027]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:21 denafrips volumio[9996]: info: Loading plugin "services"... Sep 14 08:47:21 denafrips volumio[9996]: info: Loading plugin "alsa_controller"... Sep 14 08:47:21 denafrips sudo[10036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 14 08:47:21 denafrips sudo[10036]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:21 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:21 denafrips volumio[9996]: info: Loading plugin "wizard"... Sep 14 08:47:21 denafrips volumio[9996]: info: Loading plugin "networkfs"... Sep 14 08:47:21 denafrips volumio[9996]: info: Cannot mount NAS SDCard at system boot, trial number 1 ,retrying in 5 seconds Sep 14 08:47:21 denafrips volumio[9996]: info: Starting Udev Watcher for removable devices Sep 14 08:47:21 denafrips volumio[9996]: info: Ignoring mount for partition: boot Sep 14 08:47:21 denafrips volumio[9996]: info: Ignoring mount for partition: volumio Sep 14 08:47:21 denafrips volumio[9996]: info: Ignoring mount for partition: volumio_data Sep 14 08:47:21 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:21 denafrips volumio[9996]: info: Loading plugin "volumio_command_line_client"... Sep 14 08:47:21 denafrips volumio[9996]: info: Loading plugin "upnp"... Sep 14 08:47:21 denafrips volumio[9996]: info: [1757810841631] Starting Upmpd Daemon Sep 14 08:47:21 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:21 denafrips volumio[9996]: info: Loading plugin "my_music"... Sep 14 08:47:21 denafrips volumio[9996]: info: Loading plugin "mpd"... Sep 14 08:47:21 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:21] [connect] Successful connection Sep 14 08:47:22 denafrips volumio[9996]: info: Loading plugin "upnp_browser"... Sep 14 08:47:22 denafrips volumio[10023]: Traceback (most recent call last): Sep 14 08:47:22 denafrips volumio[10023]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:22 denafrips volumio[10023]: from camilladsp import CamillaConnection Sep 14 08:47:22 denafrips volumio[10023]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:22 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:22 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:22 denafrips volumio[9996]: info: Loading plugin "alarm-clock"... Sep 14 08:47:22 denafrips volumio[9996]: info: Loading plugin "airplay_emulation"... Sep 14 08:47:22 denafrips volumio[9996]: info: Starting Shairport Sync Sep 14 08:47:22 denafrips volumio[9996]: info: Loading plugin "last_100"... Sep 14 08:47:22 denafrips volumio[9996]: info: Loading plugin "webradio"... Sep 14 08:47:22 denafrips volumio[9996]: info: Loading plugin "i2s_dacs"... Sep 14 08:47:22 denafrips volumio[9996]: info: I2S DAC not set, start Auto-detection Sep 14 08:47:22 denafrips volumio[9996]: info: Loading plugin "volumiodiscovery"... Sep 14 08:47:22 denafrips volumio[9996]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:22 denafrips volumio[9996]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:22 denafrips node[9996]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:22 denafrips volumio[9996]: *** WARNING *** For more information see Sep 14 08:47:22 denafrips volumio[9996]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:22 denafrips volumio[9996]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:22 denafrips volumio[9996]: *** WARNING *** For more information see Sep 14 08:47:22 denafrips node[9996]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:22 denafrips node[9996]: *** WARNING *** For more information see Sep 14 08:47:22 denafrips node[9996]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:22 denafrips node[9996]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:22 denafrips node[9996]: *** WARNING *** For more information see Sep 14 08:47:22 denafrips volumio[9996]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 14 08:47:22 denafrips volumio[9996]: info: Discovery: Started advertising with name: Denafrips Sep 14 08:47:22 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:22 denafrips volumio[9996]: info: Plugin spop is not enabled Sep 14 08:47:22 denafrips volumio[9996]: info: Loading plugin "outputs"... Sep 14 08:47:23 denafrips volumio[9996]: info: Loading plugin "albumart"... Sep 14 08:47:23 denafrips volumio[9996]: info: Plugin example_plugin is not enabled Sep 14 08:47:23 denafrips volumio[9996]: info: Loading plugin "inputs"... Sep 14 08:47:23 denafrips volumio[9996]: info: Loading plugin "updater_comm"... Sep 14 08:47:23 denafrips volumio[9996]: info: Plugin mpdemulation is not enabled Sep 14 08:47:23 denafrips volumio[9996]: info: Loading plugin "rest_api"... Sep 14 08:47:23 denafrips volumio[9996]: info: Loading plugin "websocket"... Sep 14 08:47:23 denafrips volumio[9996]: info: Starting Socket.io Server version 2.3.0 Sep 14 08:47:23 denafrips volumio[9996]: info: Loading plugin "fusiondsp"... Sep 14 08:47:23 denafrips volumio[9996]: Forking 3 albumart workers Sep 14 08:47:23 denafrips volumio[9996]: info: Applying required configuration parameters for plugin fusiondsp Sep 14 08:47:24 denafrips volumio[9996]: info: Loading i18n strings for locale en Sep 14 08:47:24 denafrips volumio[9996]: Updating browse sources language Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::initPlayerControls Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:24 denafrips volumio[9996]: Express server listening on port 3000 Sep 14 08:47:24 denafrips volumio[9996]: [Metrics] WebUI: 5s 978.52ms Sep 14 08:47:24 denafrips volumio[9996]: info: CoreStateMachine::resetVolumioState Sep 14 08:47:24 denafrips volumio[9996]: info: CoreStateMachine::getcurrentVolume Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:24 denafrips volumio[9996]: info: Volumio Network Manager: Network status updated: 2 Sep 14 08:47:24 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:24 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 115. Sep 14 08:47:24 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:24 denafrips volumio[9996]: verbose: New Socket.io Connection to 192.168.0.71 from 192.168.0.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 1 Sep 14 08:47:24 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757810841 101 Sep 14 08:47:24 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:24 denafrips volumio[9996]: Starting albumart workers Sep 14 08:47:24 denafrips volumio[9996]: Starting albumart workers Sep 14 08:47:24 denafrips volumio[9996]: 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: 2 Sep 14 08:47:24 denafrips volumio[9996]: verbose: New Socket.io Connection to 192.168.0.71:3000 from 192.168.0.51 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 14 08:47:24 denafrips volumio[9996]: verbose: New Socket.io Connection to 192.168.0.71 from 192.168.0.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 Sep 14 08:47:24 denafrips volumio[9996]: info: CoreStateMachine::pushState Sep 14 08:47:24 denafrips volumio[9996]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:24 denafrips volumio[9996]: Starting albumart workers Sep 14 08:47:24 denafrips volumio[9996]: info: CoreStateMachine::updateTrackBlock Sep 14 08:47:24 denafrips volumio[9996]: info: CorePlayQueue::getTrackBlock Sep 14 08:47:24 denafrips volumio[9996]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:47:25 denafrips volumio[9996]: info: Reloading queue from file Sep 14 08:47:25 denafrips volumio[9996]: info: CoreStateMachine::setRepeat null single undefined Sep 14 08:47:25 denafrips volumio[9996]: info: CoreStateMachine::pushState Sep 14 08:47:25 denafrips volumio[9996]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:25 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:47:25 denafrips volumio[9996]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:25 denafrips volumio[9996]: info: CoreStateMachine::setRandom null Sep 14 08:47:25 denafrips volumio[9996]: info: CoreStateMachine::pushState Sep 14 08:47:25 denafrips volumio[9996]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:25 denafrips volumio[9996]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:25 denafrips volumio[9996]: info: Setting Device type: Raspberry PI Sep 14 08:47:25 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 14 08:47:25 denafrips volumio[9996]: info: CoreStateMachine::pushState Sep 14 08:47:25 denafrips volumio[9996]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:25 denafrips volumio[9996]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:25 denafrips volumio[9996]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 14 08:47:25 denafrips volumio[9996]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Sep 14 08:47:25 denafrips volumio[9996]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 14 08:47:25 denafrips volumio[9996]: info: Discovery: adding b390528d-acdb-4aca-b80a-77dcc5fa3e8b Sep 14 08:47:25 denafrips volumio[9996]: info: Discovery: Found device Bedtime Sep 14 08:47:25 denafrips volumio[9996]: info: Discovery: Connecting to remote: 192.168.0.51 Sep 14 08:47:25 denafrips volumio[9996]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:25 denafrips volumio[9996]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:25 denafrips sudo[10036]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:25 denafrips volumio[9996]: info: Discovery: Connected to remote: 192.168.0.51 Sep 14 08:47:25 denafrips volumio[9996]: info: Discovery: adding 7d557128-016d-42d3-b02b-91b53d86abcc Sep 14 08:47:25 denafrips volumio[9996]: info: Discovery: Found device Denafrips Sep 14 08:47:25 denafrips volumio[9996]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:25 denafrips volumio[9996]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:25 denafrips volumio[9996]: info: Completed loading Core Plugins Sep 14 08:47:25 denafrips volumio[9996]: info: Preparing to generate the ALSA configuration file Sep 14 08:47:25 denafrips volumio[9996]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:47:25 denafrips volumio[9996]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 08:47:25 denafrips volumio[9996]: info: Reading ALSA contributions from plugins. Sep 14 08:47:25 denafrips volumio[9996]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:47:25 denafrips volumio[9996]: info: Output device has changed, restarting MPD Sep 14 08:47:26 denafrips sudo[10119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:26 denafrips volumio[9996]: info: Output device has changed, restarting Shairport Sync Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:26 denafrips sudo[10119]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:26 denafrips sudo[10119]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:26 denafrips sudo[10121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:26 denafrips sudo[10121]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:26 denafrips systemd[1]: Stopping Music Player Daemon... Sep 14 08:47:26 denafrips volumio[9996]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:26 denafrips volumio[9996]: info: ___________ START PLUGINS ___________ Sep 14 08:47:26 denafrips volumio[9996]: info: ControllerMpd::onStart: Initializing MPD Sep 14 08:47:26 denafrips volumio[9996]: info: Creating MPD Configuration file Sep 14 08:47:26 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:26 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:26 denafrips volumio[9996]: info: [1757810846271] CoreMusicLibrary::Adding element Media Servers Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:26 denafrips sudo[10132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:26 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:26 denafrips sudo[10132]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:26 denafrips sudo[10132]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:26 denafrips sudo[10139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:26 denafrips sudo[10139]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:26 denafrips systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 14 08:47:26 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:26 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:26 denafrips volumio[10089]: Traceback (most recent call last): Sep 14 08:47:26 denafrips volumio[10089]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:26 denafrips volumio[10089]: from camilladsp import CamillaConnection Sep 14 08:47:26 denafrips volumio[10089]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:26 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:26 denafrips volumio[9996]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:26 denafrips volumio[9996]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:26 denafrips volumio[9996]: info: [1757810846546] CoreMusicLibrary::Adding element Last_100 Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:26 denafrips volumio[9996]: info: [1757810846551] CoreMusicLibrary::Adding element Webradio Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:26 denafrips volumio[9996]: info: Initializing BBC Radios Sep 14 08:47:26 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:26 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:26 denafrips sudo[10145]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:26 denafrips sudo[10145]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:26 denafrips sudo[10145]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:26 denafrips volumio[9996]: info: Loading i18n strings for locale en Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 14 08:47:26 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:26 denafrips volumio[9996]: info: FusionDsp - mixtype--------------------- Software Sep 14 08:47:26 denafrips volumio[9996]: info: Volumio Calling Home Sep 14 08:47:26 denafrips volumio[9996]: info: Preparing to generate the ALSA configuration file Sep 14 08:47:26 denafrips volumio[9996]: info: Cannot mount NAS SDCard at system boot, trial number 2 ,retrying in 5 seconds Sep 14 08:47:26 denafrips volumio[9996]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:47:26 denafrips volumio[9996]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 08:47:26 denafrips volumio[9996]: info: Reading ALSA contributions from plugins. Sep 14 08:47:26 denafrips volumio[9996]: info: MPD Permissions set Sep 14 08:47:26 denafrips volumio[9996]: info: MPD Permissions set Sep 14 08:47:27 denafrips volumio[9996]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: Volumio called home Sep 14 08:47:27 denafrips volumio[9996]: info: Starting Shairport Sync Sep 14 08:47:27 denafrips volumio[9996]: info: Starting Shairport Sync Sep 14 08:47:27 denafrips sudo[10173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:27 denafrips volumio[9996]: info: Starting Shairport Sync Sep 14 08:47:27 denafrips sudo[10173]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:27 denafrips sudo[10175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:27 denafrips sudo[10175]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:27 denafrips volumio[9996]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:27 denafrips sudo[10178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:27 denafrips sudo[10178]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:27 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:27 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:27 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:27 denafrips volumio[9996]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:47:27 denafrips volumio[9996]: info: Output device has changed, restarting MPD Sep 14 08:47:27 denafrips volumio[9996]: info: Output device has changed, restarting Shairport Sync Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:27 denafrips sudo[10183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:27 denafrips sudo[10183]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:27 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:27 denafrips sudo[10173]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:27 denafrips sudo[10175]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:27 denafrips sudo[10185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:27 denafrips sudo[10185]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:27 denafrips sudo[10183]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:27 denafrips volumio[9996]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:27 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:27 denafrips systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:27 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:27 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:27 denafrips volumio[9996]: info: MPD Permissions set Sep 14 08:47:27 denafrips volumio[9996]: info: Shairport-Sync Started Sep 14 08:47:27 denafrips volumio[9996]: Error adding Membership: Error: addMembership EINVAL Sep 14 08:47:27 denafrips volumio[9996]: info: Shairport-Sync Started Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:27 denafrips sudo[10178]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:27 denafrips systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:27 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:27 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:27 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:27 denafrips volumio[9996]: info: Shairport-Sync Started Sep 14 08:47:27 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:27 denafrips volumio[9996]: info: Starting Shairport Sync Sep 14 08:47:27 denafrips sudo[10199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:27 denafrips sudo[10199]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:27 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:27 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:27 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:27 denafrips sudo[10196]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:27 denafrips sudo[10196]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:27 denafrips sudo[10196]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:27 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:27 denafrips sudo[10199]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:27 denafrips volumio[9996]: info: Shairport-Sync Started Sep 14 08:47:28 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:28 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 116. Sep 14 08:47:28 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:28 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:28 denafrips volumio[9996]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:47:28 denafrips volumio[9996]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 14 08:47:28 denafrips volumio[9996]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:47:28 denafrips volumio[9996]: info: camilladsp service started and running in background, instance 1 Sep 14 08:47:28 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:28 denafrips volumio[9996]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:47:28 denafrips volumio[9996]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 14 08:47:28 denafrips volumio[9996]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:47:28 denafrips volumio[9996]: info: FusionDsp loaded Sep 14 08:47:28 denafrips volumio[9996]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 08:47:28 denafrips sudo[10219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 14 08:47:28 denafrips sudo[10219]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:28 denafrips volumio[9996]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 14 08:47:28 denafrips volumio[9996]: info: Adding Signal Path Element [object Object] Sep 14 08:47:28 denafrips volumio[9996]: info: Adding fusiondspeq DSP Signal Path Element Sep 14 08:47:28 denafrips volumio[9996]: info: FusionDsp - ---- installed callbackRead Sep 14 08:47:28 denafrips volumio[9996]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:47:28 denafrips sudo[10219]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:28 denafrips volumio[9996]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 14 08:47:28 denafrips volumio[9996]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 14 08:47:28 denafrips volumio[9996]: at onErrorNT (internal/child_process.js:465:16) Sep 14 08:47:28 denafrips volumio[9996]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 14 08:47:28 denafrips volumio[9996]: errno: -2, Sep 14 08:47:28 denafrips volumio[9996]: code: 'ENOENT', Sep 14 08:47:28 denafrips volumio[9996]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:47:28 denafrips volumio[9996]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:47:28 denafrips volumio[9996]: spawnargs: [ Sep 14 08:47:28 denafrips volumio[9996]: '-p', Sep 14 08:47:28 denafrips volumio[9996]: 9876, Sep 14 08:47:28 denafrips volumio[9996]: '-o', Sep 14 08:47:28 denafrips volumio[9996]: '/tmp/camilladsp.log', Sep 14 08:47:28 denafrips volumio[9996]: '-l', Sep 14 08:47:28 denafrips volumio[9996]: 'warn', Sep 14 08:47:28 denafrips volumio[9996]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 14 08:47:28 denafrips volumio[9996]: ] Sep 14 08:47:28 denafrips volumio[9996]: } Sep 14 08:47:28 denafrips volumio[9996]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:47:29 denafrips mpd[10204]: Sep 14 08:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:47:29 denafrips systemd[1]: Started Music Player Daemon. Sep 14 08:47:29 denafrips sudo[10139]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:29 denafrips sudo[10121]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:29 denafrips sudo[10185]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:29 denafrips sudo[10235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 08:46 Sep 14 08:47:29 denafrips sudo[10235]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:30 denafrips sudo[10235]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:30 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:30] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 14 08:47:30 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:30] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 14 08:47:30 denafrips systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:30 denafrips volumio[10208]: Traceback (most recent call last): Sep 14 08:47:30 denafrips volumio[10208]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:30 denafrips volumio[10208]: from camilladsp import CamillaConnection Sep 14 08:47:30 denafrips volumio[10208]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:30 denafrips systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 14 08:47:30 denafrips systemd[1]: Started dynamicswap service. Sep 14 08:47:30 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:30 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:30 denafrips systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 14 08:47:30 denafrips systemd[1]: volumio.service: Scheduled restart job, restart counter is at 36. Sep 14 08:47:30 denafrips systemd[1]: Stopped Volumio Backend Module. Sep 14 08:47:30 denafrips systemd[1]: Started Volumio Backend Module. Sep 14 08:47:30 denafrips systemd[1]: dynamicswap.service: Succeeded. Sep 14 08:47:31 denafrips volumio[10245]: info: ------------------------------------------- Sep 14 08:47:31 denafrips volumio[10245]: info: ----- Volumio3 ---- Sep 14 08:47:31 denafrips volumio[10245]: info: ------------------------------------------- Sep 14 08:47:31 denafrips volumio[10245]: info: ----- System startup ---- Sep 14 08:47:31 denafrips volumio[10245]: info: ------------------------------------------- Sep 14 08:47:31 denafrips volumio[10245]: info: MYVOLUMIO Environment detected Sep 14 08:47:31 denafrips volumio[10245]: info: Plugin folders cleanup Sep 14 08:47:31 denafrips volumio[10245]: info: Scanning into folder /volumio/app/plugins/ Sep 14 08:47:31 denafrips volumio[10245]: info: Scanning category audio_interface Sep 14 08:47:31 denafrips volumio[10245]: info: Scanning category miscellanea Sep 14 08:47:31 denafrips volumio[10245]: info: Scanning category music_service Sep 14 08:47:31 denafrips volumio[10245]: info: Scanning category plugins.json Sep 14 08:47:31 denafrips volumio[10245]: info: Scanning category system_controller Sep 14 08:47:31 denafrips volumio[10245]: info: Scanning category user_interface Sep 14 08:47:31 denafrips volumio[10245]: info: Scanning into folder /data/plugins/ Sep 14 08:47:31 denafrips volumio[10245]: info: Scanning category audio_interface Sep 14 08:47:31 denafrips volumio[10245]: info: Scanning category music_service Sep 14 08:47:31 denafrips volumio[10245]: info: Plugin folders cleanup completed Sep 14 08:47:31 denafrips volumio[10245]: info: ------------------------------------------- Sep 14 08:47:31 denafrips volumio[10245]: info: ----- Core plugins startup ---- Sep 14 08:47:31 denafrips volumio[10245]: info: ------------------------------------------- Sep 14 08:47:31 denafrips volumio[10245]: info: Loading plugins from folder /volumio/app/plugins/ Sep 14 08:47:31 denafrips volumio[10245]: info: Adding plugin upnp to MyMusic Plugins Sep 14 08:47:31 denafrips volumio[10245]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 14 08:47:31 denafrips volumio[10245]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 14 08:47:31 denafrips volumio[10245]: info: Loading plugins from folder /data/plugins/ Sep 14 08:47:31 denafrips volumio[10245]: info: Loading plugin "system"... Sep 14 08:47:31 denafrips volumio[10245]: info: Loading plugin "appearance"... Sep 14 08:47:32 denafrips volumio[10245]: info: Loading plugin "network"... Sep 14 08:47:32 denafrips volumio[10245]: info: Refreshing Cached IP Addresses Sep 14 08:47:32 denafrips sudo[10266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 08:47:32 denafrips sudo[10266]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:32 denafrips sudo[10266]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:32 denafrips sudo[10268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 08:47:32 denafrips sudo[10268]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:32 denafrips sudo[10268]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:32 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:32 denafrips volumio[10245]: info: Loading plugin "services"... Sep 14 08:47:32 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 117. Sep 14 08:47:32 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:32 denafrips volumio[10245]: info: Loading plugin "alsa_controller"... Sep 14 08:47:32 denafrips sudo[10277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 14 08:47:32 denafrips sudo[10277]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:32 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:32 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:32 denafrips volumio[10245]: info: Loading plugin "wizard"... Sep 14 08:47:32 denafrips volumio[10245]: info: Loading plugin "networkfs"... Sep 14 08:47:32 denafrips volumio[10245]: info: Cannot mount NAS SDCard at system boot, trial number 1 ,retrying in 5 seconds Sep 14 08:47:32 denafrips volumio[10245]: info: Starting Udev Watcher for removable devices Sep 14 08:47:32 denafrips volumio[10245]: info: Ignoring mount for partition: boot Sep 14 08:47:32 denafrips volumio[10245]: info: Ignoring mount for partition: volumio Sep 14 08:47:32 denafrips volumio[10245]: info: Ignoring mount for partition: volumio_data Sep 14 08:47:32 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:32 denafrips volumio[10245]: info: Loading plugin "volumio_command_line_client"... Sep 14 08:47:32 denafrips volumio[10245]: info: Loading plugin "upnp"... Sep 14 08:47:32 denafrips volumio[10245]: info: [1757810852562] Starting Upmpd Daemon Sep 14 08:47:32 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:32 denafrips volumio[10245]: info: Loading plugin "my_music"... Sep 14 08:47:32 denafrips volumio[10245]: info: Loading plugin "mpd"... Sep 14 08:47:33 denafrips volumio[10245]: info: Loading plugin "upnp_browser"... Sep 14 08:47:33 denafrips volumio[10245]: info: Loading plugin "alarm-clock"... Sep 14 08:47:33 denafrips volumio[10245]: info: Loading plugin "airplay_emulation"... Sep 14 08:47:33 denafrips volumio[10245]: info: Starting Shairport Sync Sep 14 08:47:33 denafrips volumio[10245]: info: Loading plugin "last_100"... Sep 14 08:47:33 denafrips volumio[10245]: info: Loading plugin "webradio"... Sep 14 08:47:33 denafrips volumio[10245]: info: Loading plugin "i2s_dacs"... Sep 14 08:47:33 denafrips volumio[10245]: info: I2S DAC not set, start Auto-detection Sep 14 08:47:33 denafrips volumio[10245]: info: Loading plugin "volumiodiscovery"... Sep 14 08:47:33 denafrips volumio[10281]: Traceback (most recent call last): Sep 14 08:47:33 denafrips volumio[10281]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:33 denafrips volumio[10281]: from camilladsp import CamillaConnection Sep 14 08:47:33 denafrips volumio[10281]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:33 denafrips volumio[10245]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:33 denafrips volumio[10245]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:33 denafrips node[10245]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:33 denafrips volumio[10245]: *** WARNING *** For more information see Sep 14 08:47:33 denafrips volumio[10245]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:33 denafrips volumio[10245]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:33 denafrips volumio[10245]: *** WARNING *** For more information see Sep 14 08:47:33 denafrips node[10245]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:33 denafrips node[10245]: *** WARNING *** For more information see Sep 14 08:47:33 denafrips node[10245]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:33 denafrips node[10245]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:33 denafrips node[10245]: *** WARNING *** For more information see Sep 14 08:47:33 denafrips volumio[10245]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 14 08:47:33 denafrips volumio[10245]: info: Discovery: Started advertising with name: Denafrips Sep 14 08:47:33 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:33 denafrips volumio[10245]: info: Plugin spop is not enabled Sep 14 08:47:33 denafrips volumio[10245]: info: Loading plugin "outputs"... Sep 14 08:47:33 denafrips volumio[10245]: info: Loading plugin "albumart"... Sep 14 08:47:34 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:34 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:34 denafrips volumio[10245]: info: Plugin example_plugin is not enabled Sep 14 08:47:34 denafrips volumio[10245]: info: Loading plugin "inputs"... Sep 14 08:47:34 denafrips volumio[10245]: info: Loading plugin "updater_comm"... Sep 14 08:47:34 denafrips volumio[10245]: info: Plugin mpdemulation is not enabled Sep 14 08:47:34 denafrips volumio[10245]: info: Loading plugin "rest_api"... Sep 14 08:47:34 denafrips volumio[10245]: info: Loading plugin "websocket"... Sep 14 08:47:34 denafrips volumio[10245]: info: Starting Socket.io Server version 2.3.0 Sep 14 08:47:34 denafrips volumio[10245]: info: Loading plugin "fusiondsp"... Sep 14 08:47:34 denafrips volumio[10245]: Forking 3 albumart workers Sep 14 08:47:34 denafrips volumio[10245]: info: Applying required configuration parameters for plugin fusiondsp Sep 14 08:47:34 denafrips volumio[10245]: info: Loading i18n strings for locale en Sep 14 08:47:34 denafrips volumio[10245]: Updating browse sources language Sep 14 08:47:34 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:35 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:35] [connect] Successful connection Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::initPlayerControls Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:35 denafrips volumio[10245]: Express server listening on port 3000 Sep 14 08:47:35 denafrips volumio[10245]: [Metrics] WebUI: 4s 453.42ms Sep 14 08:47:35 denafrips volumio[10245]: info: CoreStateMachine::resetVolumioState Sep 14 08:47:35 denafrips volumio[10245]: info: CoreStateMachine::getcurrentVolume Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:35 denafrips volumio[10245]: info: Volumio Network Manager: Network status updated: 2 Sep 14 08:47:35 denafrips volumio[10245]: verbose: New Socket.io Connection to 192.168.0.71:3000 from 192.168.0.51 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 14 08:47:35 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757810855 101 Sep 14 08:47:35 denafrips volumio[10245]: 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: 2 Sep 14 08:47:35 denafrips volumio[10245]: verbose: New Socket.io Connection to 192.168.0.71 from 192.168.0.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3 Sep 14 08:47:35 denafrips volumio[10245]: info: CoreStateMachine::pushState Sep 14 08:47:35 denafrips volumio[10245]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:35 denafrips volumio[10245]: info: CoreStateMachine::updateTrackBlock Sep 14 08:47:35 denafrips volumio[10245]: info: CorePlayQueue::getTrackBlock Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:47:35 denafrips volumio[10245]: Starting albumart workers Sep 14 08:47:35 denafrips volumio[10245]: Starting albumart workers Sep 14 08:47:35 denafrips volumio[10245]: Starting albumart workers Sep 14 08:47:35 denafrips volumio[10245]: info: Reloading queue from file Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 14 08:47:35 denafrips volumio[10245]: info: CoreStateMachine::setRepeat null single undefined Sep 14 08:47:35 denafrips volumio[10245]: info: CoreStateMachine::pushState Sep 14 08:47:35 denafrips volumio[10245]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:35 denafrips volumio[10245]: info: CoreStateMachine::setRandom null Sep 14 08:47:35 denafrips volumio[10245]: info: CoreStateMachine::pushState Sep 14 08:47:35 denafrips volumio[10245]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:35 denafrips volumio[10245]: info: Setting Device type: Raspberry PI Sep 14 08:47:35 denafrips volumio[10245]: info: CoreStateMachine::pushState Sep 14 08:47:35 denafrips volumio[10245]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:35 denafrips volumio[10245]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:35 denafrips volumio[10245]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 14 08:47:35 denafrips volumio[10245]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Sep 14 08:47:35 denafrips volumio[10245]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 14 08:47:36 denafrips volumio[10245]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:36 denafrips volumio[10245]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:36 denafrips volumio[10245]: info: Completed loading Core Plugins Sep 14 08:47:36 denafrips volumio[10245]: info: Preparing to generate the ALSA configuration file Sep 14 08:47:36 denafrips volumio[10245]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:47:36 denafrips volumio[10245]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 08:47:36 denafrips volumio[10245]: info: Reading ALSA contributions from plugins. Sep 14 08:47:36 denafrips volumio[10245]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:47:36 denafrips volumio[10245]: info: Output device has changed, restarting MPD Sep 14 08:47:36 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:36 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 118. Sep 14 08:47:36 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:36 denafrips sudo[10344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:36 denafrips sudo[10344]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:36 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:36 denafrips volumio[10245]: info: Output device has changed, restarting Shairport Sync Sep 14 08:47:36 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:36 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:36 denafrips sudo[10344]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:36 denafrips sudo[10348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:36 denafrips sudo[10348]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:36 denafrips systemd[1]: Stopping Music Player Daemon... Sep 14 08:47:36 denafrips volumio[10245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:36 denafrips volumio[10245]: info: ___________ START PLUGINS ___________ Sep 14 08:47:36 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:36 denafrips volumio[10245]: info: ControllerMpd::onStart: Initializing MPD Sep 14 08:47:36 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:36 denafrips volumio[10245]: info: Creating MPD Configuration file Sep 14 08:47:36 denafrips sudo[10355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:36 denafrips sudo[10355]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:36 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:36 denafrips sudo[10355]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:36 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:36 denafrips volumio[10245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:36 denafrips volumio[10245]: info: [1757810856536] CoreMusicLibrary::Adding element Media Servers Sep 14 08:47:36 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:36 denafrips sudo[10277]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:36 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:36 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:36 denafrips sudo[10353]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:36 denafrips sudo[10353]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:36 denafrips sudo[10358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:36 denafrips sudo[10358]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:36 denafrips sudo[10353]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:36 denafrips systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:36 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:36 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:36 denafrips volumio[10245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:36 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:36 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:36 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:37 denafrips sudo[10365]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:37 denafrips sudo[10365]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:37 denafrips volumio[10245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:37 denafrips volumio[10245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:37 denafrips volumio[10245]: info: [1757810857043] CoreMusicLibrary::Adding element Last_100 Sep 14 08:47:37 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:37 denafrips volumio[10245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:37 denafrips sudo[10365]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:37 denafrips volumio[10245]: info: [1757810857063] CoreMusicLibrary::Adding element Webradio Sep 14 08:47:37 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:37 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:37 denafrips volumio[10245]: info: Initializing BBC Radios Sep 14 08:47:37 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:37 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:37 denafrips volumio[10245]: info: Loading i18n strings for locale en Sep 14 08:47:37 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 14 08:47:37 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:37 denafrips volumio[10245]: info: FusionDsp - mixtype--------------------- Software Sep 14 08:47:37 denafrips volumio[10245]: info: Volumio Calling Home Sep 14 08:47:37 denafrips volumio[10245]: info: Preparing to generate the ALSA configuration file Sep 14 08:47:37 denafrips volumio[10245]: info: Cannot mount NAS SDCard at system boot, trial number 2 ,retrying in 5 seconds Sep 14 08:47:37 denafrips volumio[10245]: info: Discovery: adding b390528d-acdb-4aca-b80a-77dcc5fa3e8b Sep 14 08:47:37 denafrips volumio[10245]: info: Discovery: Found device Bedtime Sep 14 08:47:37 denafrips volumio[10245]: info: Discovery: Connecting to remote: 192.168.0.51 Sep 14 08:47:37 denafrips volumio[10245]: info: Discovery: adding 7d557128-016d-42d3-b02b-91b53d86abcc Sep 14 08:47:37 denafrips volumio[10245]: info: Discovery: Found device Denafrips Sep 14 08:47:37 denafrips volumio[10245]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:37 denafrips volumio[10245]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:37 denafrips volumio[10245]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:47:37 denafrips volumio[10245]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 08:47:37 denafrips volumio[10245]: info: Reading ALSA contributions from plugins. Sep 14 08:47:38 denafrips volumio[10345]: Traceback (most recent call last): Sep 14 08:47:38 denafrips volumio[10345]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:38 denafrips volumio[10345]: from camilladsp import CamillaConnection Sep 14 08:47:38 denafrips volumio[10345]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:38 denafrips volumio[10245]: info: MPD Permissions set Sep 14 08:47:38 denafrips volumio[10245]: info: MPD Permissions set Sep 14 08:47:38 denafrips volumio[10245]: info: Discovery: Connected to remote: 192.168.0.51 Sep 14 08:47:38 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:38 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:38 denafrips volumio[10245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 14 08:47:38 denafrips volumio[10245]: info: Volumio called home Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: Starting Shairport Sync Sep 14 08:47:38 denafrips volumio[10245]: info: Starting Shairport Sync Sep 14 08:47:38 denafrips volumio[10245]: info: Starting Shairport Sync Sep 14 08:47:38 denafrips sudo[10455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:38 denafrips sudo[10455]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:38 denafrips sudo[10457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:38 denafrips sudo[10457]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:38 denafrips sudo[10461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:38 denafrips sudo[10461]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:38 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:38 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:38 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:38 denafrips volumio[10245]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:38 denafrips volumio[10245]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:47:38 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:38 denafrips volumio[10245]: info: Output device has changed, restarting MPD Sep 14 08:47:38 denafrips sudo[10455]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:38 denafrips sudo[10457]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:38 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:38 denafrips systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:38 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:38 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:38 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:38 denafrips sudo[10461]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:38 denafrips volumio[10245]: info: Output device has changed, restarting Shairport Sync Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:38 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:39 denafrips sudo[10465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:39 denafrips sudo[10465]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:39 denafrips sudo[10468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:39 denafrips sudo[10465]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:39 denafrips sudo[10468]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:39 denafrips systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:39 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:39 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:39 denafrips volumio[10245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:39 denafrips volumio[10245]: info: MPD Permissions set Sep 14 08:47:39 denafrips volumio[10245]: info: Shairport-Sync Started Sep 14 08:47:39 denafrips volumio[10245]: Error adding Membership: Error: addMembership EINVAL Sep 14 08:47:39 denafrips volumio[10245]: info: Shairport-Sync Started Sep 14 08:47:39 denafrips volumio[10245]: info: Shairport-Sync Started Sep 14 08:47:39 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:39 denafrips volumio[10245]: info: Starting Shairport Sync Sep 14 08:47:39 denafrips sudo[10476]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:39 denafrips sudo[10476]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:39 denafrips sudo[10476]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:39 denafrips sudo[10482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:39 denafrips sudo[10482]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:39 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:39 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:39 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:39 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:39 denafrips sudo[10482]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:39 denafrips volumio[10245]: info: Shairport-Sync Started Sep 14 08:47:39 denafrips volumio[10245]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:47:39 denafrips volumio[10245]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 14 08:47:39 denafrips volumio[10245]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:47:39 denafrips volumio[10245]: info: camilladsp service started and running in background, instance 1 Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:39 denafrips volumio[10245]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:47:39 denafrips volumio[10245]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 14 08:47:39 denafrips volumio[10245]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:47:39 denafrips volumio[10245]: info: FusionDsp loaded Sep 14 08:47:39 denafrips volumio[10245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 08:47:39 denafrips sudo[10499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 14 08:47:39 denafrips sudo[10499]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:39 denafrips volumio[10245]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 14 08:47:39 denafrips volumio[10245]: info: Adding Signal Path Element [object Object] Sep 14 08:47:39 denafrips volumio[10245]: info: Adding fusiondspeq DSP Signal Path Element Sep 14 08:47:39 denafrips volumio[10245]: info: FusionDsp - ---- installed callbackRead Sep 14 08:47:39 denafrips volumio[10245]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:47:39 denafrips volumio[10245]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 14 08:47:39 denafrips volumio[10245]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 14 08:47:39 denafrips volumio[10245]: at onErrorNT (internal/child_process.js:465:16) Sep 14 08:47:39 denafrips volumio[10245]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 14 08:47:39 denafrips volumio[10245]: errno: -2, Sep 14 08:47:39 denafrips volumio[10245]: code: 'ENOENT', Sep 14 08:47:39 denafrips volumio[10245]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:47:39 denafrips volumio[10245]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:47:39 denafrips volumio[10245]: spawnargs: [ Sep 14 08:47:39 denafrips volumio[10245]: '-p', Sep 14 08:47:39 denafrips volumio[10245]: 9876, Sep 14 08:47:39 denafrips volumio[10245]: '-o', Sep 14 08:47:39 denafrips volumio[10245]: '/tmp/camilladsp.log', Sep 14 08:47:39 denafrips volumio[10245]: '-l', Sep 14 08:47:39 denafrips volumio[10245]: 'warn', Sep 14 08:47:39 denafrips volumio[10245]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 14 08:47:39 denafrips volumio[10245]: ] Sep 14 08:47:39 denafrips volumio[10245]: } Sep 14 08:47:39 denafrips volumio[10245]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:47:40 denafrips sudo[10510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 08:46 Sep 14 08:47:40 denafrips sudo[10510]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:40 denafrips mpd[10484]: Sep 14 08:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:47:40 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:40 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 119. Sep 14 08:47:40 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:40 denafrips sudo[10510]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:40 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:40 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 14 08:47:40 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 14 08:47:40 denafrips sudo[10499]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:40 denafrips systemd[1]: Started Music Player Daemon. Sep 14 08:47:40 denafrips sudo[10358]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:40 denafrips sudo[10348]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:40 denafrips systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:40 denafrips sudo[10468]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:40 denafrips systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 14 08:47:40 denafrips systemd[1]: Started dynamicswap service. Sep 14 08:47:40 denafrips systemd[1]: dynamicswap.service: Succeeded. Sep 14 08:47:40 denafrips systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 14 08:47:40 denafrips systemd[1]: volumio.service: Scheduled restart job, restart counter is at 37. Sep 14 08:47:40 denafrips systemd[1]: Stopped Volumio Backend Module. Sep 14 08:47:40 denafrips systemd[1]: Started Volumio Backend Module. Sep 14 08:47:40 denafrips systemd[1]: Started dynamicswap service. Sep 14 08:47:40 denafrips systemd[1]: dynamicswap.service: Succeeded. Sep 14 08:47:41 denafrips volumio[10512]: Traceback (most recent call last): Sep 14 08:47:41 denafrips volumio[10512]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:41 denafrips volumio[10512]: from camilladsp import CamillaConnection Sep 14 08:47:41 denafrips volumio[10512]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:42 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:42 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:43 denafrips volumio[10523]: info: ------------------------------------------- Sep 14 08:47:43 denafrips volumio[10523]: info: ----- Volumio3 ---- Sep 14 08:47:43 denafrips volumio[10523]: info: ------------------------------------------- Sep 14 08:47:43 denafrips volumio[10523]: info: ----- System startup ---- Sep 14 08:47:43 denafrips volumio[10523]: info: ------------------------------------------- Sep 14 08:47:43 denafrips volumio[10523]: info: MYVOLUMIO Environment detected Sep 14 08:47:43 denafrips volumio[10523]: info: Plugin folders cleanup Sep 14 08:47:43 denafrips volumio[10523]: info: Scanning into folder /volumio/app/plugins/ Sep 14 08:47:43 denafrips volumio[10523]: info: Scanning category audio_interface Sep 14 08:47:43 denafrips volumio[10523]: info: Scanning category miscellanea Sep 14 08:47:43 denafrips volumio[10523]: info: Scanning category music_service Sep 14 08:47:43 denafrips volumio[10523]: info: Scanning category plugins.json Sep 14 08:47:43 denafrips volumio[10523]: info: Scanning category system_controller Sep 14 08:47:43 denafrips volumio[10523]: info: Scanning category user_interface Sep 14 08:47:43 denafrips volumio[10523]: info: Scanning into folder /data/plugins/ Sep 14 08:47:43 denafrips volumio[10523]: info: Scanning category audio_interface Sep 14 08:47:43 denafrips volumio[10523]: info: Scanning category music_service Sep 14 08:47:43 denafrips volumio[10523]: info: Plugin folders cleanup completed Sep 14 08:47:43 denafrips volumio[10523]: info: ------------------------------------------- Sep 14 08:47:43 denafrips volumio[10523]: info: ----- Core plugins startup ---- Sep 14 08:47:43 denafrips volumio[10523]: info: ------------------------------------------- Sep 14 08:47:43 denafrips volumio[10523]: info: Loading plugins from folder /volumio/app/plugins/ Sep 14 08:47:43 denafrips volumio[10523]: info: Adding plugin upnp to MyMusic Plugins Sep 14 08:47:43 denafrips volumio[10523]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 14 08:47:43 denafrips volumio[10523]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 14 08:47:43 denafrips volumio[10523]: info: Loading plugins from folder /data/plugins/ Sep 14 08:47:43 denafrips volumio[10523]: info: Loading plugin "system"... Sep 14 08:47:43 denafrips volumio[10523]: info: Loading plugin "appearance"... Sep 14 08:47:44 denafrips volumio[10523]: info: Loading plugin "network"... Sep 14 08:47:44 denafrips volumio[10523]: info: Refreshing Cached IP Addresses Sep 14 08:47:44 denafrips sudo[10550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 08:47:44 denafrips sudo[10550]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:44 denafrips sudo[10550]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:44 denafrips sudo[10552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 08:47:44 denafrips sudo[10552]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:44 denafrips sudo[10552]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:44 denafrips volumio[10523]: info: Loading plugin "services"... Sep 14 08:47:44 denafrips volumio[10523]: info: Loading plugin "alsa_controller"... Sep 14 08:47:44 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:44 denafrips sudo[10561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 14 08:47:44 denafrips sudo[10561]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:44 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 120. Sep 14 08:47:44 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:44 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:44 denafrips volumio[10523]: info: Loading plugin "wizard"... Sep 14 08:47:44 denafrips volumio[10523]: info: Loading plugin "networkfs"... Sep 14 08:47:44 denafrips volumio[10523]: info: Cannot mount NAS SDCard at system boot, trial number 1 ,retrying in 5 seconds Sep 14 08:47:44 denafrips volumio[10523]: info: Starting Udev Watcher for removable devices Sep 14 08:47:44 denafrips volumio[10523]: info: Ignoring mount for partition: boot Sep 14 08:47:44 denafrips volumio[10523]: info: Ignoring mount for partition: volumio Sep 14 08:47:44 denafrips volumio[10523]: info: Ignoring mount for partition: volumio_data Sep 14 08:47:44 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:44 denafrips volumio[10523]: info: Loading plugin "volumio_command_line_client"... Sep 14 08:47:44 denafrips volumio[10523]: info: Loading plugin "upnp"... Sep 14 08:47:44 denafrips volumio[10523]: info: [1757810864249] Starting Upmpd Daemon Sep 14 08:47:44 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:44 denafrips volumio[10523]: info: Loading plugin "my_music"... Sep 14 08:47:44 denafrips volumio[10523]: info: Loading plugin "mpd"... Sep 14 08:47:44 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:44 denafrips volumio[10523]: info: Loading plugin "upnp_browser"... Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "alarm-clock"... Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "airplay_emulation"... Sep 14 08:47:45 denafrips volumio[10523]: info: Starting Shairport Sync Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "last_100"... Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "webradio"... Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "i2s_dacs"... Sep 14 08:47:45 denafrips volumio[10523]: info: I2S DAC not set, start Auto-detection Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "volumiodiscovery"... Sep 14 08:47:45 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:45] [connect] Successful connection Sep 14 08:47:45 denafrips volumio[10523]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:45 denafrips volumio[10523]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:45 denafrips volumio[10523]: *** WARNING *** For more information see Sep 14 08:47:45 denafrips node[10523]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:45 denafrips volumio[10523]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:45 denafrips volumio[10523]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:45 denafrips volumio[10523]: *** WARNING *** For more information see Sep 14 08:47:45 denafrips node[10523]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:45 denafrips node[10523]: *** WARNING *** For more information see Sep 14 08:47:45 denafrips node[10523]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:45 denafrips node[10523]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:45 denafrips node[10523]: *** WARNING *** For more information see Sep 14 08:47:45 denafrips volumio[10523]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 14 08:47:45 denafrips volumio[10523]: info: Discovery: Started advertising with name: Denafrips Sep 14 08:47:45 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:45 denafrips volumio[10523]: info: Plugin spop is not enabled Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "outputs"... Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "albumart"... Sep 14 08:47:45 denafrips volumio[10523]: info: Plugin example_plugin is not enabled Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "inputs"... Sep 14 08:47:45 denafrips volumio[10572]: Traceback (most recent call last): Sep 14 08:47:45 denafrips volumio[10572]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:45 denafrips volumio[10572]: from camilladsp import CamillaConnection Sep 14 08:47:45 denafrips volumio[10572]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "updater_comm"... Sep 14 08:47:45 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:45 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:45 denafrips volumio[10523]: info: Plugin mpdemulation is not enabled Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "rest_api"... Sep 14 08:47:45 denafrips volumio[10523]: info: Loading plugin "websocket"... Sep 14 08:47:46 denafrips volumio[10523]: info: Starting Socket.io Server version 2.3.0 Sep 14 08:47:46 denafrips volumio[10523]: info: Loading plugin "fusiondsp"... Sep 14 08:47:46 denafrips volumio[10523]: Forking 3 albumart workers Sep 14 08:47:46 denafrips volumio[10523]: info: Applying required configuration parameters for plugin fusiondsp Sep 14 08:47:46 denafrips volumio[10523]: info: Loading i18n strings for locale en Sep 14 08:47:46 denafrips volumio[10523]: Updating browse sources language Sep 14 08:47:46 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::initPlayerControls Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: Express server listening on port 3000 Sep 14 08:47:47 denafrips volumio[10523]: [Metrics] WebUI: 4s 903.14ms Sep 14 08:47:47 denafrips volumio[10523]: info: CoreStateMachine::resetVolumioState Sep 14 08:47:47 denafrips volumio[10523]: info: CoreStateMachine::getcurrentVolume Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: info: Volumio Network Manager: Network status updated: 2 Sep 14 08:47:47 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757810865 101 Sep 14 08:47:47 denafrips volumio[10523]: Starting albumart workers Sep 14 08:47:47 denafrips volumio[10523]: 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: 1 Sep 14 08:47:47 denafrips volumio[10523]: verbose: New Socket.io Connection to 192.168.0.71 from 192.168.0.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 2 Sep 14 08:47:47 denafrips volumio[10523]: verbose: New Socket.io Connection to 192.168.0.71:3000 from 192.168.0.51 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 14 08:47:47 denafrips volumio[10523]: info: CoreStateMachine::pushState Sep 14 08:47:47 denafrips volumio[10523]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:47 denafrips volumio[10523]: Starting albumart workers Sep 14 08:47:47 denafrips volumio[10523]: info: CoreStateMachine::updateTrackBlock Sep 14 08:47:47 denafrips volumio[10523]: info: CorePlayQueue::getTrackBlock Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:47:47 denafrips volumio[10523]: Starting albumart workers Sep 14 08:47:47 denafrips volumio[10523]: info: Reloading queue from file Sep 14 08:47:47 denafrips volumio[10523]: info: Setting Device type: Raspberry PI Sep 14 08:47:47 denafrips volumio[10523]: info: CoreStateMachine::setRepeat null single undefined Sep 14 08:47:47 denafrips volumio[10523]: info: CoreStateMachine::pushState Sep 14 08:47:47 denafrips volumio[10523]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:47 denafrips volumio[10523]: info: CoreStateMachine::setRandom null Sep 14 08:47:47 denafrips volumio[10523]: info: CoreStateMachine::pushState Sep 14 08:47:47 denafrips volumio[10523]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:47 denafrips volumio[10523]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 14 08:47:47 denafrips volumio[10523]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Sep 14 08:47:47 denafrips volumio[10523]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 14 08:47:47 denafrips volumio[10523]: info: CoreStateMachine::pushState Sep 14 08:47:47 denafrips volumio[10523]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 14 08:47:47 denafrips volumio[10523]: info: Completed loading Core Plugins Sep 14 08:47:47 denafrips volumio[10523]: info: Preparing to generate the ALSA configuration file Sep 14 08:47:47 denafrips volumio[10523]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:47:47 denafrips volumio[10523]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 08:47:47 denafrips volumio[10523]: info: Reading ALSA contributions from plugins. Sep 14 08:47:47 denafrips volumio[10523]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:47:47 denafrips volumio[10523]: info: Output device has changed, restarting MPD Sep 14 08:47:47 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:47 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 121. Sep 14 08:47:47 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:47 denafrips volumio[10523]: info: Output device has changed, restarting Shairport Sync Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:47 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:47 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:47 denafrips sudo[10628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:47 denafrips sudo[10628]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:48 denafrips sudo[10631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:48 denafrips sudo[10628]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:48 denafrips sudo[10631]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:48 denafrips systemd[1]: Stopping Music Player Daemon... Sep 14 08:47:48 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:48 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:48 denafrips volumio[10523]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:48 denafrips volumio[10523]: info: ___________ START PLUGINS ___________ Sep 14 08:47:48 denafrips volumio[10523]: info: ControllerMpd::onStart: Initializing MPD Sep 14 08:47:48 denafrips volumio[10523]: info: Creating MPD Configuration file Sep 14 08:47:48 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:48 denafrips sudo[10561]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:48 denafrips sudo[10639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:48 denafrips sudo[10639]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:48 denafrips sudo[10639]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:48 denafrips volumio[10523]: info: [1757810868476] CoreMusicLibrary::Adding element Media Servers Sep 14 08:47:48 denafrips sudo[10637]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:48 denafrips sudo[10637]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:48 denafrips sudo[10637]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:48 denafrips sudo[10642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:48 denafrips sudo[10642]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:48 denafrips systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:48 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:48 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:48 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:48 denafrips volumio[10523]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:48 denafrips sudo[10657]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:48 denafrips sudo[10657]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:48 denafrips sudo[10657]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:48 denafrips volumio[10523]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:48 denafrips volumio[10523]: info: [1757810868932] CoreMusicLibrary::Adding element Last_100 Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:47:48 denafrips volumio[10523]: info: [1757810868937] CoreMusicLibrary::Adding element Webradio Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:48 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:48 denafrips volumio[10523]: info: Initializing BBC Radios Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: Loading i18n strings for locale en Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: FusionDsp - mixtype--------------------- Software Sep 14 08:47:49 denafrips volumio[10523]: info: Volumio Calling Home Sep 14 08:47:49 denafrips volumio[10523]: info: Preparing to generate the ALSA configuration file Sep 14 08:47:49 denafrips volumio[10523]: info: Cannot mount NAS SDCard at system boot, trial number 2 ,retrying in 5 seconds Sep 14 08:47:49 denafrips volumio[10523]: info: Discovery: adding b390528d-acdb-4aca-b80a-77dcc5fa3e8b Sep 14 08:47:49 denafrips volumio[10523]: info: Discovery: Found device Bedtime Sep 14 08:47:49 denafrips volumio[10523]: info: Discovery: Connecting to remote: 192.168.0.51 Sep 14 08:47:49 denafrips volumio[10523]: info: Discovery: adding 7d557128-016d-42d3-b02b-91b53d86abcc Sep 14 08:47:49 denafrips volumio[10523]: info: Discovery: Found device Denafrips Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:49 denafrips volumio[10523]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:49 denafrips volumio[10523]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:47:49 denafrips volumio[10523]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 08:47:49 denafrips volumio[10523]: info: Reading ALSA contributions from plugins. Sep 14 08:47:49 denafrips volumio[10523]: info: MPD Permissions set Sep 14 08:47:49 denafrips volumio[10523]: info: MPD Permissions set Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:49 denafrips volumio[10523]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:49 denafrips volumio[10523]: info: Discovery: Connected to remote: 192.168.0.51 Sep 14 08:47:49 denafrips volumio[10523]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10629]: Traceback (most recent call last): Sep 14 08:47:49 denafrips volumio[10629]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:49 denafrips volumio[10629]: from camilladsp import CamillaConnection Sep 14 08:47:49 denafrips volumio[10629]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:49 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:49 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:49 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:50 denafrips volumio[10523]: info: Volumio called home Sep 14 08:47:50 denafrips volumio[10523]: info: Starting Shairport Sync Sep 14 08:47:50 denafrips volumio[10523]: info: Starting Shairport Sync Sep 14 08:47:50 denafrips volumio[10523]: info: Starting Shairport Sync Sep 14 08:47:50 denafrips sudo[10703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:50 denafrips sudo[10703]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:50 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:50 denafrips sudo[10708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:50 denafrips sudo[10708]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:50 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:50 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::volumioGetState Sep 14 08:47:50 denafrips sudo[10705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:50 denafrips volumio[10523]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:50 denafrips sudo[10705]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:50 denafrips volumio[10523]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:47:50 denafrips volumio[10523]: info: Output device has changed, restarting MPD Sep 14 08:47:50 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:50 denafrips sudo[10703]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:50 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:50 denafrips sudo[10713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:47:50 denafrips systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:50 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:50 denafrips sudo[10713]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:50 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:50 denafrips sudo[10713]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:50 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:50 denafrips volumio[10523]: info: Output device has changed, restarting Shairport Sync Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:50 denafrips sudo[10705]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:50 denafrips sudo[10708]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:50 denafrips sudo[10718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:47:50 denafrips sudo[10718]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:50 denafrips volumio[10523]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:47:50 denafrips systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 08:47:50 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:47:50 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:47:50 denafrips volumio[10523]: info: MPD Permissions set Sep 14 08:47:50 denafrips volumio[10523]: info: Shairport-Sync Started Sep 14 08:47:50 denafrips volumio[10523]: Error adding Membership: Error: addMembership EINVAL Sep 14 08:47:50 denafrips volumio[10523]: info: Shairport-Sync Started Sep 14 08:47:50 denafrips volumio[10523]: info: Shairport-Sync Started Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:50 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:50 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:50 denafrips volumio[10523]: info: Starting Shairport Sync Sep 14 08:47:50 denafrips sudo[10730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:47:50 denafrips sudo[10730]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:50 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:47:50 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:47:50 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:50 denafrips sudo[10724]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:47:50 denafrips sudo[10724]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:50 denafrips sudo[10724]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:50 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:47:50 denafrips sudo[10730]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:50 denafrips volumio[10523]: info: Shairport-Sync Started Sep 14 08:47:51 denafrips volumio[10523]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:47:51 denafrips volumio[10523]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 14 08:47:51 denafrips volumio[10523]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:47:51 denafrips volumio[10523]: info: camilladsp service started and running in background, instance 1 Sep 14 08:47:51 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:51 denafrips volumio[10523]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:47:51 denafrips volumio[10523]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 14 08:47:51 denafrips volumio[10523]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:47:51 denafrips volumio[10523]: info: FusionDsp loaded Sep 14 08:47:51 denafrips volumio[10523]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 08:47:51 denafrips sudo[10748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 14 08:47:51 denafrips sudo[10748]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:51 denafrips volumio[10523]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 14 08:47:51 denafrips volumio[10523]: info: Adding Signal Path Element [object Object] Sep 14 08:47:51 denafrips volumio[10523]: info: Adding fusiondspeq DSP Signal Path Element Sep 14 08:47:51 denafrips volumio[10523]: info: FusionDsp - ---- installed callbackRead Sep 14 08:47:51 denafrips volumio[10523]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:47:51 denafrips volumio[10523]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 14 08:47:51 denafrips volumio[10523]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 14 08:47:51 denafrips volumio[10523]: at onErrorNT (internal/child_process.js:465:16) Sep 14 08:47:51 denafrips volumio[10523]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 14 08:47:51 denafrips volumio[10523]: errno: -2, Sep 14 08:47:51 denafrips volumio[10523]: code: 'ENOENT', Sep 14 08:47:51 denafrips volumio[10523]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:47:51 denafrips volumio[10523]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:47:51 denafrips volumio[10523]: spawnargs: [ Sep 14 08:47:51 denafrips volumio[10523]: '-p', Sep 14 08:47:51 denafrips volumio[10523]: 9876, Sep 14 08:47:51 denafrips volumio[10523]: '-o', Sep 14 08:47:51 denafrips volumio[10523]: '/tmp/camilladsp.log', Sep 14 08:47:51 denafrips volumio[10523]: '-l', Sep 14 08:47:51 denafrips volumio[10523]: 'warn', Sep 14 08:47:51 denafrips volumio[10523]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 14 08:47:51 denafrips volumio[10523]: ] Sep 14 08:47:51 denafrips volumio[10523]: } Sep 14 08:47:51 denafrips volumio[10523]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:47:51 denafrips sudo[10760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 08:46 Sep 14 08:47:51 denafrips sudo[10760]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:51 denafrips mpd[10735]: Sep 14 08:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:47:51 denafrips sudo[10760]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:51 denafrips systemd[1]: Started Music Player Daemon. Sep 14 08:47:51 denafrips sudo[10631]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:51 denafrips sudo[10642]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:51 denafrips sudo[10718]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:51 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:51] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 14 08:47:51 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:51] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 14 08:47:51 denafrips systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:51 denafrips sudo[10748]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:51 denafrips systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 14 08:47:51 denafrips systemd[1]: Started dynamicswap service. Sep 14 08:47:51 denafrips systemd[1]: dynamicswap.service: Succeeded. Sep 14 08:47:51 denafrips systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 14 08:47:51 denafrips systemd[1]: volumio.service: Scheduled restart job, restart counter is at 38. Sep 14 08:47:51 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:51 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 122. Sep 14 08:47:51 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:51 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:51 denafrips systemd[1]: Stopped Volumio Backend Module. Sep 14 08:47:51 denafrips systemd[1]: Started Volumio Backend Module. Sep 14 08:47:51 denafrips systemd[1]: Started dynamicswap service. Sep 14 08:47:51 denafrips systemd[1]: dynamicswap.service: Succeeded. Sep 14 08:47:53 denafrips volumio[10773]: Traceback (most recent call last): Sep 14 08:47:53 denafrips volumio[10773]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:53 denafrips volumio[10773]: from camilladsp import CamillaConnection Sep 14 08:47:53 denafrips volumio[10773]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:53 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:53 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:54 denafrips volumio[10774]: info: ------------------------------------------- Sep 14 08:47:54 denafrips volumio[10774]: info: ----- Volumio3 ---- Sep 14 08:47:54 denafrips volumio[10774]: info: ------------------------------------------- Sep 14 08:47:54 denafrips volumio[10774]: info: ----- System startup ---- Sep 14 08:47:54 denafrips volumio[10774]: info: ------------------------------------------- Sep 14 08:47:55 denafrips volumio[10774]: info: MYVOLUMIO Environment detected Sep 14 08:47:55 denafrips volumio[10774]: info: Plugin folders cleanup Sep 14 08:47:55 denafrips volumio[10774]: info: Scanning into folder /volumio/app/plugins/ Sep 14 08:47:55 denafrips volumio[10774]: info: Scanning category audio_interface Sep 14 08:47:55 denafrips volumio[10774]: info: Scanning category miscellanea Sep 14 08:47:55 denafrips volumio[10774]: info: Scanning category music_service Sep 14 08:47:55 denafrips volumio[10774]: info: Scanning category plugins.json Sep 14 08:47:55 denafrips volumio[10774]: info: Scanning category system_controller Sep 14 08:47:55 denafrips volumio[10774]: info: Scanning category user_interface Sep 14 08:47:55 denafrips volumio[10774]: info: Scanning into folder /data/plugins/ Sep 14 08:47:55 denafrips volumio[10774]: info: Scanning category audio_interface Sep 14 08:47:55 denafrips volumio[10774]: info: Scanning category music_service Sep 14 08:47:55 denafrips volumio[10774]: info: Plugin folders cleanup completed Sep 14 08:47:55 denafrips volumio[10774]: info: ------------------------------------------- Sep 14 08:47:55 denafrips volumio[10774]: info: ----- Core plugins startup ---- Sep 14 08:47:55 denafrips volumio[10774]: info: ------------------------------------------- Sep 14 08:47:55 denafrips volumio[10774]: info: Loading plugins from folder /volumio/app/plugins/ Sep 14 08:47:55 denafrips volumio[10774]: info: Adding plugin upnp to MyMusic Plugins Sep 14 08:47:55 denafrips volumio[10774]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 14 08:47:55 denafrips volumio[10774]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 14 08:47:55 denafrips volumio[10774]: info: Loading plugins from folder /data/plugins/ Sep 14 08:47:55 denafrips volumio[10774]: info: Loading plugin "system"... Sep 14 08:47:55 denafrips volumio[10774]: info: Loading plugin "appearance"... Sep 14 08:47:55 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:55 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 123. Sep 14 08:47:55 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:55 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:56 denafrips volumio[10774]: info: Loading plugin "network"... Sep 14 08:47:56 denafrips volumio[10774]: info: Refreshing Cached IP Addresses Sep 14 08:47:56 denafrips sudo[10802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 08:47:56 denafrips sudo[10802]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:56 denafrips sudo[10802]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:56 denafrips sudo[10804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 08:47:56 denafrips sudo[10804]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:56 denafrips sudo[10804]: pam_unix(sudo:session): session closed for user root Sep 14 08:47:56 denafrips volumio[10774]: info: Loading plugin "services"... Sep 14 08:47:56 denafrips volumio[10774]: info: Loading plugin "alsa_controller"... Sep 14 08:47:56 denafrips sudo[10813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 14 08:47:56 denafrips sudo[10813]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:47:56 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:47:56 denafrips volumio[10774]: info: Loading plugin "wizard"... Sep 14 08:47:56 denafrips volumio[10774]: info: Loading plugin "networkfs"... Sep 14 08:47:56 denafrips volumio[10774]: info: Cannot mount NAS SDCard at system boot, trial number 1 ,retrying in 5 seconds Sep 14 08:47:56 denafrips volumio[10774]: info: Starting Udev Watcher for removable devices Sep 14 08:47:56 denafrips volumio[10774]: info: Ignoring mount for partition: boot Sep 14 08:47:56 denafrips volumio[10774]: info: Ignoring mount for partition: volumio Sep 14 08:47:56 denafrips volumio[10774]: info: Ignoring mount for partition: volumio_data Sep 14 08:47:56 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:56 denafrips volumio[10774]: info: Loading plugin "volumio_command_line_client"... Sep 14 08:47:56 denafrips volumio[10774]: info: Loading plugin "upnp"... Sep 14 08:47:56 denafrips volumio[10774]: info: [1757810876644] Starting Upmpd Daemon Sep 14 08:47:56 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:56 denafrips volumio[10774]: info: Loading plugin "my_music"... Sep 14 08:47:56 denafrips volumio[10774]: info: Loading plugin "mpd"... Sep 14 08:47:56 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:56] [connect] Successful connection Sep 14 08:47:57 denafrips volumio[10774]: info: Loading plugin "upnp_browser"... Sep 14 08:47:57 denafrips volumio[10800]: Traceback (most recent call last): Sep 14 08:47:57 denafrips volumio[10800]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:47:57 denafrips volumio[10800]: from camilladsp import CamillaConnection Sep 14 08:47:57 denafrips volumio[10800]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:47:57 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:47:57 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:47:57 denafrips volumio[10774]: info: Loading plugin "alarm-clock"... Sep 14 08:47:57 denafrips volumio[10774]: info: Loading plugin "airplay_emulation"... Sep 14 08:47:57 denafrips volumio[10774]: info: Starting Shairport Sync Sep 14 08:47:57 denafrips volumio[10774]: info: Loading plugin "last_100"... Sep 14 08:47:57 denafrips volumio[10774]: info: Loading plugin "webradio"... Sep 14 08:47:57 denafrips volumio[10774]: info: Loading plugin "i2s_dacs"... Sep 14 08:47:57 denafrips volumio[10774]: info: I2S DAC not set, start Auto-detection Sep 14 08:47:57 denafrips volumio[10774]: info: Loading plugin "volumiodiscovery"... Sep 14 08:47:57 denafrips volumio[10774]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:57 denafrips volumio[10774]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:57 denafrips node[10774]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:57 denafrips volumio[10774]: *** WARNING *** For more information see Sep 14 08:47:57 denafrips volumio[10774]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:57 denafrips volumio[10774]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:57 denafrips volumio[10774]: *** WARNING *** For more information see Sep 14 08:47:57 denafrips node[10774]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:57 denafrips node[10774]: *** WARNING *** For more information see Sep 14 08:47:57 denafrips node[10774]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:47:57 denafrips node[10774]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:47:57 denafrips node[10774]: *** WARNING *** For more information see Sep 14 08:47:57 denafrips volumio[10774]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 14 08:47:57 denafrips volumio[10774]: info: Discovery: Started advertising with name: Denafrips Sep 14 08:47:57 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:47:57 denafrips volumio[10774]: info: Plugin spop is not enabled Sep 14 08:47:57 denafrips volumio[10774]: info: Loading plugin "outputs"... Sep 14 08:47:57 denafrips volumio[10774]: info: Loading plugin "albumart"... Sep 14 08:47:58 denafrips volumio[10774]: info: Plugin example_plugin is not enabled Sep 14 08:47:58 denafrips volumio[10774]: info: Loading plugin "inputs"... Sep 14 08:47:58 denafrips volumio[10774]: info: Loading plugin "updater_comm"... Sep 14 08:47:58 denafrips volumio[10774]: info: Plugin mpdemulation is not enabled Sep 14 08:47:58 denafrips volumio[10774]: info: Loading plugin "rest_api"... Sep 14 08:47:58 denafrips volumio[10774]: info: Loading plugin "websocket"... Sep 14 08:47:58 denafrips volumio[10774]: info: Starting Socket.io Server version 2.3.0 Sep 14 08:47:58 denafrips volumio[10774]: info: Loading plugin "fusiondsp"... Sep 14 08:47:58 denafrips volumio[10774]: Forking 3 albumart workers Sep 14 08:47:58 denafrips volumio[10774]: info: Applying required configuration parameters for plugin fusiondsp Sep 14 08:47:58 denafrips volumio[10774]: info: Loading i18n strings for locale en Sep 14 08:47:58 denafrips volumio[10774]: Updating browse sources language Sep 14 08:47:58 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::initPlayerControls Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:47:59 denafrips volumio[10774]: Express server listening on port 3000 Sep 14 08:47:59 denafrips volumio[10774]: [Metrics] WebUI: 5s 591.95ms Sep 14 08:47:59 denafrips volumio[10774]: info: CoreStateMachine::resetVolumioState Sep 14 08:47:59 denafrips volumio[10774]: info: CoreStateMachine::getcurrentVolume Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:47:59 denafrips volumio[10774]: info: Volumio Network Manager: Network status updated: 2 Sep 14 08:47:59 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:47:59 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 124. Sep 14 08:47:59 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:47:59 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:47:59 denafrips volumio-remote-updater[560]: [2025-09-14 08:47:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757810876 101 Sep 14 08:47:59 denafrips volumio[10774]: Starting albumart workers Sep 14 08:47:59 denafrips volumio[10774]: 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: 1 Sep 14 08:47:59 denafrips volumio[10774]: verbose: New Socket.io Connection to 192.168.0.71 from 192.168.0.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 2 Sep 14 08:47:59 denafrips volumio[10774]: verbose: New Socket.io Connection to 192.168.0.71:3000 from 192.168.0.51 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 14 08:47:59 denafrips volumio[10774]: Starting albumart workers Sep 14 08:47:59 denafrips volumio[10774]: Starting albumart workers Sep 14 08:47:59 denafrips volumio[10774]: info: CoreStateMachine::pushState Sep 14 08:47:59 denafrips volumio[10774]: info: CorePlayQueue::getTrack 0 Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::volumioPushState Sep 14 08:47:59 denafrips volumio[10774]: info: CoreStateMachine::updateTrackBlock Sep 14 08:47:59 denafrips volumio[10774]: info: CorePlayQueue::getTrackBlock Sep 14 08:47:59 denafrips volumio[10774]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:48:00 denafrips volumio[10774]: info: Reloading queue from file Sep 14 08:48:00 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 14 08:48:00 denafrips volumio[10774]: info: CoreStateMachine::pushState Sep 14 08:48:00 denafrips volumio[10774]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:00 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:48:00 denafrips volumio[10774]: info: CoreCommandRouter::volumioPushState Sep 14 08:48:00 denafrips volumio[10774]: info: CoreStateMachine::setRepeat null single undefined Sep 14 08:48:00 denafrips volumio[10774]: info: CoreStateMachine::pushState Sep 14 08:48:00 denafrips volumio[10774]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:00 denafrips volumio[10774]: info: CoreCommandRouter::volumioPushState Sep 14 08:48:00 denafrips volumio[10774]: info: CoreStateMachine::setRandom null Sep 14 08:48:00 denafrips volumio[10774]: info: CoreStateMachine::pushState Sep 14 08:48:00 denafrips volumio[10774]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:00 denafrips volumio[10774]: info: CoreCommandRouter::volumioPushState Sep 14 08:48:00 denafrips volumio[10774]: info: Setting Device type: Raspberry PI Sep 14 08:48:00 denafrips volumio[10774]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 14 08:48:00 denafrips volumio[10774]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Sep 14 08:48:00 denafrips volumio[10774]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 14 08:48:00 denafrips volumio[10774]: info: CoreCommandRouter::volumioGetState Sep 14 08:48:00 denafrips volumio[10774]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:00 denafrips sudo[10813]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:00 denafrips volumio[10774]: info: Discovery: adding b390528d-acdb-4aca-b80a-77dcc5fa3e8b Sep 14 08:48:00 denafrips volumio[10774]: info: Discovery: Found device Bedtime Sep 14 08:48:00 denafrips volumio[10774]: info: Discovery: Connecting to remote: 192.168.0.51 Sep 14 08:48:00 denafrips volumio[10774]: info: Completed loading Core Plugins Sep 14 08:48:00 denafrips volumio[10774]: info: Preparing to generate the ALSA configuration file Sep 14 08:48:00 denafrips volumio[10774]: info: Discovery: adding 7d557128-016d-42d3-b02b-91b53d86abcc Sep 14 08:48:00 denafrips volumio[10774]: info: Discovery: Found device Denafrips Sep 14 08:48:00 denafrips volumio[10774]: info: CoreCommandRouter::volumioGetState Sep 14 08:48:00 denafrips volumio[10774]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:00 denafrips volumio[10774]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:48:00 denafrips volumio[10774]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 08:48:00 denafrips volumio[10774]: info: Reading ALSA contributions from plugins. Sep 14 08:48:00 denafrips volumio[10774]: info: Discovery: Connected to remote: 192.168.0.51 Sep 14 08:48:01 denafrips volumio[10774]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:48:01 denafrips volumio[10774]: info: Output device has changed, restarting MPD Sep 14 08:48:01 denafrips sudo[10896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:48:01 denafrips volumio[10774]: info: Output device has changed, restarting Shairport Sync Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:01 denafrips sudo[10896]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:01 denafrips sudo[10896]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:01 denafrips sudo[10898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:48:01 denafrips sudo[10898]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:01 denafrips systemd[1]: Stopping Music Player Daemon... Sep 14 08:48:01 denafrips volumio[10866]: Traceback (most recent call last): Sep 14 08:48:01 denafrips volumio[10866]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:48:01 denafrips volumio[10866]: from camilladsp import CamillaConnection Sep 14 08:48:01 denafrips volumio[10866]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:48:01 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:48:01 denafrips volumio[10774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:48:01 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:48:01 denafrips volumio[10774]: info: ___________ START PLUGINS ___________ Sep 14 08:48:01 denafrips volumio[10774]: info: ControllerMpd::onStart: Initializing MPD Sep 14 08:48:01 denafrips volumio[10774]: info: Creating MPD Configuration file Sep 14 08:48:01 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:48:01 denafrips sudo[10914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:48:01 denafrips sudo[10914]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:01 denafrips sudo[10914]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:48:01 denafrips volumio[10774]: info: [1757810881361] CoreMusicLibrary::Adding element Media Servers Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:48:01 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:48:01 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:48:01 denafrips sudo[10917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:48:01 denafrips sudo[10917]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:01 denafrips systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 14 08:48:01 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:48:01 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:48:01 denafrips volumio[10774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:01 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:48:01 denafrips volumio[10774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:48:01 denafrips volumio[10774]: info: [1757810881558] CoreMusicLibrary::Adding element Last_100 Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:48:01 denafrips volumio[10774]: info: [1757810881562] CoreMusicLibrary::Adding element Webradio Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:48:01 denafrips volumio[10774]: info: Initializing BBC Radios Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:01 denafrips sudo[10922]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:48:01 denafrips sudo[10922]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:01 denafrips volumio[10774]: info: Loading i18n strings for locale en Sep 14 08:48:01 denafrips sudo[10922]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 14 08:48:01 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:01 denafrips volumio[10774]: info: FusionDsp - mixtype--------------------- Software Sep 14 08:48:01 denafrips volumio[10774]: info: Volumio Calling Home Sep 14 08:48:01 denafrips volumio[10774]: info: Preparing to generate the ALSA configuration file Sep 14 08:48:01 denafrips volumio[10774]: info: Cannot mount NAS SDCard at system boot, trial number 2 ,retrying in 5 seconds Sep 14 08:48:01 denafrips volumio[10774]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:48:01 denafrips volumio[10774]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 08:48:01 denafrips volumio[10774]: info: Reading ALSA contributions from plugins. Sep 14 08:48:02 denafrips volumio[10774]: info: MPD Permissions set Sep 14 08:48:02 denafrips volumio[10774]: info: MPD Permissions set Sep 14 08:48:02 denafrips volumio[10774]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: Volumio called home Sep 14 08:48:02 denafrips volumio[10774]: info: Starting Shairport Sync Sep 14 08:48:02 denafrips volumio[10774]: info: Starting Shairport Sync Sep 14 08:48:02 denafrips sudo[10950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:48:02 denafrips sudo[10950]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:02 denafrips volumio[10774]: info: Starting Shairport Sync Sep 14 08:48:02 denafrips sudo[10953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:48:02 denafrips sudo[10953]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:02 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:48:02 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:48:02 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:48:02 denafrips sudo[10955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:48:02 denafrips sudo[10955]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:02 denafrips volumio[10774]: verbose: New Socket.io Connection to 192.168.0.71 from 192.168.0.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::volumioGetState Sep 14 08:48:02 denafrips volumio[10774]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:02 denafrips volumio[10774]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:48:02 denafrips volumio[10774]: info: Output device has changed, restarting MPD Sep 14 08:48:02 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:48:02 denafrips volumio[10774]: info: Output device has changed, restarting Shairport Sync Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:02 denafrips sudo[10950]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:02 denafrips sudo[10960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:48:02 denafrips sudo[10960]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:02 denafrips sudo[10960]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:02 denafrips sudo[10962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:48:02 denafrips sudo[10962]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:02 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:48:02 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:48:02 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:48:02 denafrips volumio[10774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 08:48:02 denafrips volumio[10774]: info: MPD Permissions set Sep 14 08:48:02 denafrips volumio[10774]: info: Shairport-Sync Started Sep 14 08:48:02 denafrips volumio[10774]: Error adding Membership: Error: addMembership EINVAL Sep 14 08:48:02 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:48:02 denafrips sudo[10953]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:02 denafrips systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 08:48:02 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 08:48:02 denafrips sudo[10955]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:02 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::volumioGetState Sep 14 08:48:02 denafrips volumio[10774]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 08:48:02 denafrips volumio[10774]: info: Received Get System Info Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 08:48:02 denafrips volumio[10774]: info: Discovery: Getting this device information Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::volumioGetState Sep 14 08:48:02 denafrips volumio[10774]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::volumioGetState Sep 14 08:48:02 denafrips volumio[10774]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:02 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:48:02 denafrips volumio[10774]: info: Listing playlists Sep 14 08:48:02 denafrips volumio[10774]: info: Shairport-Sync Started Sep 14 08:48:02 denafrips volumio[10774]: info: Shairport-Sync Started Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:02 denafrips sudo[10970]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:48:02 denafrips sudo[10970]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:02 denafrips sudo[10970]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:02 denafrips volumio[10774]: info: Starting Shairport Sync Sep 14 08:48:02 denafrips sudo[10980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:48:02 denafrips sudo[10980]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:02 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips sudo[10980]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:03 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips volumio[10774]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:03 denafrips volumio[10774]: info: Shairport-Sync Started Sep 14 08:48:03 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:03 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:48:03 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 125. Sep 14 08:48:03 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:48:03 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:03 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:48:03 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:03 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:04 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:04 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:04 denafrips mpd[10978]: Sep 14 08:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:48:04 denafrips systemd[1]: Started Music Player Daemon. Sep 14 08:48:04 denafrips sudo[10898]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:04 denafrips sudo[10962]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:04 denafrips sudo[10917]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:04 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:04 denafrips volumio[10986]: Traceback (most recent call last): Sep 14 08:48:04 denafrips volumio[10986]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:48:04 denafrips volumio[10986]: from camilladsp import CamillaConnection Sep 14 08:48:04 denafrips volumio[10986]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:48:05 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:05 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:48:05 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:48:05 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:05 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:05 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:05 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:05 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:06 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:06 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:06 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:06 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:06 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:06 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:06 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:06 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:06 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:06 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:07 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:07 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:48:07 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 126. Sep 14 08:48:07 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:48:07 denafrips volumio[10774]: info: FusionDsp - Sep 14 08:48:07 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:48:07 denafrips volumio[10774]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:48:07 denafrips volumio[10774]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 14 08:48:07 denafrips volumio[10774]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 14 08:48:07 denafrips volumio[10774]: info: camilladsp service started and running in background, instance 1 Sep 14 08:48:07 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:07 denafrips volumio[10774]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:48:07 denafrips volumio[10774]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 14 08:48:07 denafrips volumio[10774]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 14 08:48:07 denafrips volumio[10774]: info: FusionDsp loaded Sep 14 08:48:07 denafrips volumio[10774]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 08:48:07 denafrips sudo[11014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 14 08:48:07 denafrips sudo[11014]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:07 denafrips volumio[10774]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 14 08:48:07 denafrips volumio[10774]: info: Adding Signal Path Element [object Object] Sep 14 08:48:07 denafrips volumio[10774]: info: Adding fusiondspeq DSP Signal Path Element Sep 14 08:48:07 denafrips volumio[10774]: info: FusionDsp - ---- installed callbackRead Sep 14 08:48:07 denafrips volumio[10774]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:48:07 denafrips volumio[10774]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 14 08:48:07 denafrips volumio[10774]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 14 08:48:07 denafrips volumio[10774]: at onErrorNT (internal/child_process.js:465:16) Sep 14 08:48:07 denafrips volumio[10774]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 14 08:48:07 denafrips volumio[10774]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 14 08:48:07 denafrips volumio[10774]: at listOnTimeout (internal/timers.js:523:9) Sep 14 08:48:07 denafrips volumio[10774]: at processTimers (internal/timers.js:497:7) { Sep 14 08:48:07 denafrips volumio[10774]: errno: -2, Sep 14 08:48:07 denafrips volumio[10774]: code: 'ENOENT', Sep 14 08:48:07 denafrips volumio[10774]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:48:07 denafrips volumio[10774]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 14 08:48:07 denafrips volumio[10774]: spawnargs: [ Sep 14 08:48:07 denafrips volumio[10774]: '-p', Sep 14 08:48:07 denafrips volumio[10774]: 9876, Sep 14 08:48:07 denafrips volumio[10774]: '-o', Sep 14 08:48:07 denafrips volumio[10774]: '/tmp/camilladsp.log', Sep 14 08:48:07 denafrips volumio[10774]: '-l', Sep 14 08:48:07 denafrips volumio[10774]: 'warn', Sep 14 08:48:07 denafrips volumio[10774]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 14 08:48:07 denafrips volumio[10774]: ] Sep 14 08:48:07 denafrips volumio[10774]: } Sep 14 08:48:07 denafrips volumio[10774]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:48:07 denafrips sudo[11014]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:08 denafrips sudo[11025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 08:47 Sep 14 08:48:08 denafrips sudo[11025]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:08 denafrips volumio[11003]: Traceback (most recent call last): Sep 14 08:48:08 denafrips volumio[11003]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:48:08 denafrips volumio[11003]: from camilladsp import CamillaConnection Sep 14 08:48:08 denafrips volumio[11003]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:48:08 denafrips sudo[11025]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:08 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:48:08 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:48:08 denafrips volumio-remote-updater[560]: [2025-09-14 08:48:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 14 08:48:08 denafrips volumio-remote-updater[560]: [2025-09-14 08:48:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 14 08:48:08 denafrips systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:48:08 denafrips systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 14 08:48:08 denafrips systemd[1]: Started dynamicswap service. Sep 14 08:48:08 denafrips systemd[1]: dynamicswap.service: Succeeded. Sep 14 08:48:09 denafrips systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 14 08:48:09 denafrips systemd[1]: volumio.service: Scheduled restart job, restart counter is at 39. Sep 14 08:48:09 denafrips systemd[1]: Started dynamicswap service. Sep 14 08:48:09 denafrips systemd[1]: Stopped Volumio Backend Module. Sep 14 08:48:09 denafrips systemd[1]: Started Volumio Backend Module. Sep 14 08:48:09 denafrips systemd[1]: dynamicswap.service: Succeeded. Sep 14 08:48:10 denafrips volumio[11036]: info: ------------------------------------------- Sep 14 08:48:10 denafrips volumio[11036]: info: ----- Volumio3 ---- Sep 14 08:48:10 denafrips volumio[11036]: info: ------------------------------------------- Sep 14 08:48:10 denafrips volumio[11036]: info: ----- System startup ---- Sep 14 08:48:10 denafrips volumio[11036]: info: ------------------------------------------- Sep 14 08:48:10 denafrips volumio[11036]: info: MYVOLUMIO Environment detected Sep 14 08:48:10 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:48:10 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 127. Sep 14 08:48:10 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:48:10 denafrips volumio[11036]: info: Plugin folders cleanup Sep 14 08:48:10 denafrips volumio[11036]: info: Scanning into folder /volumio/app/plugins/ Sep 14 08:48:10 denafrips volumio[11036]: info: Scanning category audio_interface Sep 14 08:48:10 denafrips volumio[11036]: info: Scanning category miscellanea Sep 14 08:48:10 denafrips volumio[11036]: info: Scanning category music_service Sep 14 08:48:10 denafrips volumio[11036]: info: Scanning category plugins.json Sep 14 08:48:10 denafrips volumio[11036]: info: Scanning category system_controller Sep 14 08:48:10 denafrips volumio[11036]: info: Scanning category user_interface Sep 14 08:48:10 denafrips volumio[11036]: info: Scanning into folder /data/plugins/ Sep 14 08:48:10 denafrips volumio[11036]: info: Scanning category audio_interface Sep 14 08:48:10 denafrips volumio[11036]: info: Scanning category music_service Sep 14 08:48:10 denafrips volumio[11036]: info: Plugin folders cleanup completed Sep 14 08:48:10 denafrips volumio[11036]: info: ------------------------------------------- Sep 14 08:48:10 denafrips volumio[11036]: info: ----- Core plugins startup ---- Sep 14 08:48:10 denafrips volumio[11036]: info: ------------------------------------------- Sep 14 08:48:10 denafrips volumio[11036]: info: Loading plugins from folder /volumio/app/plugins/ Sep 14 08:48:10 denafrips volumio[11036]: info: Adding plugin upnp to MyMusic Plugins Sep 14 08:48:10 denafrips volumio[11036]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 14 08:48:10 denafrips volumio[11036]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 14 08:48:10 denafrips volumio[11036]: info: Loading plugins from folder /data/plugins/ Sep 14 08:48:10 denafrips volumio[11036]: info: Loading plugin "system"... Sep 14 08:48:10 denafrips volumio[11036]: info: Loading plugin "appearance"... Sep 14 08:48:10 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:48:11 denafrips volumio[11036]: info: Loading plugin "network"... Sep 14 08:48:11 denafrips volumio[11036]: info: Refreshing Cached IP Addresses Sep 14 08:48:11 denafrips sudo[11063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 08:48:11 denafrips sudo[11063]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:11 denafrips sudo[11063]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:11 denafrips sudo[11065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 08:48:11 denafrips sudo[11065]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:11 denafrips sudo[11065]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:11 denafrips volumio[11058]: Traceback (most recent call last): Sep 14 08:48:11 denafrips volumio[11058]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:48:11 denafrips volumio[11058]: from camilladsp import CamillaConnection Sep 14 08:48:11 denafrips volumio[11058]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:48:11 denafrips volumio[11036]: info: Loading plugin "services"... Sep 14 08:48:11 denafrips volumio[11036]: info: Loading plugin "alsa_controller"... Sep 14 08:48:11 denafrips sudo[11076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 14 08:48:11 denafrips sudo[11076]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:11 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:48:11 denafrips volumio[11036]: info: Loading plugin "wizard"... Sep 14 08:48:11 denafrips volumio[11036]: info: Loading plugin "networkfs"... Sep 14 08:48:11 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:48:11 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:48:11 denafrips volumio[11036]: info: Cannot mount NAS SDCard at system boot, trial number 1 ,retrying in 5 seconds Sep 14 08:48:11 denafrips volumio[11036]: info: Starting Udev Watcher for removable devices Sep 14 08:48:11 denafrips volumio[11036]: info: Ignoring mount for partition: boot Sep 14 08:48:11 denafrips volumio[11036]: info: Ignoring mount for partition: volumio Sep 14 08:48:11 denafrips volumio[11036]: info: Ignoring mount for partition: volumio_data Sep 14 08:48:11 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:48:11 denafrips volumio[11036]: info: Loading plugin "volumio_command_line_client"... Sep 14 08:48:11 denafrips volumio[11036]: info: Loading plugin "upnp"... Sep 14 08:48:11 denafrips volumio[11036]: info: [1757810891866] Starting Upmpd Daemon Sep 14 08:48:11 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:48:11 denafrips volumio[11036]: info: Loading plugin "my_music"... Sep 14 08:48:11 denafrips volumio[11036]: info: Loading plugin "mpd"... Sep 14 08:48:12 denafrips volumio[11036]: info: Loading plugin "upnp_browser"... Sep 14 08:48:12 denafrips volumio[11036]: info: Loading plugin "alarm-clock"... Sep 14 08:48:12 denafrips volumio[11036]: info: Loading plugin "airplay_emulation"... Sep 14 08:48:12 denafrips volumio[11036]: info: Starting Shairport Sync Sep 14 08:48:12 denafrips volumio[11036]: info: Loading plugin "last_100"... Sep 14 08:48:12 denafrips volumio[11036]: info: Loading plugin "webradio"... Sep 14 08:48:13 denafrips volumio[11036]: info: Loading plugin "i2s_dacs"... Sep 14 08:48:13 denafrips volumio[11036]: info: I2S DAC not set, start Auto-detection Sep 14 08:48:13 denafrips volumio[11036]: info: Loading plugin "volumiodiscovery"... Sep 14 08:48:13 denafrips volumio[11036]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:48:13 denafrips volumio[11036]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:48:13 denafrips node[11036]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 14 08:48:13 denafrips volumio[11036]: *** WARNING *** For more information see Sep 14 08:48:13 denafrips volumio[11036]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:48:13 denafrips volumio[11036]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:48:13 denafrips volumio[11036]: *** WARNING *** For more information see Sep 14 08:48:13 denafrips node[11036]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:48:13 denafrips node[11036]: *** WARNING *** For more information see Sep 14 08:48:13 denafrips node[11036]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 14 08:48:13 denafrips node[11036]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 14 08:48:13 denafrips node[11036]: *** WARNING *** For more information see Sep 14 08:48:13 denafrips volumio[11036]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 14 08:48:13 denafrips volumio[11036]: info: Discovery: Started advertising with name: Denafrips Sep 14 08:48:13 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 08:48:13 denafrips volumio[11036]: info: Plugin spop is not enabled Sep 14 08:48:13 denafrips volumio[11036]: info: Loading plugin "outputs"... Sep 14 08:48:13 denafrips volumio[11036]: info: Loading plugin "albumart"... Sep 14 08:48:13 denafrips volumio[11036]: info: Plugin example_plugin is not enabled Sep 14 08:48:13 denafrips volumio[11036]: info: Loading plugin "inputs"... Sep 14 08:48:13 denafrips volumio[11036]: info: Loading plugin "updater_comm"... Sep 14 08:48:13 denafrips volumio[11036]: info: Plugin mpdemulation is not enabled Sep 14 08:48:13 denafrips volumio[11036]: info: Loading plugin "rest_api"... Sep 14 08:48:13 denafrips volumio[11036]: info: Loading plugin "websocket"... Sep 14 08:48:13 denafrips volumio[11036]: info: Starting Socket.io Server version 2.3.0 Sep 14 08:48:13 denafrips volumio[11036]: info: Loading plugin "fusiondsp"... Sep 14 08:48:13 denafrips volumio[11036]: Forking 3 albumart workers Sep 14 08:48:13 denafrips volumio-remote-updater[560]: [2025-09-14 08:48:13] [connect] Successful connection Sep 14 08:48:13 denafrips volumio[11036]: info: Applying required configuration parameters for plugin fusiondsp Sep 14 08:48:13 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:48:13 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 128. Sep 14 08:48:13 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:48:14 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:48:14 denafrips volumio[11036]: info: Loading i18n strings for locale en Sep 14 08:48:14 denafrips volumio[11036]: Updating browse sources language Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::initPlayerControls Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:48:14 denafrips volumio[11036]: Express server listening on port 3000 Sep 14 08:48:14 denafrips volumio[11036]: [Metrics] WebUI: 4s 667.05ms Sep 14 08:48:14 denafrips volumio[11036]: info: CoreStateMachine::resetVolumioState Sep 14 08:48:14 denafrips volumio[11036]: info: CoreStateMachine::getcurrentVolume Sep 14 08:48:14 denafrips volumio[11036]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:48:15 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:15 denafrips volumio[11036]: info: Volumio Network Manager: Network status updated: 2 Sep 14 08:48:15 denafrips volumio[11036]: Starting albumart workers Sep 14 08:48:15 denafrips volumio[11036]: Starting albumart workers Sep 14 08:48:15 denafrips volumio[11036]: verbose: New Socket.io Connection to 192.168.0.71 from 192.168.0.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 1 Sep 14 08:48:15 denafrips volumio-remote-updater[560]: [2025-09-14 08:48:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757810893 101 Sep 14 08:48:15 denafrips volumio[11036]: 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: 2 Sep 14 08:48:15 denafrips volumio[11036]: verbose: New Socket.io Connection to 192.168.0.71:3000 from 192.168.0.51 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 14 08:48:15 denafrips volumio[11036]: info: CoreStateMachine::pushState Sep 14 08:48:15 denafrips volumio[11036]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:15 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:48:15 denafrips volumio[11036]: Starting albumart workers Sep 14 08:48:15 denafrips volumio[11036]: info: CoreCommandRouter::volumioPushState Sep 14 08:48:15 denafrips volumio[11036]: info: CoreStateMachine::updateTrackBlock Sep 14 08:48:15 denafrips volumio[11121]: Traceback (most recent call last): Sep 14 08:48:15 denafrips volumio[11121]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:48:15 denafrips volumio[11121]: from camilladsp import CamillaConnection Sep 14 08:48:15 denafrips volumio[11121]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:48:15 denafrips volumio[11036]: info: CorePlayQueue::getTrackBlock Sep 14 08:48:15 denafrips volumio[11036]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 08:48:15 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:48:15 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:48:15 denafrips volumio[11036]: info: Reloading queue from file Sep 14 08:48:15 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 14 08:48:15 denafrips volumio[11036]: info: CoreStateMachine::setRepeat null single undefined Sep 14 08:48:15 denafrips volumio[11036]: info: CoreStateMachine::pushState Sep 14 08:48:15 denafrips volumio[11036]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:15 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:48:15 denafrips volumio[11036]: info: CoreCommandRouter::volumioPushState Sep 14 08:48:15 denafrips volumio[11036]: info: CoreStateMachine::setRandom null Sep 14 08:48:15 denafrips volumio[11036]: info: CoreStateMachine::pushState Sep 14 08:48:15 denafrips volumio[11036]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:15 denafrips volumio[11036]: info: CoreCommandRouter::volumioPushState Sep 14 08:48:15 denafrips volumio[11036]: info: Setting Device type: Raspberry PI Sep 14 08:48:15 denafrips volumio[11036]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 14 08:48:15 denafrips volumio[11036]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Sep 14 08:48:15 denafrips volumio[11036]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 14 08:48:15 denafrips volumio[11036]: info: CoreStateMachine::pushState Sep 14 08:48:15 denafrips volumio[11036]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:15 denafrips volumio[11036]: info: CoreCommandRouter::volumioPushState Sep 14 08:48:15 denafrips sudo[11076]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:15 denafrips volumio[11036]: info: Discovery: adding b390528d-acdb-4aca-b80a-77dcc5fa3e8b Sep 14 08:48:15 denafrips volumio[11036]: info: Discovery: Found device Bedtime Sep 14 08:48:15 denafrips volumio[11036]: info: Discovery: Connecting to remote: 192.168.0.51 Sep 14 08:48:16 denafrips volumio[11036]: verbose: New Socket.io Connection to 192.168.0.71 from 192.168.0.42 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::volumioGetState Sep 14 08:48:16 denafrips volumio[11036]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 08:48:16 denafrips volumio[11036]: info: Discovery: adding 7d557128-016d-42d3-b02b-91b53d86abcc Sep 14 08:48:16 denafrips volumio[11036]: info: Discovery: Found device Denafrips Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::volumioGetState Sep 14 08:48:16 denafrips volumio[11036]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::volumioGetState Sep 14 08:48:16 denafrips volumio[11036]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 08:48:16 denafrips volumio[11036]: info: Received Get System Info Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 08:48:16 denafrips volumio[11036]: info: Discovery: Getting this device information Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::volumioGetState Sep 14 08:48:16 denafrips volumio[11036]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::volumioGetState Sep 14 08:48:16 denafrips volumio[11036]: info: CorePlayQueue::getTrack 0 Sep 14 08:48:16 denafrips volumio[11036]: info: Listing playlists Sep 14 08:48:16 denafrips volumio[11036]: info: Discovery: Connected to remote: 192.168.0.51 Sep 14 08:48:16 denafrips volumio[11036]: info: Completed loading Core Plugins Sep 14 08:48:16 denafrips volumio[11036]: info: Preparing to generate the ALSA configuration file Sep 14 08:48:16 denafrips volumio[11036]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:48:16 denafrips volumio[11036]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 14 08:48:16 denafrips volumio[11036]: info: Reading ALSA contributions from plugins. Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:48:16 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:16 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:17 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:17 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:17 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:17 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:48:17 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 129. Sep 14 08:48:17 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:48:17 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:17 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:48:17 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:18 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:18 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:18 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:18 denafrips volumio[11168]: Traceback (most recent call last): Sep 14 08:48:18 denafrips volumio[11168]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:48:18 denafrips volumio[11168]: from camilladsp import CamillaConnection Sep 14 08:48:18 denafrips volumio[11168]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:48:18 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:18 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:48:18 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:48:18 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:19 denafrips volumio[11036]: info: FusionDsp - Sep 14 08:48:20 denafrips volumio[11036]: info: Cannot mount NAS SDCard at system boot, trial number 2 ,retrying in 5 seconds Sep 14 08:48:20 denafrips volumio[11036]: info: Asound.conf file unchanged, so no further update is needed Sep 14 08:48:20 denafrips volumio[11036]: info: Output device has changed, restarting MPD Sep 14 08:48:20 denafrips sudo[11170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:48:20 denafrips sudo[11170]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:20 denafrips sudo[11170]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:20 denafrips volumio[11036]: info: Output device has changed, restarting Shairport Sync Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:20 denafrips sudo[11173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:48:20 denafrips sudo[11173]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:20 denafrips systemd[1]: Stopping Music Player Daemon... Sep 14 08:48:20 denafrips volumio[11036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:48:20 denafrips volumio[11036]: info: ___________ START PLUGINS ___________ Sep 14 08:48:20 denafrips volumio[11036]: info: ControllerMpd::onStart: Initializing MPD Sep 14 08:48:20 denafrips volumio[11036]: info: Creating MPD Configuration file Sep 14 08:48:20 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:48:20 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:48:20 denafrips sudo[11180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:48:20 denafrips sudo[11180]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:48:20 denafrips volumio[11036]: info: [1757810900188] CoreMusicLibrary::Adding element Media Servers Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:48:20 denafrips sudo[11180]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:20 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:48:20 denafrips sudo[11183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:48:20 denafrips sudo[11183]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:20 denafrips systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 14 08:48:20 denafrips systemd[1]: mpd.service: Succeeded. Sep 14 08:48:20 denafrips systemd[1]: Stopped Music Player Daemon. Sep 14 08:48:20 denafrips systemd[1]: Starting Music Player Daemon... Sep 14 08:48:20 denafrips volumio[11036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:20 denafrips volumio[11036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:48:20 denafrips volumio[11036]: info: [1757810900289] CoreMusicLibrary::Adding element Last_100 Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 14 08:48:20 denafrips volumio[11036]: info: [1757810900291] CoreMusicLibrary::Adding element Webradio Sep 14 08:48:20 denafrips sudo[11186]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 08:48:20 denafrips sudo[11186]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:48:20 denafrips volumio[11036]: info: Initializing BBC Radios Sep 14 08:48:20 denafrips sudo[11186]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:48:20 denafrips volumio[11036]: info: Loading i18n strings for locale en Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 14 08:48:20 denafrips volumio[11036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:48:20 denafrips volumio[11036]: info: FusionDsp - mixtype--------------------- Software Sep 14 08:48:20 denafrips volumio[11036]: info: Volumio Calling Home Sep 14 08:48:20 denafrips volumio[11036]: info: Preparing to generate the ALSA configuration file Sep 14 08:48:20 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:20 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:20 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:20 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:20 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:20 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:20 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:20 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:20 denafrips systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 14 08:48:20 denafrips systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 130. Sep 14 08:48:20 denafrips systemd[1]: Stopped FusionDsp Daemon. Sep 14 08:48:20 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:20 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:20 denafrips systemd[1]: Started FusionDsp Daemon. Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips mpd[11193]: Sep 14 08:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips systemd[1]: Started Music Player Daemon. Sep 14 08:48:21 denafrips sudo[11183]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:21 denafrips sudo[11173]: pam_unix(sudo:session): session closed for user root Sep 14 08:48:21 denafrips volumio[11036]: info: FusionDsp - undefined Sep 14 08:48:22 denafrips volumio[11036]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:48:22 denafrips volumio[11036]: TypeError: Cannot read property 'off' of undefined Sep 14 08:48:22 denafrips volumio[11036]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Sep 14 08:48:22 denafrips volumio[11036]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 14 08:48:22 denafrips volumio[11036]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Sep 14 08:48:22 denafrips volumio[11036]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Sep 14 08:48:22 denafrips volumio[11036]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Sep 14 08:48:22 denafrips volumio[11036]: at Socket.emit (events.js:315:20) Sep 14 08:48:22 denafrips volumio[11036]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 14 08:48:22 denafrips volumio[11036]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 14 08:48:22 denafrips volumio[11036]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:48:22 denafrips volumio[11207]: Traceback (most recent call last): Sep 14 08:48:22 denafrips volumio[11207]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in Sep 14 08:48:22 denafrips volumio[11207]: from camilladsp import CamillaConnection Sep 14 08:48:22 denafrips volumio[11207]: ModuleNotFoundError: No module named 'camilladsp' Sep 14 08:48:22 denafrips systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 14 08:48:22 denafrips systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 14 08:48:22 denafrips sudo[11233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 08:47 Sep 14 08:48:22 denafrips sudo[11233]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"