-- Logs begin at Sat 2025-07-26 13:31:15 PKT, end at Sat 2025-07-26 13:37:07 PKT. -- Jul 26 13:36:00 hifidac volumio[4111]: info: Plugin mpdemulation is not enabled Jul 26 13:36:00 hifidac volumio[4111]: info: Loading plugin "rest_api"... Jul 26 13:36:00 hifidac volumio[4111]: info: Loading plugin "websocket"... Jul 26 13:36:00 hifidac volumio[4111]: info: Starting Socket.io Server version 2.3.0 Jul 26 13:36:00 hifidac volumio[4111]: info: Loading plugin "fusiondsp"... Jul 26 13:36:00 hifidac volumio[4111]: Forking 3 albumart workers Jul 26 13:36:00 hifidac volumio[4111]: info: Applying required configuration parameters for plugin fusiondsp Jul 26 13:36:01 hifidac volumio[4111]: info: Loading plugin "ferrum_streaming_control_technology"... Jul 26 13:36:01 hifidac volumio[4111]: info: Applying required configuration parameters for plugin ferrum_streaming_control_technology Jul 26 13:36:01 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:36:01 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 42. Jul 26 13:36:01 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:36:01 hifidac volumio[4111]: info: Loading i18n strings for locale en Jul 26 13:36:01 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:36:01 hifidac volumio[4111]: Updating browse sources language Jul 26 13:36:01 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:02 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:36:02 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:02 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:36:02 hifidac volumio[4111]: info: CoreCommandRouter::initPlayerControls Jul 26 13:36:02 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:02 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:02 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:02 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:02 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:02 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:02 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:36:02 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:02 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:02 hifidac volumio[4111]: Express server listening on port 3000 Jul 26 13:36:02 hifidac volumio[4111]: [Metrics] WebUI: 10s 619.59ms Jul 26 13:36:02 hifidac volumio[4111]: Starting albumart workers Jul 26 13:36:02 hifidac volumio[4111]: info: CoreStateMachine::resetVolumioState Jul 26 13:36:02 hifidac volumio[4111]: info: CoreStateMachine::getcurrentVolume Jul 26 13:36:02 hifidac volumio[4111]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 13:36:03 hifidac volumio[4111]: Starting albumart workers Jul 26 13:36:03 hifidac volumio[4111]: info: Volumio Network Manager: Network status updated: 0 Jul 26 13:36:03 hifidac volumio[4111]: Starting albumart workers Jul 26 13:36:03 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1753518959 101 Jul 26 13:36:03 hifidac volumio[4111]: 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 Jul 26 13:36:03 hifidac volumio[4111]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 26 13:36:03 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:03 hifidac volumio[4111]: info: Reloading queue from file Jul 26 13:36:03 hifidac volumio[4111]: info: VolumeController:: Volume=undefined Mute =false Jul 26 13:36:03 hifidac volumio[4111]: info: CoreStateMachine::pushState Jul 26 13:36:03 hifidac volumio[4111]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:03 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:36:03 hifidac volumio[4111]: info: CoreCommandRouter::volumioPushState Jul 26 13:36:03 hifidac volumio[4111]: info: CoreStateMachine::updateTrackBlock Jul 26 13:36:03 hifidac volumio[4111]: info: CorePlayQueue::getTrackBlock Jul 26 13:36:03 hifidac volumio[4111]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 13:36:03 hifidac volumio[4111]: info: CoreStateMachine::setRepeat null single undefined Jul 26 13:36:03 hifidac volumio[4111]: info: CoreStateMachine::pushState Jul 26 13:36:03 hifidac volumio[4111]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:03 hifidac volumio[4111]: info: CoreCommandRouter::volumioPushState Jul 26 13:36:03 hifidac volumio[4111]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:03 hifidac volumio[4111]: info: Setting Device type: Raspberry PI Jul 26 13:36:03 hifidac volumio[4111]: info: Completed loading Core Plugins Jul 26 13:36:03 hifidac volumio[4111]: info: Preparing to generate the ALSA configuration file Jul 26 13:36:03 hifidac volumio[4111]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 26 13:36:03 hifidac volumio[4111]: info: Reading ALSA contributions from plugins. Jul 26 13:36:03 hifidac volumio[4111]: info: Asound.conf file unchanged, so no further update is needed Jul 26 13:36:03 hifidac volumio[4111]: info: Output device has changed, restarting MPD Jul 26 13:36:03 hifidac volumio[4111]: info: ___________ START PLUGINS ___________ Jul 26 13:36:03 hifidac volumio[4111]: info: ControllerMpd::onStart: Initializing MPD Jul 26 13:36:03 hifidac volumio[4111]: info: Creating MPD Configuration file Jul 26 13:36:03 hifidac sudo[4230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:36:03 hifidac sudo[4230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:03 hifidac sudo[4230]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:03 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:36:03 hifidac volumio[4111]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:36:03 hifidac sudo[4232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:36:03 hifidac volumio[4111]: info: [1753518963936] CoreMusicLibrary::Adding element Media Servers Jul 26 13:36:03 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:03 hifidac sudo[4232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:03 hifidac sudo[4234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:36:03 hifidac sudo[4234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:03 hifidac sudo[4234]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:04 hifidac volumio[4111]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:36:04 hifidac volumio[4111]: info: [1753518964077] CoreMusicLibrary::Adding element Last_100 Jul 26 13:36:04 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:04 hifidac systemd[1]: Stopping Music Player Daemon... Jul 26 13:36:04 hifidac volumio[4111]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:36:04 hifidac volumio[4111]: info: [1753518964118] CoreMusicLibrary::Adding element Webradio Jul 26 13:36:04 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:04 hifidac sudo[4237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:36:04 hifidac systemd[1]: mpd.service: Succeeded. Jul 26 13:36:04 hifidac systemd[1]: Stopped Music Player Daemon. Jul 26 13:36:04 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 13:36:04 hifidac sudo[4237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:04 hifidac volumio[4111]: info: Initializing BBC Radios Jul 26 13:36:04 hifidac systemd[1]: Starting Music Player Daemon... Jul 26 13:36:04 hifidac systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 26 13:36:04 hifidac systemd[1]: mpd.service: Succeeded. Jul 26 13:36:04 hifidac systemd[1]: Stopped Music Player Daemon. Jul 26 13:36:04 hifidac systemd[1]: Starting Music Player Daemon... Jul 26 13:36:04 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 13:36:04 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:04 hifidac sudo[4248]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 26 13:36:04 hifidac sudo[4248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:04 hifidac sudo[4248]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:04 hifidac volumio[4111]: info: Loading i18n strings for locale en Jul 26 13:36:04 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 26 13:36:04 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:04 hifidac volumio[4111]: info: FusionDsp - mixtype--------------------- undefined Jul 26 13:36:04 hifidac fsct-plugin[4111]: Failed to initialize device 1d6b:0002: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:04 hifidac volumio[4111]: info: Volumio Calling Home Jul 26 13:36:04 hifidac fsct-plugin[4111]: Failed to initialize device 0424:ec00: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:04 hifidac fsct-plugin[4111]: Failed to initialize device 0424:9514: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:04 hifidac fsct-plugin[4111]: Failed to initialize device 046d:c52f: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:05 hifidac volumio[4213]: Traceback (most recent call last): Jul 26 13:36:05 hifidac volumio[4213]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:36:05 hifidac volumio[4213]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:36:05 hifidac volumio[4213]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:36:05 hifidac volumio[4111]: info: Preparing to generate the ALSA configuration file Jul 26 13:36:05 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:05 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:36:05 hifidac volumio[4111]: Unhandled rejection Error: No sockets available, cannot start. Jul 26 13:36:05 hifidac volumio[4111]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 26 13:36:05 hifidac volumio[4111]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 26 13:36:05 hifidac volumio[4111]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 26 13:36:05 hifidac volumio[4111]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 26 13:36:05 hifidac volumio[4111]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 26 13:36:05 hifidac volumio[4111]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 26 13:36:05 hifidac volumio[4111]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 26 13:36:05 hifidac volumio[4111]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 26 13:36:05 hifidac volumio[4111]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 26 13:36:05 hifidac volumio[4111]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 26 13:36:05 hifidac volumio[4111]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 26 13:36:05 hifidac volumio[4111]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 26 13:36:05 hifidac volumio[4111]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 26 13:36:05 hifidac volumio[4111]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 26 13:36:05 hifidac volumio[4111]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 26 13:36:05 hifidac volumio[4111]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 26 13:36:05 hifidac volumio[4111]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 26 13:36:05 hifidac volumio[4111]: info: Reading ALSA contributions from plugins. Jul 26 13:36:05 hifidac volumio[4111]: info: MPD Permissions set Jul 26 13:36:05 hifidac volumio[4111]: info: MPD Permissions set Jul 26 13:36:05 hifidac volumio[4111]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 26 13:36:05 hifidac volumio[4111]: info: CoreCommandRouter::volumioGetState Jul 26 13:36:05 hifidac volumio[4111]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:05 hifidac volumio[4111]: info: FSCT Started Jul 26 13:36:05 hifidac volumio[4111]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 26 13:36:05 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:05 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:05 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:05 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:05 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:05 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:05 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:05 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:05 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:05 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: info: VolumeController:: Volume=undefined Mute =false Jul 26 13:36:06 hifidac volumio[4111]: info: CoreStateMachine::pushState Jul 26 13:36:06 hifidac volumio[4111]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::volumioPushState Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::volumioGetState Jul 26 13:36:06 hifidac volumio[4111]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:06 hifidac volumio[4111]: info: Asound.conf file unchanged, so no further update is needed Jul 26 13:36:06 hifidac volumio[4111]: info: Output device has changed, restarting MPD Jul 26 13:36:06 hifidac sudo[4293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:36:06 hifidac sudo[4293]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:06 hifidac sudo[4293]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:06 hifidac sudo[4295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:36:06 hifidac sudo[4295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:06 hifidac volumio[4111]: info: MPD Permissions set Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 26 13:36:06 hifidac systemd[1]: mpd.service: Succeeded. Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:06 hifidac systemd[1]: Stopped Music Player Daemon. Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac systemd[1]: Starting Music Player Daemon... Jul 26 13:36:06 hifidac sudo[4302]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 26 13:36:06 hifidac sudo[4302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:06 hifidac sudo[4302]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:06 hifidac volumio[4111]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 26 13:36:06 hifidac volumio[4111]: 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 Jul 26 13:36:06 hifidac volumio[4111]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 26 13:36:06 hifidac volumio[4111]: info: camilladsp service started and running in background, instance 1 Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:06 hifidac volumio[4111]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 26 13:36:06 hifidac volumio[4111]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 26 13:36:06 hifidac volumio[4111]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 26 13:36:06 hifidac volumio[4111]: info: FusionDsp loaded Jul 26 13:36:06 hifidac volumio[4111]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 26 13:36:06 hifidac sudo[4315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 26 13:36:06 hifidac sudo[4315]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:07 hifidac volumio[4111]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 26 13:36:07 hifidac volumio[4111]: info: Adding Signal Path Element [object Object] Jul 26 13:36:07 hifidac volumio[4111]: info: Adding fusiondspeq DSP Signal Path Element Jul 26 13:36:07 hifidac volumio[4111]: info: FusionDsp - ---- installed callbackRead Jul 26 13:36:07 hifidac volumio[4111]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:36:07 hifidac volumio[4111]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 26 13:36:07 hifidac volumio[4111]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 26 13:36:07 hifidac volumio[4111]: at onErrorNT (internal/child_process.js:465:16) Jul 26 13:36:07 hifidac volumio[4111]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 26 13:36:07 hifidac volumio[4111]: errno: -2, Jul 26 13:36:07 hifidac volumio[4111]: code: 'ENOENT', Jul 26 13:36:07 hifidac volumio[4111]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 26 13:36:07 hifidac volumio[4111]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 26 13:36:07 hifidac volumio[4111]: spawnargs: [ Jul 26 13:36:07 hifidac volumio[4111]: '-p', Jul 26 13:36:07 hifidac volumio[4111]: 9876, Jul 26 13:36:07 hifidac volumio[4111]: '-o', Jul 26 13:36:07 hifidac volumio[4111]: '/tmp/camilladsp.log', Jul 26 13:36:07 hifidac volumio[4111]: '-l', Jul 26 13:36:07 hifidac volumio[4111]: 'warn', Jul 26 13:36:07 hifidac volumio[4111]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 26 13:36:07 hifidac volumio[4111]: ] Jul 26 13:36:07 hifidac volumio[4111]: } Jul 26 13:36:07 hifidac volumio[4111]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:36:07 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:36:07 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 43. Jul 26 13:36:07 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:36:07 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:36:07 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:07 hifidac sudo[4315]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:07 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:07 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:07 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:36:08 hifidac sudo[4346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-26 13:35 Jul 26 13:36:08 hifidac sudo[4346]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:08 hifidac sudo[4346]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:08 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 26 13:36:08 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 26 13:36:08 hifidac systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:09 hifidac sudo[4295]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:09 hifidac sudo[4232]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:09 hifidac sudo[4237]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:09 hifidac systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 26 13:36:09 hifidac systemd[1]: Started dynamicswap service. Jul 26 13:36:09 hifidac systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 26 13:36:09 hifidac systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13. Jul 26 13:36:09 hifidac systemd[1]: dynamicswap.service: Succeeded. Jul 26 13:36:09 hifidac systemd[1]: Stopped Volumio Backend Module. Jul 26 13:36:09 hifidac systemd[1]: Started Volumio Backend Module. Jul 26 13:36:10 hifidac mpd[4305]: Jul 26 13:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 26 13:36:10 hifidac systemd[1]: Started Music Player Daemon. Jul 26 13:36:10 hifidac volumio[4339]: Traceback (most recent call last): Jul 26 13:36:10 hifidac volumio[4339]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:36:10 hifidac volumio[4339]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:36:10 hifidac volumio[4339]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:36:10 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:10 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:36:12 hifidac volumio[4359]: info: ------------------------------------------- Jul 26 13:36:12 hifidac volumio[4359]: info: ----- Volumio3 ---- Jul 26 13:36:12 hifidac volumio[4359]: info: ------------------------------------------- Jul 26 13:36:12 hifidac volumio[4359]: info: ----- System startup ---- Jul 26 13:36:12 hifidac volumio[4359]: info: ------------------------------------------- Jul 26 13:36:12 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:12 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:12 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:13 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:36:13 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 44. Jul 26 13:36:13 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:36:13 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:36:13 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:36:13 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:13] [connect] Successful connection Jul 26 13:36:14 hifidac volumio[4359]: info: MYVOLUMIO Environment detected Jul 26 13:36:14 hifidac volumio[4359]: info: Plugin folders cleanup Jul 26 13:36:14 hifidac volumio[4359]: info: Scanning into folder /volumio/app/plugins/ Jul 26 13:36:14 hifidac volumio[4359]: info: Scanning category audio_interface Jul 26 13:36:14 hifidac volumio[4359]: info: Scanning category miscellanea Jul 26 13:36:14 hifidac volumio[4359]: info: Scanning category music_service Jul 26 13:36:14 hifidac volumio[4359]: info: Scanning category plugins.json Jul 26 13:36:14 hifidac volumio[4359]: info: Scanning category system_controller Jul 26 13:36:14 hifidac volumio[4359]: info: Scanning category user_interface Jul 26 13:36:14 hifidac volumio[4359]: info: Scanning into folder /data/plugins/ Jul 26 13:36:14 hifidac volumio[4359]: info: Scanning category audio_interface Jul 26 13:36:14 hifidac volumio[4359]: info: Scanning category user_interface Jul 26 13:36:14 hifidac volumio[4359]: info: Plugin folders cleanup completed Jul 26 13:36:14 hifidac volumio[4359]: info: ------------------------------------------- Jul 26 13:36:14 hifidac volumio[4359]: info: ----- Core plugins startup ---- Jul 26 13:36:14 hifidac volumio[4359]: info: ------------------------------------------- Jul 26 13:36:14 hifidac volumio[4359]: info: Loading plugins from folder /volumio/app/plugins/ Jul 26 13:36:14 hifidac volumio[4359]: info: Adding plugin upnp to MyMusic Plugins Jul 26 13:36:14 hifidac volumio[4359]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 26 13:36:14 hifidac volumio[4359]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 26 13:36:14 hifidac volumio[4359]: info: Loading plugins from folder /data/plugins/ Jul 26 13:36:14 hifidac volumio[4359]: info: Loading plugin "system"... Jul 26 13:36:14 hifidac volumio[4359]: info: Loading plugin "appearance"... Jul 26 13:36:15 hifidac volumio[4381]: Traceback (most recent call last): Jul 26 13:36:15 hifidac volumio[4381]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:36:15 hifidac volumio[4381]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:36:15 hifidac volumio[4381]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:36:16 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:16 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:36:16 hifidac volumio[4359]: info: Loading plugin "network"... Jul 26 13:36:16 hifidac volumio[4359]: info: Refreshing Cached IP Addresses Jul 26 13:36:16 hifidac sudo[4391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 26 13:36:16 hifidac sudo[4391]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:16 hifidac sudo[4391]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:16 hifidac sudo[4393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 26 13:36:16 hifidac sudo[4393]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:16 hifidac sudo[4393]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:16 hifidac volumio[4359]: info: Loading plugin "services"... Jul 26 13:36:16 hifidac volumio[4359]: info: Loading plugin "alsa_controller"... Jul 26 13:36:16 hifidac sudo[4402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 26 13:36:16 hifidac sudo[4402]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:16 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 13:36:16 hifidac volumio[4359]: info: Loading plugin "wizard"... Jul 26 13:36:16 hifidac volumio[4359]: info: Loading plugin "networkfs"... Jul 26 13:36:16 hifidac volumio[4359]: info: Starting Udev Watcher for removable devices Jul 26 13:36:16 hifidac volumio[4359]: info: Ignoring mount for partition: boot Jul 26 13:36:16 hifidac volumio[4359]: info: Ignoring mount for partition: volumio Jul 26 13:36:16 hifidac volumio[4359]: info: Ignoring mount for partition: volumio_data Jul 26 13:36:16 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 13:36:16 hifidac volumio[4359]: info: Loading plugin "volumio_command_line_client"... Jul 26 13:36:16 hifidac volumio[4359]: info: Loading plugin "upnp"... Jul 26 13:36:17 hifidac volumio[4359]: info: [1753518977003] Starting Upmpd Daemon Jul 26 13:36:17 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 13:36:17 hifidac volumio[4359]: info: Loading plugin "my_music"... Jul 26 13:36:17 hifidac volumio[4359]: info: Loading plugin "mpd"... Jul 26 13:36:17 hifidac sudo[4402]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:17 hifidac volumio[4359]: info: Loading plugin "upnp_browser"... Jul 26 13:36:17 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:17 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:17 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:18 hifidac volumio[4359]: info: Loading plugin "alarm-clock"... Jul 26 13:36:18 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:36:18 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 45. Jul 26 13:36:18 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:36:18 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:36:18 hifidac volumio[4359]: info: Plugin airplay_emulation is not enabled Jul 26 13:36:18 hifidac volumio[4359]: info: Loading plugin "last_100"... Jul 26 13:36:18 hifidac volumio[4359]: info: Loading plugin "webradio"... Jul 26 13:36:18 hifidac volumio[4359]: info: Loading plugin "i2s_dacs"... Jul 26 13:36:18 hifidac volumio[4359]: info: Loading plugin "volumiodiscovery"... Jul 26 13:36:18 hifidac volumio[4359]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:18 hifidac volumio[4359]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:18 hifidac volumio[4359]: *** WARNING *** For more information see Jul 26 13:36:18 hifidac node[4359]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:18 hifidac volumio[4359]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:18 hifidac volumio[4359]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:18 hifidac volumio[4359]: *** WARNING *** For more information see Jul 26 13:36:18 hifidac node[4359]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:18 hifidac node[4359]: *** WARNING *** For more information see Jul 26 13:36:18 hifidac node[4359]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:18 hifidac node[4359]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:18 hifidac node[4359]: *** WARNING *** For more information see Jul 26 13:36:18 hifidac volumio[4359]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 26 13:36:18 hifidac volumio[4359]: info: Discovery: Started advertising with name: Hifidac Jul 26 13:36:18 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 13:36:18 hifidac volumio[4359]: info: Loading plugin "outputs"... Jul 26 13:36:18 hifidac volumio[4359]: info: Loading plugin "albumart"... Jul 26 13:36:18 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:36:19 hifidac volumio[4359]: info: Plugin example_plugin is not enabled Jul 26 13:36:19 hifidac volumio[4359]: info: Loading plugin "inputs"... Jul 26 13:36:19 hifidac volumio[4359]: info: Loading plugin "updater_comm"... Jul 26 13:36:19 hifidac volumio[4359]: info: Plugin mpdemulation is not enabled Jul 26 13:36:19 hifidac volumio[4359]: info: Loading plugin "rest_api"... Jul 26 13:36:19 hifidac volumio[4359]: info: Loading plugin "websocket"... Jul 26 13:36:19 hifidac volumio[4359]: info: Starting Socket.io Server version 2.3.0 Jul 26 13:36:19 hifidac volumio[4359]: info: Loading plugin "fusiondsp"... Jul 26 13:36:19 hifidac volumio[4359]: Forking 3 albumart workers Jul 26 13:36:20 hifidac volumio[4359]: info: Applying required configuration parameters for plugin fusiondsp Jul 26 13:36:20 hifidac volumio[4359]: info: Loading plugin "ferrum_streaming_control_technology"... Jul 26 13:36:21 hifidac volumio[4421]: Traceback (most recent call last): Jul 26 13:36:21 hifidac volumio[4421]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:36:21 hifidac volumio[4421]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:36:21 hifidac volumio[4421]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:36:21 hifidac volumio[4359]: info: Applying required configuration parameters for plugin ferrum_streaming_control_technology Jul 26 13:36:21 hifidac volumio[4359]: info: Loading i18n strings for locale en Jul 26 13:36:21 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:21 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:36:21 hifidac volumio[4359]: Updating browse sources language Jul 26 13:36:21 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::initPlayerControls Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:36:22 hifidac volumio[4359]: Express server listening on port 3000 Jul 26 13:36:22 hifidac volumio[4359]: [Metrics] WebUI: 10s 489.89ms Jul 26 13:36:22 hifidac volumio[4359]: info: CoreStateMachine::resetVolumioState Jul 26 13:36:22 hifidac volumio[4359]: info: CoreStateMachine::getcurrentVolume Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 13:36:22 hifidac volumio[4359]: info: Volumio Network Manager: Network status updated: 0 Jul 26 13:36:22 hifidac volumio[4359]: Starting albumart workers Jul 26 13:36:22 hifidac volumio[4359]: Starting albumart workers Jul 26 13:36:22 hifidac volumio[4359]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 26 13:36:22 hifidac volumio[4359]: Starting albumart workers Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:22 hifidac volumio[4359]: info: Reloading queue from file Jul 26 13:36:22 hifidac volumio[4359]: info: CoreStateMachine::setRepeat null single undefined Jul 26 13:36:22 hifidac volumio[4359]: info: CoreStateMachine::pushState Jul 26 13:36:22 hifidac volumio[4359]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::volumioPushState Jul 26 13:36:22 hifidac volumio[4359]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:22 hifidac volumio[4359]: info: Setting Device type: Raspberry PI Jul 26 13:36:22 hifidac volumio[4359]: info: Completed loading Core Plugins Jul 26 13:36:22 hifidac volumio[4359]: info: Preparing to generate the ALSA configuration file Jul 26 13:36:22 hifidac volumio[4359]: info: VolumeController:: Volume=undefined Mute =false Jul 26 13:36:22 hifidac volumio[4359]: info: CoreStateMachine::pushState Jul 26 13:36:22 hifidac volumio[4359]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::volumioPushState Jul 26 13:36:22 hifidac volumio[4359]: info: CoreStateMachine::updateTrackBlock Jul 26 13:36:22 hifidac volumio[4359]: info: CorePlayQueue::getTrackBlock Jul 26 13:36:22 hifidac volumio[4359]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 13:36:22 hifidac volumio[4359]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 26 13:36:22 hifidac volumio[4359]: info: Reading ALSA contributions from plugins. Jul 26 13:36:22 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:22 hifidac volumio[4359]: info: Asound.conf file unchanged, so no further update is needed Jul 26 13:36:22 hifidac volumio[4359]: info: Output device has changed, restarting MPD Jul 26 13:36:23 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:23 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:23 hifidac sudo[4471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:36:23 hifidac volumio[4359]: info: ___________ START PLUGINS ___________ Jul 26 13:36:23 hifidac sudo[4471]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:23 hifidac volumio[4359]: info: ControllerMpd::onStart: Initializing MPD Jul 26 13:36:23 hifidac volumio[4359]: info: Creating MPD Configuration file Jul 26 13:36:23 hifidac sudo[4471]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:23 hifidac sudo[4474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:36:23 hifidac sudo[4474]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:36:23 hifidac volumio[4359]: info: [1753518983208] CoreMusicLibrary::Adding element Media Servers Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:23 hifidac sudo[4477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:36:23 hifidac sudo[4477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:23 hifidac sudo[4477]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:23 hifidac sudo[4479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:36:23 hifidac sudo[4479]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:23 hifidac systemd[1]: Stopping Music Player Daemon... Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:36:23 hifidac volumio[4359]: info: [1753518983324] CoreMusicLibrary::Adding element Last_100 Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:36:23 hifidac volumio[4359]: info: [1753518983355] CoreMusicLibrary::Adding element Webradio Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:23 hifidac systemd[1]: mpd.service: Succeeded. Jul 26 13:36:23 hifidac systemd[1]: Stopped Music Player Daemon. Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 13:36:23 hifidac volumio[4359]: info: Initializing BBC Radios Jul 26 13:36:23 hifidac systemd[1]: Starting Music Player Daemon... Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:23 hifidac volumio[4359]: info: Loading i18n strings for locale en Jul 26 13:36:23 hifidac sudo[4499]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 26 13:36:23 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:23 hifidac volumio[4359]: info: FusionDsp - mixtype--------------------- undefined Jul 26 13:36:23 hifidac sudo[4499]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:23 hifidac fsct-plugin[4359]: Failed to initialize device 1d6b:0002: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:23 hifidac sudo[4499]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:23 hifidac fsct-plugin[4359]: Failed to initialize device 0424:ec00: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:23 hifidac volumio[4359]: info: Volumio Calling Home Jul 26 13:36:23 hifidac fsct-plugin[4359]: Failed to initialize device 0424:9514: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:23 hifidac fsct-plugin[4359]: Failed to initialize device 046d:c52f: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:23 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:36:23 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 46. Jul 26 13:36:23 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:36:23 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:36:24 hifidac volumio[4359]: info: Preparing to generate the ALSA configuration file Jul 26 13:36:24 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:36:24 hifidac volumio[4359]: Unhandled rejection Error: No sockets available, cannot start. Jul 26 13:36:24 hifidac volumio[4359]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 26 13:36:24 hifidac volumio[4359]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 26 13:36:24 hifidac volumio[4359]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 26 13:36:24 hifidac volumio[4359]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 26 13:36:24 hifidac volumio[4359]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 26 13:36:24 hifidac volumio[4359]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 26 13:36:24 hifidac volumio[4359]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 26 13:36:24 hifidac volumio[4359]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 26 13:36:24 hifidac volumio[4359]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 26 13:36:24 hifidac volumio[4359]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 26 13:36:24 hifidac volumio[4359]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 26 13:36:24 hifidac volumio[4359]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 26 13:36:24 hifidac volumio[4359]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 26 13:36:24 hifidac volumio[4359]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 26 13:36:24 hifidac volumio[4359]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 26 13:36:24 hifidac volumio[4359]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 26 13:36:24 hifidac volumio[4359]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 26 13:36:24 hifidac volumio[4359]: info: Reading ALSA contributions from plugins. Jul 26 13:36:24 hifidac volumio[4359]: info: MPD Permissions set Jul 26 13:36:24 hifidac volumio[4359]: info: MPD Permissions set Jul 26 13:36:24 hifidac volumio[4359]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::volumioGetState Jul 26 13:36:25 hifidac volumio[4359]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:25 hifidac volumio[4359]: info: FSCT Started Jul 26 13:36:25 hifidac volumio[4359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: VolumeController:: Volume=undefined Mute =false Jul 26 13:36:25 hifidac volumio[4359]: info: CoreStateMachine::pushState Jul 26 13:36:25 hifidac volumio[4359]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::volumioPushState Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::volumioGetState Jul 26 13:36:25 hifidac volumio[4359]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:25 hifidac volumio[4359]: info: Asound.conf file unchanged, so no further update is needed Jul 26 13:36:25 hifidac volumio[4359]: info: Output device has changed, restarting MPD Jul 26 13:36:25 hifidac sudo[4574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac sudo[4574]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac sudo[4574]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:25 hifidac sudo[4576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:36:25 hifidac sudo[4576]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:25 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:25 hifidac systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 26 13:36:25 hifidac systemd[1]: mpd.service: Succeeded. Jul 26 13:36:25 hifidac systemd[1]: Stopped Music Player Daemon. Jul 26 13:36:25 hifidac volumio[4359]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 26 13:36:25 hifidac volumio[4359]: 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 Jul 26 13:36:25 hifidac volumio[4359]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 26 13:36:26 hifidac volumio[4359]: info: camilladsp service started and running in background, instance 1 Jul 26 13:36:26 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:26 hifidac systemd[1]: Starting Music Player Daemon... Jul 26 13:36:26 hifidac volumio[4359]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 26 13:36:26 hifidac volumio[4359]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 26 13:36:26 hifidac volumio[4359]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 26 13:36:26 hifidac volumio[4359]: info: FusionDsp loaded Jul 26 13:36:26 hifidac volumio[4359]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 26 13:36:26 hifidac sudo[4594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 26 13:36:26 hifidac sudo[4594]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:26 hifidac volumio[4359]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 26 13:36:26 hifidac volumio[4359]: info: Adding Signal Path Element [object Object] Jul 26 13:36:26 hifidac volumio[4359]: info: Adding fusiondspeq DSP Signal Path Element Jul 26 13:36:26 hifidac volumio[4359]: info: FusionDsp - ---- installed callbackRead Jul 26 13:36:26 hifidac volumio[4359]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:36:26 hifidac volumio[4359]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 26 13:36:26 hifidac volumio[4359]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 26 13:36:26 hifidac volumio[4359]: at onErrorNT (internal/child_process.js:465:16) Jul 26 13:36:26 hifidac volumio[4359]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 26 13:36:26 hifidac volumio[4359]: at runNextTicks (internal/process/task_queues.js:62:3) Jul 26 13:36:26 hifidac volumio[4359]: at listOnTimeout (internal/timers.js:523:9) Jul 26 13:36:26 hifidac volumio[4359]: at processTimers (internal/timers.js:497:7) { Jul 26 13:36:26 hifidac volumio[4359]: errno: -2, Jul 26 13:36:26 hifidac volumio[4359]: code: 'ENOENT', Jul 26 13:36:26 hifidac volumio[4359]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 26 13:36:26 hifidac volumio[4359]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 26 13:36:26 hifidac volumio[4359]: spawnargs: [ Jul 26 13:36:26 hifidac volumio[4359]: '-p', Jul 26 13:36:26 hifidac volumio[4359]: 9876, Jul 26 13:36:26 hifidac volumio[4359]: '-o', Jul 26 13:36:26 hifidac volumio[4359]: '/tmp/camilladsp.log', Jul 26 13:36:26 hifidac volumio[4359]: '-l', Jul 26 13:36:26 hifidac volumio[4359]: 'warn', Jul 26 13:36:26 hifidac volumio[4359]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 26 13:36:26 hifidac volumio[4359]: ] Jul 26 13:36:26 hifidac volumio[4359]: } Jul 26 13:36:26 hifidac volumio[4359]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:36:26 hifidac sudo[4594]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:26 hifidac sudo[4583]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 26 13:36:26 hifidac sudo[4583]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:26 hifidac sudo[4583]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:26 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:26] [connect] Successful connection Jul 26 13:36:27 hifidac volumio[4538]: Traceback (most recent call last): Jul 26 13:36:27 hifidac volumio[4538]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:36:27 hifidac volumio[4538]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:36:27 hifidac volumio[4538]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:36:27 hifidac sudo[4621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-26 13:35 Jul 26 13:36:27 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:27 hifidac sudo[4621]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:27 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:36:27 hifidac sudo[4621]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:28 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:28 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:28 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:28 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:28] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Jul 26 13:36:28 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:28] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Jul 26 13:36:28 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:28] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 26 13:36:28 hifidac systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:28 hifidac sudo[4576]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:28 hifidac sudo[4479]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:28 hifidac sudo[4474]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:28 hifidac systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 26 13:36:28 hifidac systemd[1]: Started dynamicswap service. Jul 26 13:36:28 hifidac systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 26 13:36:28 hifidac systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14. Jul 26 13:36:28 hifidac systemd[1]: Stopped Volumio Backend Module. Jul 26 13:36:28 hifidac systemd[1]: Started Volumio Backend Module. Jul 26 13:36:28 hifidac systemd[1]: dynamicswap.service: Succeeded. Jul 26 13:36:29 hifidac mpd[4604]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 26 13:36:29 hifidac mpd[4604]: output: No 'audio_output' defined in config file Jul 26 13:36:29 hifidac mpd[4604]: output: Successfully detected a sndio audio device Jul 26 13:36:29 hifidac mpd[4604]: zeroconf: No global port, disabling zeroconf Jul 26 13:36:29 hifidac systemd[1]: Started Music Player Daemon. Jul 26 13:36:29 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:36:29 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 47. Jul 26 13:36:29 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:36:29 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:36:30 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:36:31 hifidac volumio[4635]: info: ------------------------------------------- Jul 26 13:36:31 hifidac volumio[4635]: info: ----- Volumio3 ---- Jul 26 13:36:31 hifidac volumio[4635]: info: ------------------------------------------- Jul 26 13:36:31 hifidac volumio[4635]: info: ----- System startup ---- Jul 26 13:36:31 hifidac volumio[4635]: info: ------------------------------------------- Jul 26 13:36:32 hifidac volumio[4649]: Traceback (most recent call last): Jul 26 13:36:32 hifidac volumio[4649]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:36:32 hifidac volumio[4649]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:36:32 hifidac volumio[4649]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:36:32 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:32 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:36:33 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:33 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:33 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:33 hifidac volumio[4635]: info: MYVOLUMIO Environment detected Jul 26 13:36:33 hifidac volumio[4635]: info: Plugin folders cleanup Jul 26 13:36:33 hifidac volumio[4635]: info: Scanning into folder /volumio/app/plugins/ Jul 26 13:36:33 hifidac volumio[4635]: info: Scanning category audio_interface Jul 26 13:36:33 hifidac volumio[4635]: info: Scanning category miscellanea Jul 26 13:36:33 hifidac volumio[4635]: info: Scanning category music_service Jul 26 13:36:33 hifidac volumio[4635]: info: Scanning category plugins.json Jul 26 13:36:33 hifidac volumio[4635]: info: Scanning category system_controller Jul 26 13:36:33 hifidac volumio[4635]: info: Scanning category user_interface Jul 26 13:36:33 hifidac volumio[4635]: info: Scanning into folder /data/plugins/ Jul 26 13:36:33 hifidac volumio[4635]: info: Scanning category audio_interface Jul 26 13:36:33 hifidac volumio[4635]: info: Scanning category user_interface Jul 26 13:36:33 hifidac volumio[4635]: info: Plugin folders cleanup completed Jul 26 13:36:33 hifidac volumio[4635]: info: ------------------------------------------- Jul 26 13:36:33 hifidac volumio[4635]: info: ----- Core plugins startup ---- Jul 26 13:36:33 hifidac volumio[4635]: info: ------------------------------------------- Jul 26 13:36:33 hifidac volumio[4635]: info: Loading plugins from folder /volumio/app/plugins/ Jul 26 13:36:33 hifidac volumio[4635]: info: Adding plugin upnp to MyMusic Plugins Jul 26 13:36:33 hifidac volumio[4635]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 26 13:36:33 hifidac volumio[4635]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 26 13:36:33 hifidac volumio[4635]: info: Loading plugins from folder /data/plugins/ Jul 26 13:36:33 hifidac volumio[4635]: info: Loading plugin "system"... Jul 26 13:36:33 hifidac volumio[4635]: info: Loading plugin "appearance"... Jul 26 13:36:35 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:36:35 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 48. Jul 26 13:36:35 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:36:35 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:36:35 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:36:35 hifidac volumio[4635]: info: Loading plugin "network"... Jul 26 13:36:35 hifidac volumio[4635]: info: Refreshing Cached IP Addresses Jul 26 13:36:35 hifidac sudo[4673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 26 13:36:35 hifidac sudo[4673]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:35 hifidac sudo[4673]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:35 hifidac sudo[4675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 26 13:36:35 hifidac sudo[4675]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:35 hifidac volumio[4635]: info: Loading plugin "services"... Jul 26 13:36:35 hifidac volumio[4635]: info: Loading plugin "alsa_controller"... Jul 26 13:36:35 hifidac sudo[4675]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:35 hifidac sudo[4684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 26 13:36:35 hifidac sudo[4684]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:35 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 13:36:35 hifidac volumio[4635]: info: Loading plugin "wizard"... Jul 26 13:36:35 hifidac volumio[4635]: info: Loading plugin "networkfs"... Jul 26 13:36:36 hifidac volumio[4635]: info: Starting Udev Watcher for removable devices Jul 26 13:36:36 hifidac volumio[4635]: info: Ignoring mount for partition: boot Jul 26 13:36:36 hifidac volumio[4635]: info: Ignoring mount for partition: volumio Jul 26 13:36:36 hifidac volumio[4635]: info: Ignoring mount for partition: volumio_data Jul 26 13:36:36 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 13:36:36 hifidac volumio[4635]: info: Loading plugin "volumio_command_line_client"... Jul 26 13:36:36 hifidac volumio[4635]: info: Loading plugin "upnp"... Jul 26 13:36:36 hifidac volumio[4635]: info: [1753518996102] Starting Upmpd Daemon Jul 26 13:36:36 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 13:36:36 hifidac volumio[4635]: info: Loading plugin "my_music"... Jul 26 13:36:36 hifidac volumio[4635]: info: Loading plugin "mpd"... Jul 26 13:36:36 hifidac sudo[4684]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:36 hifidac volumio[4635]: info: Loading plugin "upnp_browser"... Jul 26 13:36:37 hifidac volumio[4635]: info: Loading plugin "alarm-clock"... Jul 26 13:36:37 hifidac volumio[4635]: info: Plugin airplay_emulation is not enabled Jul 26 13:36:37 hifidac volumio[4635]: info: Loading plugin "last_100"... Jul 26 13:36:37 hifidac volumio[4635]: info: Loading plugin "webradio"... Jul 26 13:36:37 hifidac volumio[4668]: Traceback (most recent call last): Jul 26 13:36:37 hifidac volumio[4668]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:36:37 hifidac volumio[4668]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:36:37 hifidac volumio[4668]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:36:37 hifidac volumio[4635]: info: Loading plugin "i2s_dacs"... Jul 26 13:36:38 hifidac volumio[4635]: info: Loading plugin "volumiodiscovery"... Jul 26 13:36:38 hifidac volumio[4635]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:38 hifidac volumio[4635]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:38 hifidac volumio[4635]: *** WARNING *** For more information see Jul 26 13:36:38 hifidac node[4635]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:38 hifidac volumio[4635]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:38 hifidac volumio[4635]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:38 hifidac volumio[4635]: *** WARNING *** For more information see Jul 26 13:36:38 hifidac node[4635]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:38 hifidac node[4635]: *** WARNING *** For more information see Jul 26 13:36:38 hifidac node[4635]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:38 hifidac node[4635]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:38 hifidac node[4635]: *** WARNING *** For more information see Jul 26 13:36:38 hifidac volumio[4635]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 26 13:36:38 hifidac volumio[4635]: info: Discovery: Started advertising with name: Hifidac Jul 26 13:36:38 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 13:36:38 hifidac volumio[4635]: info: Loading plugin "outputs"... Jul 26 13:36:38 hifidac volumio[4635]: info: Loading plugin "albumart"... Jul 26 13:36:38 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:38] [connect] Successful connection Jul 26 13:36:38 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:38 hifidac volumio[4635]: info: Plugin example_plugin is not enabled Jul 26 13:36:38 hifidac volumio[4635]: info: Loading plugin "inputs"... Jul 26 13:36:38 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:38 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:36:38 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:38 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:38 hifidac volumio[4635]: info: Loading plugin "updater_comm"... Jul 26 13:36:38 hifidac volumio[4635]: info: Plugin mpdemulation is not enabled Jul 26 13:36:38 hifidac volumio[4635]: info: Loading plugin "rest_api"... Jul 26 13:36:38 hifidac volumio[4635]: info: Loading plugin "websocket"... Jul 26 13:36:38 hifidac volumio[4635]: info: Starting Socket.io Server version 2.3.0 Jul 26 13:36:38 hifidac volumio[4635]: info: Loading plugin "fusiondsp"... Jul 26 13:36:39 hifidac volumio[4635]: Forking 3 albumart workers Jul 26 13:36:39 hifidac volumio[4635]: info: Applying required configuration parameters for plugin fusiondsp Jul 26 13:36:39 hifidac volumio[4635]: info: Loading plugin "ferrum_streaming_control_technology"... Jul 26 13:36:40 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:36:40 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 49. Jul 26 13:36:40 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:36:40 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:36:40 hifidac volumio[4635]: info: Applying required configuration parameters for plugin ferrum_streaming_control_technology Jul 26 13:36:40 hifidac volumio[4635]: info: Loading i18n strings for locale en Jul 26 13:36:40 hifidac volumio[4635]: Updating browse sources language Jul 26 13:36:40 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:41 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:36:41 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:36:41 hifidac volumio[4635]: info: CoreCommandRouter::initPlayerControls Jul 26 13:36:41 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:41 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:41 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:41 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:41 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:41 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:41 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:36:41 hifidac volumio[4635]: Express server listening on port 3000 Jul 26 13:36:41 hifidac volumio[4635]: [Metrics] WebUI: 10s 499.19ms Jul 26 13:36:41 hifidac volumio[4635]: Starting albumart workers Jul 26 13:36:41 hifidac volumio[4635]: info: CoreStateMachine::resetVolumioState Jul 26 13:36:41 hifidac volumio[4635]: info: CoreStateMachine::getcurrentVolume Jul 26 13:36:41 hifidac volumio[4635]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 13:36:41 hifidac volumio[4635]: Starting albumart workers Jul 26 13:36:41 hifidac volumio[4635]: Starting albumart workers Jul 26 13:36:41 hifidac volumio[4635]: info: Volumio Network Manager: Network status updated: 0 Jul 26 13:36:41 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1753518998 101 Jul 26 13:36:41 hifidac volumio[4635]: 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 Jul 26 13:36:41 hifidac volumio[4635]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:42 hifidac volumio[4635]: info: Reloading queue from file Jul 26 13:36:42 hifidac volumio[4635]: info: CoreStateMachine::setRepeat null single undefined Jul 26 13:36:42 hifidac volumio[4635]: info: CoreStateMachine::pushState Jul 26 13:36:42 hifidac volumio[4635]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::volumioPushState Jul 26 13:36:42 hifidac volumio[4635]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:42 hifidac volumio[4635]: info: Setting Device type: Raspberry PI Jul 26 13:36:42 hifidac volumio[4635]: info: VolumeController:: Volume=undefined Mute =false Jul 26 13:36:42 hifidac volumio[4635]: info: CoreStateMachine::pushState Jul 26 13:36:42 hifidac volumio[4635]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::volumioPushState Jul 26 13:36:42 hifidac volumio[4635]: info: CoreStateMachine::updateTrackBlock Jul 26 13:36:42 hifidac volumio[4635]: info: CorePlayQueue::getTrackBlock Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 13:36:42 hifidac volumio[4635]: info: Completed loading Core Plugins Jul 26 13:36:42 hifidac volumio[4635]: info: Preparing to generate the ALSA configuration file Jul 26 13:36:42 hifidac volumio[4635]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 26 13:36:42 hifidac volumio[4635]: info: Reading ALSA contributions from plugins. Jul 26 13:36:42 hifidac volumio[4635]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 26 13:36:42 hifidac volumio[4635]: info: Asound.conf file unchanged, so no further update is needed Jul 26 13:36:42 hifidac volumio[4635]: info: Output device has changed, restarting MPD Jul 26 13:36:42 hifidac sudo[4750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:36:42 hifidac sudo[4750]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:42 hifidac volumio[4635]: info: ___________ START PLUGINS ___________ Jul 26 13:36:42 hifidac sudo[4750]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:42 hifidac volumio[4635]: info: ControllerMpd::onStart: Initializing MPD Jul 26 13:36:42 hifidac volumio[4635]: info: Creating MPD Configuration file Jul 26 13:36:42 hifidac sudo[4752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:36:42 hifidac sudo[4752]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:42 hifidac systemd[1]: Stopping Music Player Daemon... Jul 26 13:36:42 hifidac systemd[1]: mpd.service: Succeeded. Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:36:42 hifidac systemd[1]: Stopped Music Player Daemon. Jul 26 13:36:42 hifidac sudo[4756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:36:42 hifidac sudo[4756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:42 hifidac volumio[4635]: info: [1753519002896] CoreMusicLibrary::Adding element Media Servers Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:42 hifidac sudo[4756]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:42 hifidac systemd[1]: Starting Music Player Daemon... Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:36:42 hifidac volumio[4635]: info: [1753519002958] CoreMusicLibrary::Adding element Last_100 Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:36:42 hifidac volumio[4635]: info: [1753519002966] CoreMusicLibrary::Adding element Webradio Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:36:42 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 13:36:42 hifidac volumio[4635]: info: Initializing BBC Radios Jul 26 13:36:43 hifidac sudo[4758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:36:43 hifidac sudo[4758]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:43 hifidac systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 26 13:36:43 hifidac systemd[1]: mpd.service: Succeeded. Jul 26 13:36:43 hifidac systemd[1]: Stopped Music Player Daemon. Jul 26 13:36:43 hifidac systemd[1]: Starting Music Player Daemon... Jul 26 13:36:43 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:43 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:43 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:43 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 13:36:43 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:43 hifidac volumio[4635]: info: Loading i18n strings for locale en Jul 26 13:36:43 hifidac sudo[4768]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 26 13:36:43 hifidac sudo[4768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:43 hifidac sudo[4768]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:43 hifidac volumio[4733]: Traceback (most recent call last): Jul 26 13:36:43 hifidac volumio[4733]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:36:43 hifidac volumio[4733]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:36:43 hifidac volumio[4733]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:36:43 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 26 13:36:43 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:43 hifidac volumio[4635]: info: FusionDsp - mixtype--------------------- undefined Jul 26 13:36:43 hifidac volumio[4635]: info: Volumio Calling Home Jul 26 13:36:43 hifidac fsct-plugin[4635]: Failed to initialize device 1d6b:0002: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:43 hifidac fsct-plugin[4635]: Failed to initialize device 0424:ec00: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:43 hifidac fsct-plugin[4635]: Failed to initialize device 0424:9514: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:43 hifidac fsct-plugin[4635]: Failed to initialize device 046d:c52f: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:36:44 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:44 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:36:44 hifidac volumio[4635]: info: Preparing to generate the ALSA configuration file Jul 26 13:36:44 hifidac volumio[4635]: Unhandled rejection Error: No sockets available, cannot start. Jul 26 13:36:44 hifidac volumio[4635]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 26 13:36:44 hifidac volumio[4635]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 26 13:36:44 hifidac volumio[4635]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 26 13:36:44 hifidac volumio[4635]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 26 13:36:44 hifidac volumio[4635]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 26 13:36:44 hifidac volumio[4635]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 26 13:36:44 hifidac volumio[4635]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 26 13:36:44 hifidac volumio[4635]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 26 13:36:44 hifidac volumio[4635]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 26 13:36:44 hifidac volumio[4635]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 26 13:36:44 hifidac volumio[4635]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 26 13:36:44 hifidac volumio[4635]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 26 13:36:44 hifidac volumio[4635]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 26 13:36:44 hifidac volumio[4635]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 26 13:36:44 hifidac volumio[4635]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 26 13:36:44 hifidac volumio[4635]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 26 13:36:44 hifidac volumio[4635]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 26 13:36:44 hifidac volumio[4635]: info: Reading ALSA contributions from plugins. Jul 26 13:36:44 hifidac volumio[4635]: info: MPD Permissions set Jul 26 13:36:44 hifidac volumio[4635]: info: MPD Permissions set Jul 26 13:36:44 hifidac volumio[4635]: info: VolumeController:: Volume=undefined Mute =false Jul 26 13:36:44 hifidac volumio[4635]: info: CoreStateMachine::pushState Jul 26 13:36:44 hifidac volumio[4635]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::volumioPushState Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::volumioGetState Jul 26 13:36:44 hifidac volumio[4635]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:44 hifidac volumio[4635]: info: FSCT Started Jul 26 13:36:44 hifidac volumio[4635]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::volumioGetState Jul 26 13:36:44 hifidac volumio[4635]: info: CorePlayQueue::getTrack 0 Jul 26 13:36:44 hifidac volumio[4635]: info: Asound.conf file unchanged, so no further update is needed Jul 26 13:36:44 hifidac volumio[4635]: info: Output device has changed, restarting MPD Jul 26 13:36:44 hifidac sudo[4817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:36:44 hifidac sudo[4817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:44 hifidac sudo[4817]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:44 hifidac sudo[4819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:36:44 hifidac sudo[4819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:44 hifidac volumio[4635]: info: MPD Permissions set Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:36:44 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:44 hifidac systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 26 13:36:45 hifidac systemd[1]: mpd.service: Succeeded. Jul 26 13:36:45 hifidac systemd[1]: Stopped Music Player Daemon. Jul 26 13:36:45 hifidac systemd[1]: Starting Music Player Daemon... Jul 26 13:36:45 hifidac sudo[4826]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 26 13:36:45 hifidac sudo[4826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:45 hifidac sudo[4826]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:45 hifidac volumio[4635]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 26 13:36:45 hifidac volumio[4635]: 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 Jul 26 13:36:45 hifidac volumio[4635]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 26 13:36:45 hifidac volumio[4635]: info: camilladsp service started and running in background, instance 1 Jul 26 13:36:45 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:36:45 hifidac volumio[4635]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 26 13:36:45 hifidac volumio[4635]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 26 13:36:45 hifidac volumio[4635]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 26 13:36:45 hifidac volumio[4635]: info: FusionDsp loaded Jul 26 13:36:45 hifidac volumio[4635]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 26 13:36:45 hifidac sudo[4839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 26 13:36:45 hifidac sudo[4839]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:45 hifidac volumio[4635]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 26 13:36:45 hifidac volumio[4635]: info: Adding Signal Path Element [object Object] Jul 26 13:36:45 hifidac volumio[4635]: info: Adding fusiondspeq DSP Signal Path Element Jul 26 13:36:45 hifidac volumio[4635]: info: FusionDsp - ---- installed callbackRead Jul 26 13:36:45 hifidac volumio[4635]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:36:45 hifidac volumio[4635]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 26 13:36:45 hifidac volumio[4635]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 26 13:36:45 hifidac volumio[4635]: at onErrorNT (internal/child_process.js:465:16) Jul 26 13:36:45 hifidac volumio[4635]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 26 13:36:45 hifidac volumio[4635]: errno: -2, Jul 26 13:36:45 hifidac volumio[4635]: code: 'ENOENT', Jul 26 13:36:45 hifidac volumio[4635]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 26 13:36:45 hifidac volumio[4635]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 26 13:36:45 hifidac volumio[4635]: spawnargs: [ Jul 26 13:36:45 hifidac volumio[4635]: '-p', Jul 26 13:36:45 hifidac volumio[4635]: 9876, Jul 26 13:36:45 hifidac volumio[4635]: '-o', Jul 26 13:36:45 hifidac volumio[4635]: '/tmp/camilladsp.log', Jul 26 13:36:45 hifidac volumio[4635]: '-l', Jul 26 13:36:45 hifidac volumio[4635]: 'warn', Jul 26 13:36:45 hifidac volumio[4635]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 26 13:36:45 hifidac volumio[4635]: ] Jul 26 13:36:45 hifidac volumio[4635]: } Jul 26 13:36:45 hifidac volumio[4635]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:36:46 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:36:46 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 50. Jul 26 13:36:46 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:36:46 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:36:46 hifidac sudo[4839]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:46 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:36:47 hifidac sudo[4865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-26 13:35 Jul 26 13:36:47 hifidac sudo[4865]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:47 hifidac sudo[4865]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:47 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:47] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 26 13:36:47 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:47] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 26 13:36:47 hifidac systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:47 hifidac sudo[4758]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:47 hifidac sudo[4752]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:48 hifidac sudo[4819]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:48 hifidac systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 26 13:36:48 hifidac systemd[1]: Started dynamicswap service. Jul 26 13:36:48 hifidac systemd[1]: dynamicswap.service: Succeeded. Jul 26 13:36:48 hifidac systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 26 13:36:48 hifidac systemd[1]: volumio.service: Scheduled restart job, restart counter is at 15. Jul 26 13:36:48 hifidac systemd[1]: Stopped Volumio Backend Module. Jul 26 13:36:48 hifidac systemd[1]: Started Volumio Backend Module. Jul 26 13:36:48 hifidac systemd[1]: Started dynamicswap service. Jul 26 13:36:48 hifidac systemd[1]: dynamicswap.service: Succeeded. Jul 26 13:36:48 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:48 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:48 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:48 hifidac mpd[4828]: Jul 26 13:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 26 13:36:48 hifidac systemd[1]: Started Music Player Daemon. Jul 26 13:36:49 hifidac volumio[4862]: Traceback (most recent call last): Jul 26 13:36:49 hifidac volumio[4862]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:36:49 hifidac volumio[4862]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:36:49 hifidac volumio[4862]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:36:49 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:49 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:36:50 hifidac ntpd[794]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 26 13:36:51 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:36:51 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 51. Jul 26 13:36:51 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:36:51 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:36:51 hifidac volumio[4878]: info: ------------------------------------------- Jul 26 13:36:51 hifidac volumio[4878]: info: ----- Volumio3 ---- Jul 26 13:36:51 hifidac volumio[4878]: info: ------------------------------------------- Jul 26 13:36:51 hifidac volumio[4878]: info: ----- System startup ---- Jul 26 13:36:51 hifidac volumio[4878]: info: ------------------------------------------- Jul 26 13:36:52 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:36:52 hifidac volumio-remote-updater[592]: [2025-07-26 13:36:52] [connect] Successful connection Jul 26 13:36:53 hifidac volumio[4878]: info: MYVOLUMIO Environment detected Jul 26 13:36:53 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:53 hifidac volumio[4878]: info: Plugin folders cleanup Jul 26 13:36:53 hifidac volumio[4878]: info: Scanning into folder /volumio/app/plugins/ Jul 26 13:36:53 hifidac volumio[4878]: info: Scanning category audio_interface Jul 26 13:36:53 hifidac volumio[4878]: info: Scanning category miscellanea Jul 26 13:36:53 hifidac volumio[4878]: info: Scanning category music_service Jul 26 13:36:53 hifidac volumio[4878]: info: Scanning category plugins.json Jul 26 13:36:53 hifidac volumio[4878]: info: Scanning category system_controller Jul 26 13:36:53 hifidac volumio[4878]: info: Scanning category user_interface Jul 26 13:36:53 hifidac volumio[4878]: info: Scanning into folder /data/plugins/ Jul 26 13:36:53 hifidac volumio[4878]: info: Scanning category audio_interface Jul 26 13:36:53 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:53 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:53 hifidac volumio[4878]: info: Scanning category user_interface Jul 26 13:36:53 hifidac volumio[4878]: info: Plugin folders cleanup completed Jul 26 13:36:53 hifidac volumio[4878]: info: ------------------------------------------- Jul 26 13:36:53 hifidac volumio[4878]: info: ----- Core plugins startup ---- Jul 26 13:36:53 hifidac volumio[4878]: info: ------------------------------------------- Jul 26 13:36:53 hifidac volumio[4878]: info: Loading plugins from folder /volumio/app/plugins/ Jul 26 13:36:53 hifidac volumio[4878]: info: Adding plugin upnp to MyMusic Plugins Jul 26 13:36:53 hifidac volumio[4878]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 26 13:36:53 hifidac volumio[4878]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 26 13:36:53 hifidac volumio[4878]: info: Loading plugins from folder /data/plugins/ Jul 26 13:36:53 hifidac volumio[4878]: info: Loading plugin "system"... Jul 26 13:36:53 hifidac volumio[4878]: info: Loading plugin "appearance"... Jul 26 13:36:53 hifidac ntpd[794]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 26 13:36:54 hifidac volumio[4906]: Traceback (most recent call last): Jul 26 13:36:54 hifidac volumio[4906]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:36:54 hifidac volumio[4906]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:36:54 hifidac volumio[4906]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:36:54 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:54 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:36:55 hifidac volumio[4878]: info: Loading plugin "network"... Jul 26 13:36:55 hifidac volumio[4878]: info: Refreshing Cached IP Addresses Jul 26 13:36:55 hifidac sudo[4921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 26 13:36:55 hifidac sudo[4921]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:55 hifidac sudo[4921]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:55 hifidac sudo[4923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 26 13:36:55 hifidac ntpd[794]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 26 13:36:55 hifidac sudo[4923]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:55 hifidac sudo[4923]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:55 hifidac volumio[4878]: info: Loading plugin "services"... Jul 26 13:36:55 hifidac volumio[4878]: info: Loading plugin "alsa_controller"... Jul 26 13:36:55 hifidac sudo[4936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 26 13:36:55 hifidac sudo[4936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:36:56 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 13:36:56 hifidac volumio[4878]: info: Loading plugin "wizard"... Jul 26 13:36:56 hifidac volumio[4878]: info: Loading plugin "networkfs"... Jul 26 13:36:56 hifidac volumio[4878]: info: Starting Udev Watcher for removable devices Jul 26 13:36:56 hifidac volumio[4878]: info: Ignoring mount for partition: boot Jul 26 13:36:56 hifidac volumio[4878]: info: Ignoring mount for partition: volumio Jul 26 13:36:56 hifidac volumio[4878]: info: Ignoring mount for partition: volumio_data Jul 26 13:36:56 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 13:36:56 hifidac volumio[4878]: info: Loading plugin "volumio_command_line_client"... Jul 26 13:36:56 hifidac volumio[4878]: info: Loading plugin "upnp"... Jul 26 13:36:56 hifidac volumio[4878]: info: [1753519016194] Starting Upmpd Daemon Jul 26 13:36:56 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 13:36:56 hifidac volumio[4878]: info: Loading plugin "my_music"... Jul 26 13:36:56 hifidac volumio[4878]: info: Loading plugin "mpd"... Jul 26 13:36:56 hifidac sudo[4936]: pam_unix(sudo:session): session closed for user root Jul 26 13:36:56 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:36:56 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 52. Jul 26 13:36:56 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:36:56 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:36:56 hifidac ntpd[794]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 26 13:36:56 hifidac volumio[4878]: info: Loading plugin "upnp_browser"... Jul 26 13:36:57 hifidac dhcpcd[907]: eth0: carrier acquired Jul 26 13:36:57 hifidac kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jul 26 13:36:57 hifidac dhcpcd[907]: DUID 00:01:00:01:30:17:4f:23:b8:27:eb:69:70:44 Jul 26 13:36:57 hifidac dhcpcd[907]: eth0: IAID eb:69:70:44 Jul 26 13:36:57 hifidac dhcpcd[907]: eth0: adding address fe80::fb05:db71:6b31:38f3 Jul 26 13:36:57 hifidac dhcpcd[907]: ipv6_addaddr1: Permission denied Jul 26 13:36:57 hifidac volumio[4878]: info: Loading plugin "alarm-clock"... Jul 26 13:36:57 hifidac ifplugd(eth0)[797]: Link beat detected. Jul 26 13:36:57 hifidac ifplugd(eth0)[797]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 26 13:36:57 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:36:57 hifidac ifplugd(eth0)[797]: client: sending commands to master dhcpcd process Jul 26 13:36:57 hifidac dhcpcd[4969]: sending commands to master dhcpcd process Jul 26 13:36:57 hifidac dhcpcd[907]: control command: /sbin/dhcpcd eth0 Jul 26 13:36:57 hifidac volumio[4878]: info: Plugin airplay_emulation is not enabled Jul 26 13:36:57 hifidac volumio[4878]: info: Loading plugin "last_100"... Jul 26 13:36:57 hifidac volumio[4878]: info: Loading plugin "webradio"... Jul 26 13:36:58 hifidac dhcpcd[907]: eth0: rebinding lease of 192.168.100.121 Jul 26 13:36:58 hifidac ifplugd(eth0)[797]: Program executed successfully. Jul 26 13:36:58 hifidac volumio[4878]: info: Loading plugin "i2s_dacs"... Jul 26 13:36:58 hifidac volumio[4878]: info: Loading plugin "volumiodiscovery"... Jul 26 13:36:58 hifidac dhcpcd[907]: eth0: NAK: from 192.168.100.1 Jul 26 13:36:58 hifidac dhcpcd[907]: eth0: soliciting a DHCP lease Jul 26 13:36:58 hifidac volumio[4878]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:58 hifidac volumio[4878]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:58 hifidac volumio[4878]: *** WARNING *** For more information see Jul 26 13:36:58 hifidac node[4878]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:58 hifidac volumio[4878]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:58 hifidac volumio[4878]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:58 hifidac volumio[4878]: *** WARNING *** For more information see Jul 26 13:36:58 hifidac node[4878]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:58 hifidac node[4878]: *** WARNING *** For more information see Jul 26 13:36:58 hifidac node[4878]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 26 13:36:58 hifidac node[4878]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 26 13:36:58 hifidac node[4878]: *** WARNING *** For more information see Jul 26 13:36:58 hifidac volumio[4878]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 26 13:36:58 hifidac volumio[4878]: info: Discovery: Started advertising with name: Hifidac Jul 26 13:36:58 hifidac dhcpcd[907]: eth0: soliciting an IPv6 router Jul 26 13:36:58 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 26 13:36:58 hifidac volumio[4878]: info: Loading plugin "outputs"... Jul 26 13:36:58 hifidac volumio[4878]: info: Loading plugin "albumart"... Jul 26 13:36:58 hifidac volumio[4878]: info: Plugin example_plugin is not enabled Jul 26 13:36:58 hifidac volumio[4878]: info: Loading plugin "inputs"... Jul 26 13:36:58 hifidac volumio[4878]: info: Loading plugin "updater_comm"... Jul 26 13:36:58 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:36:58 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:36:58 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:36:58 hifidac volumio[4878]: info: Plugin mpdemulation is not enabled Jul 26 13:36:58 hifidac volumio[4878]: info: Loading plugin "rest_api"... Jul 26 13:36:59 hifidac volumio[4878]: info: Loading plugin "websocket"... Jul 26 13:36:59 hifidac volumio[4878]: info: Starting Socket.io Server version 2.3.0 Jul 26 13:36:59 hifidac volumio[4878]: info: Loading plugin "fusiondsp"... Jul 26 13:36:59 hifidac volumio[4878]: Forking 3 albumart workers Jul 26 13:36:59 hifidac volumio[4946]: Traceback (most recent call last): Jul 26 13:36:59 hifidac volumio[4946]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:36:59 hifidac volumio[4946]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:36:59 hifidac volumio[4946]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:36:59 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:36:59 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:37:00 hifidac volumio[4878]: info: Applying required configuration parameters for plugin fusiondsp Jul 26 13:37:00 hifidac dhcpcd[907]: eth0: offered 192.168.100.129 from 192.168.100.1 Jul 26 13:37:00 hifidac dhcpcd[907]: eth0: probing address 192.168.100.129/24 Jul 26 13:37:00 hifidac volumio[4878]: info: Loading plugin "ferrum_streaming_control_technology"... Jul 26 13:37:00 hifidac volumio[4878]: info: Applying required configuration parameters for plugin ferrum_streaming_control_technology Jul 26 13:37:01 hifidac volumio[4878]: info: Loading i18n strings for locale en Jul 26 13:37:01 hifidac volumio[4878]: Updating browse sources language Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::initPlayerControls Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:37:01 hifidac volumio[4878]: Starting albumart workers Jul 26 13:37:01 hifidac volumio[4878]: Express server listening on port 3000 Jul 26 13:37:01 hifidac volumio[4878]: [Metrics] WebUI: 10s 939.67ms Jul 26 13:37:01 hifidac volumio[4878]: Starting albumart workers Jul 26 13:37:01 hifidac volumio[4878]: info: CoreStateMachine::resetVolumioState Jul 26 13:37:01 hifidac volumio[4878]: info: CoreStateMachine::getcurrentVolume Jul 26 13:37:01 hifidac volumio[4878]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 13:37:01 hifidac volumio[4878]: Starting albumart workers Jul 26 13:37:01 hifidac volumio[4878]: info: Volumio Network Manager: Network status updated: 0 Jul 26 13:37:02 hifidac volumio[4878]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 26 13:37:02 hifidac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 26 13:37:02 hifidac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 53. Jul 26 13:37:02 hifidac systemd[1]: Stopped FusionDsp Daemon. Jul 26 13:37:02 hifidac systemd[1]: Started FusionDsp Daemon. Jul 26 13:37:02 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:37:02 hifidac volumio[4878]: info: Reloading queue from file Jul 26 13:37:02 hifidac volumio[4878]: info: VolumeController:: Volume=undefined Mute =false Jul 26 13:37:02 hifidac volumio[4878]: info: CoreStateMachine::pushState Jul 26 13:37:02 hifidac volumio[4878]: info: CorePlayQueue::getTrack 0 Jul 26 13:37:02 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:37:02 hifidac volumio[4878]: info: CoreCommandRouter::volumioPushState Jul 26 13:37:02 hifidac volumio[4878]: info: CoreStateMachine::updateTrackBlock Jul 26 13:37:02 hifidac volumio[4878]: info: CorePlayQueue::getTrackBlock Jul 26 13:37:02 hifidac volumio[4878]: info: CoreCommandRouter::volumioRetrievevolume Jul 26 13:37:02 hifidac volumio[4878]: info: CoreStateMachine::setRepeat null single undefined Jul 26 13:37:02 hifidac volumio[4878]: info: CoreStateMachine::pushState Jul 26 13:37:02 hifidac volumio[4878]: info: CorePlayQueue::getTrack 0 Jul 26 13:37:02 hifidac volumio[4878]: info: CoreCommandRouter::volumioPushState Jul 26 13:37:02 hifidac volumio[4878]: info: CorePlayQueue::getTrack 0 Jul 26 13:37:02 hifidac volumio[4878]: info: Setting Device type: Raspberry PI Jul 26 13:37:02 hifidac volumio[4878]: info: Completed loading Core Plugins Jul 26 13:37:02 hifidac volumio[4878]: info: Preparing to generate the ALSA configuration file Jul 26 13:37:02 hifidac volumio[4878]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 26 13:37:02 hifidac volumio[4878]: info: Reading ALSA contributions from plugins. Jul 26 13:37:02 hifidac volumio[4878]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 26 13:37:02 hifidac volumio[4878]: info: Asound.conf file unchanged, so no further update is needed Jul 26 13:37:02 hifidac volumio[4878]: info: Output device has changed, restarting MPD Jul 26 13:37:02 hifidac volumio[4878]: info: ___________ START PLUGINS ___________ Jul 26 13:37:02 hifidac volumio[4878]: info: ControllerMpd::onStart: Initializing MPD Jul 26 13:37:02 hifidac sudo[5055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:37:02 hifidac sudo[5055]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:37:02 hifidac volumio[4878]: info: Creating MPD Configuration file Jul 26 13:37:02 hifidac sudo[5055]: pam_unix(sudo:session): session closed for user root Jul 26 13:37:02 hifidac sudo[5057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:37:02 hifidac sudo[5057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:37:03 hifidac systemd[1]: Stopping Music Player Daemon... Jul 26 13:37:03 hifidac sudo[5061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:37:03 hifidac sudo[5061]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:37:03 hifidac volumio[4878]: info: [1753519023034] CoreMusicLibrary::Adding element Media Servers Jul 26 13:37:03 hifidac sudo[5061]: pam_unix(sudo:session): session closed for user root Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:37:03 hifidac systemd[1]: mpd.service: Succeeded. Jul 26 13:37:03 hifidac systemd[1]: Stopped Music Player Daemon. Jul 26 13:37:03 hifidac sudo[5063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:37:03 hifidac sudo[5063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:37:03 hifidac systemd[1]: Starting Music Player Daemon... Jul 26 13:37:03 hifidac systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 26 13:37:03 hifidac systemd[1]: mpd.service: Succeeded. Jul 26 13:37:03 hifidac systemd[1]: Stopped Music Player Daemon. Jul 26 13:37:03 hifidac systemd[1]: Starting Music Player Daemon... Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:37:03 hifidac volumio[4878]: info: [1753519023239] CoreMusicLibrary::Adding element Last_100 Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 26 13:37:03 hifidac volumio[4878]: info: [1753519023269] CoreMusicLibrary::Adding element Webradio Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 26 13:37:03 hifidac wpa_supplicant[895]: wlan0: Failed to initiate sched scan Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 13:37:03 hifidac volumio[4878]: info: Initializing BBC Radios Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:37:03 hifidac volumio[4878]: info: Loading i18n strings for locale en Jul 26 13:37:03 hifidac sudo[5071]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 26 13:37:03 hifidac sudo[5071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 26 13:37:03 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:03 hifidac volumio[4878]: info: FusionDsp - mixtype--------------------- undefined Jul 26 13:37:03 hifidac sudo[5071]: pam_unix(sudo:session): session closed for user root Jul 26 13:37:03 hifidac fsct-plugin[4878]: Failed to initialize device 1d6b:0002: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:37:03 hifidac fsct-plugin[4878]: Failed to initialize device 0424:ec00: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:37:03 hifidac volumio[4878]: info: Volumio Calling Home Jul 26 13:37:03 hifidac fsct-plugin[4878]: Failed to initialize device 0424:9514: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:37:03 hifidac fsct-plugin[4878]: Failed to initialize device 046d:c52f: BOS descriptor not available, unsupported usb version 2.00 Jul 26 13:37:03 hifidac volumio-time-update[617]: volumio-time-update-util: Fetching time from Volumio... Jul 26 13:37:03 hifidac volumio-time-update[617]: volumio-time-update-util: Date not found in response Jul 26 13:37:03 hifidac volumio-time-update[617]: volumio-time-update-util: Retrying in 5 seconds... Jul 26 13:37:03 hifidac volumio[4878]: info: Preparing to generate the ALSA configuration file Jul 26 13:37:04 hifidac volumio[4878]: Unhandled rejection Error: No sockets available, cannot start. Jul 26 13:37:04 hifidac volumio[4878]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 26 13:37:04 hifidac volumio[4878]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 26 13:37:04 hifidac volumio[4878]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 26 13:37:04 hifidac volumio[4878]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 26 13:37:04 hifidac volumio[4878]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 26 13:37:04 hifidac volumio[4878]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 26 13:37:04 hifidac volumio[4878]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 26 13:37:04 hifidac volumio[4878]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 26 13:37:04 hifidac volumio[4878]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 26 13:37:04 hifidac volumio[4878]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 26 13:37:04 hifidac volumio[4878]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 26 13:37:04 hifidac volumio[4878]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 26 13:37:04 hifidac volumio[4878]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 26 13:37:04 hifidac volumio[4878]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 26 13:37:04 hifidac volumio[4878]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 26 13:37:04 hifidac volumio[4878]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 26 13:37:04 hifidac volumio[4878]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 26 13:37:04 hifidac volumio[4878]: info: Reading ALSA contributions from plugins. Jul 26 13:37:04 hifidac volumio[4878]: info: MPD Permissions set Jul 26 13:37:04 hifidac volumio[4878]: info: MPD Permissions set Jul 26 13:37:04 hifidac volumio[4878]: info: VolumeController:: Volume=undefined Mute =false Jul 26 13:37:04 hifidac volumio[4878]: info: CoreStateMachine::pushState Jul 26 13:37:04 hifidac volumio[4878]: info: CorePlayQueue::getTrack 0 Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::volumioPushState Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::volumioGetState Jul 26 13:37:04 hifidac volumio[4878]: info: CorePlayQueue::getTrack 0 Jul 26 13:37:04 hifidac volumio[4878]: info: FSCT Started Jul 26 13:37:04 hifidac volumio[4878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac dhcpcd[907]: eth0: leased 192.168.100.129 for 86400 seconds Jul 26 13:37:04 hifidac avahi-daemon[590]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.100.129. Jul 26 13:37:04 hifidac dhcpcd[907]: eth0: adding route to 192.168.100.0/24 Jul 26 13:37:04 hifidac avahi-daemon[590]: New relevant interface eth0.IPv4 for mDNS. Jul 26 13:37:04 hifidac dhcpcd[907]: eth0: adding default route via 192.168.100.1 Jul 26 13:37:04 hifidac avahi-daemon[590]: Registering new address record for 192.168.100.129 on eth0.IPv4. Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::volumioGetState Jul 26 13:37:04 hifidac volumio[4878]: info: CorePlayQueue::getTrack 0 Jul 26 13:37:04 hifidac volumio[4878]: info: Asound.conf file unchanged, so no further update is needed Jul 26 13:37:04 hifidac volumio[4878]: info: Output device has changed, restarting MPD Jul 26 13:37:04 hifidac sudo[5144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 26 13:37:04 hifidac sudo[5144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:37:04 hifidac sudo[5144]: pam_unix(sudo:session): session closed for user root Jul 26 13:37:04 hifidac volumio[4878]: info: MPD Permissions set Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac sudo[5146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:04 hifidac sudo[5146]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:37:05 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 26 13:37:05 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:05 hifidac systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 26 13:37:05 hifidac systemd[1]: mpd.service: Succeeded. Jul 26 13:37:05 hifidac systemd[1]: Stopped Music Player Daemon. Jul 26 13:37:05 hifidac systemd[1]: Starting Music Player Daemon... Jul 26 13:37:05 hifidac sudo[5153]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 26 13:37:05 hifidac sudo[5153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:37:05 hifidac sudo[5153]: pam_unix(sudo:session): session closed for user root Jul 26 13:37:05 hifidac volumio[4878]: info: Discovery: adding 17aad2b9-efd3-430f-aa76-1f3b07931b13 Jul 26 13:37:05 hifidac volumio[4878]: info: Discovery: Found device Hifidac Jul 26 13:37:05 hifidac volumio[4878]: info: CoreCommandRouter::volumioGetState Jul 26 13:37:05 hifidac volumio[4878]: info: CorePlayQueue::getTrack 0 Jul 26 13:37:05 hifidac volumio-remote-updater[592]: [2025-07-26 13:37:05] [connect] Successful connection Jul 26 13:37:05 hifidac volumio-remote-updater[592]: [2025-07-26 13:37:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1753519025 101 Jul 26 13:37:05 hifidac volumio[4878]: 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 Jul 26 13:37:05 hifidac volumio[5047]: Traceback (most recent call last): Jul 26 13:37:05 hifidac volumio[5047]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jul 26 13:37:05 hifidac volumio[5047]: from camilladsp_plot.validate_config import CamillaValidator Jul 26 13:37:05 hifidac volumio[5047]: ModuleNotFoundError: No module named 'camilladsp_plot' Jul 26 13:37:05 hifidac volumio[4878]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 26 13:37:05 hifidac volumio[4878]: 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 Jul 26 13:37:05 hifidac volumio[4878]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 26 13:37:05 hifidac volumio[4878]: info: camilladsp service started and running in background, instance 1 Jul 26 13:37:05 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 26 13:37:05 hifidac volumio[4878]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 26 13:37:05 hifidac volumio[4878]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 26 13:37:05 hifidac volumio[4878]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 26 13:37:05 hifidac volumio[4878]: info: FusionDsp loaded Jul 26 13:37:05 hifidac volumio[4878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 26 13:37:05 hifidac sudo[5166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 26 13:37:05 hifidac sudo[5166]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:37:05 hifidac volumio[4878]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 26 13:37:05 hifidac volumio[4878]: info: Adding Signal Path Element [object Object] Jul 26 13:37:05 hifidac volumio[4878]: info: Adding fusiondspeq DSP Signal Path Element Jul 26 13:37:05 hifidac volumio[4878]: info: FusionDsp - ---- installed callbackRead Jul 26 13:37:05 hifidac volumio[4878]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:37:05 hifidac ntpd[794]: Listen normally on 3 eth0 192.168.100.129:123 Jul 26 13:37:05 hifidac ntpd[794]: new interface(s) found: waking up resolver Jul 26 13:37:05 hifidac volumio[4878]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 26 13:37:05 hifidac volumio[4878]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 26 13:37:05 hifidac volumio[4878]: at onErrorNT (internal/child_process.js:465:16) Jul 26 13:37:05 hifidac volumio[4878]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 26 13:37:05 hifidac volumio[4878]: errno: -2, Jul 26 13:37:05 hifidac volumio[4878]: code: 'ENOENT', Jul 26 13:37:05 hifidac volumio[4878]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 26 13:37:05 hifidac volumio[4878]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 26 13:37:05 hifidac volumio[4878]: spawnargs: [ Jul 26 13:37:05 hifidac volumio[4878]: '-p', Jul 26 13:37:05 hifidac volumio[4878]: 9876, Jul 26 13:37:05 hifidac volumio[4878]: '-o', Jul 26 13:37:05 hifidac volumio[4878]: '/tmp/camilladsp.log', Jul 26 13:37:05 hifidac volumio[4878]: '-l', Jul 26 13:37:05 hifidac volumio[4878]: 'warn', Jul 26 13:37:05 hifidac volumio[4878]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 26 13:37:05 hifidac volumio[4878]: ] Jul 26 13:37:05 hifidac volumio[4878]: } Jul 26 13:37:05 hifidac volumio[4878]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:37:05 hifidac sudo[5166]: pam_unix(sudo:session): session closed for user root Jul 26 13:37:05 hifidac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 26 13:37:05 hifidac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 26 13:37:07 hifidac sudo[5191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-26 13:36 Jul 26 13:37:07 hifidac sudo[5191]: 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"