-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-09-15 19:00:19 CEST. -- Sep 15 18:59:00 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 15 18:59:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 42. Sep 15 18:59:00 volumio systemd[1]: Stopped Volumio Backend Module. Sep 15 18:59:00 volumio systemd[1]: Started Volumio Backend Module. Sep 15 18:59:00 volumio systemd[1]: Started dynamicswap service. Sep 15 18:59:00 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 18:59:01 volumio shairport-sync[14211]: no RTSP header received Sep 15 18:59:01 volumio volumio[14249]: info: ------------------------------------------- Sep 15 18:59:01 volumio volumio[14249]: info: ----- Volumio3 ---- Sep 15 18:59:01 volumio volumio[14249]: info: ------------------------------------------- Sep 15 18:59:01 volumio volumio[14249]: info: ----- System startup ---- Sep 15 18:59:01 volumio volumio[14249]: info: ------------------------------------------- Sep 15 18:59:02 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:02 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 157. Sep 15 18:59:02 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:02 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:02 volumio volumio[14271]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:02 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:02 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:02 volumio volumio[14249]: info: MYVOLUMIO Environment detected Sep 15 18:59:02 volumio volumio[14249]: info: Plugin folders cleanup Sep 15 18:59:02 volumio volumio[14249]: info: Scanning into folder /volumio/app/plugins/ Sep 15 18:59:02 volumio volumio[14249]: info: Scanning category audio_interface Sep 15 18:59:02 volumio volumio[14249]: info: Scanning category miscellanea Sep 15 18:59:02 volumio volumio[14249]: info: Scanning category music_service Sep 15 18:59:02 volumio volumio[14249]: info: Scanning category plugins.json Sep 15 18:59:02 volumio volumio[14249]: info: Scanning category system_controller Sep 15 18:59:02 volumio volumio[14249]: info: Scanning category user_interface Sep 15 18:59:02 volumio volumio[14249]: info: Scanning into folder /data/plugins/ Sep 15 18:59:02 volumio volumio[14249]: info: Scanning category audio_interface Sep 15 18:59:02 volumio volumio[14249]: info: Plugin folders cleanup completed Sep 15 18:59:02 volumio volumio[14249]: info: ------------------------------------------- Sep 15 18:59:02 volumio volumio[14249]: info: ----- Core plugins startup ---- Sep 15 18:59:02 volumio volumio[14249]: info: ------------------------------------------- Sep 15 18:59:02 volumio volumio[14249]: info: Loading plugins from folder /volumio/app/plugins/ Sep 15 18:59:02 volumio volumio[14249]: info: Adding plugin upnp to MyMusic Plugins Sep 15 18:59:02 volumio volumio[14249]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 15 18:59:02 volumio volumio[14249]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 15 18:59:02 volumio volumio[14249]: info: Loading plugins from folder /data/plugins/ Sep 15 18:59:02 volumio volumio[14249]: info: Loading plugin "system"... Sep 15 18:59:02 volumio volumio[14249]: info: Loading plugin "appearance"... Sep 15 18:59:03 volumio volumio[14249]: info: Loading plugin "network"... Sep 15 18:59:03 volumio volumio[14249]: info: Refreshing Cached IP Addresses Sep 15 18:59:03 volumio sudo[14278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 18:59:03 volumio sudo[14278]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:03 volumio sudo[14278]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:03 volumio sudo[14280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 18:59:03 volumio sudo[14280]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:03 volumio volumio[14249]: info: Loading plugin "services"... Sep 15 18:59:03 volumio volumio[14249]: info: Loading plugin "alsa_controller"... Sep 15 18:59:03 volumio sudo[14291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 18:59:03 volumio sudo[14291]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:03 volumio sudo[14280]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:03 volumio sudo[14291]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:03 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:03 volumio volumio[14249]: info: Loading plugin "wizard"... Sep 15 18:59:03 volumio volumio[14249]: info: Loading plugin "networkfs"... Sep 15 18:59:03 volumio volumio[14249]: info: Starting Udev Watcher for removable devices Sep 15 18:59:03 volumio volumio[14249]: info: Ignoring mount for partition: boot Sep 15 18:59:03 volumio volumio[14249]: info: Ignoring mount for partition: volumio Sep 15 18:59:03 volumio volumio[14249]: info: Ignoring mount for partition: volumio_data Sep 15 18:59:03 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:03 volumio volumio[14249]: info: Loading plugin "volumio_command_line_client"... Sep 15 18:59:03 volumio volumio[14249]: info: Loading plugin "upnp"... Sep 15 18:59:03 volumio volumio[14249]: info: [1757955543930] Starting Upmpd Daemon Sep 15 18:59:03 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:03 volumio volumio[14249]: info: Loading plugin "my_music"... Sep 15 18:59:03 volumio volumio[14249]: info: Loading plugin "mpd"... Sep 15 18:59:04 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:04 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 158. Sep 15 18:59:04 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:04 volumio volumio[14249]: info: Loading plugin "upnp_browser"... Sep 15 18:59:04 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:04 volumio volumio[14307]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:04 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:04 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:04 volumio volumio[14249]: info: Loading plugin "alarm-clock"... Sep 15 18:59:04 volumio volumio-remote-updater[575]: [2025-09-15 18:59:04] [connect] Successful connection Sep 15 18:59:04 volumio volumio[14249]: info: Loading plugin "airplay_emulation"... Sep 15 18:59:04 volumio volumio[14249]: info: Starting Shairport Sync Sep 15 18:59:04 volumio volumio[14249]: info: Loading plugin "last_100"... Sep 15 18:59:04 volumio volumio[14249]: info: Loading plugin "webradio"... Sep 15 18:59:04 volumio volumio[14249]: info: Loading plugin "i2s_dacs"... Sep 15 18:59:04 volumio volumio[14249]: info: Loading plugin "volumiodiscovery"... Sep 15 18:59:04 volumio volumio[14249]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:04 volumio volumio[14249]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:04 volumio volumio[14249]: *** WARNING *** For more information see Sep 15 18:59:04 volumio node[14249]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:04 volumio volumio[14249]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:04 volumio volumio[14249]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:04 volumio volumio[14249]: *** WARNING *** For more information see Sep 15 18:59:04 volumio node[14249]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:04 volumio node[14249]: *** WARNING *** For more information see Sep 15 18:59:04 volumio node[14249]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:04 volumio node[14249]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:04 volumio node[14249]: *** WARNING *** For more information see Sep 15 18:59:04 volumio volumio[14249]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 15 18:59:04 volumio volumio[14249]: info: Discovery: Started advertising with name: Volumio Sep 15 18:59:04 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:04 volumio volumio[14249]: info: Loading plugin "outputs"... Sep 15 18:59:04 volumio volumio[14249]: info: Loading plugin "albumart"... Sep 15 18:59:05 volumio volumio[14249]: info: Plugin example_plugin is not enabled Sep 15 18:59:05 volumio volumio[14249]: info: Loading plugin "inputs"... Sep 15 18:59:05 volumio volumio[14249]: info: Loading plugin "updater_comm"... Sep 15 18:59:05 volumio volumio[14249]: info: Plugin mpdemulation is not enabled Sep 15 18:59:05 volumio volumio[14249]: info: Loading plugin "rest_api"... Sep 15 18:59:05 volumio volumio[14249]: info: Loading plugin "websocket"... Sep 15 18:59:05 volumio volumio[14249]: info: Starting Socket.io Server version 2.3.0 Sep 15 18:59:05 volumio volumio[14249]: info: Loading plugin "fusiondsp"... Sep 15 18:59:05 volumio volumio[14249]: Forking 3 albumart workers Sep 15 18:59:05 volumio volumio[14249]: info: Applying required configuration parameters for plugin fusiondsp Sep 15 18:59:05 volumio volumio[14249]: info: Loading i18n strings for locale de Sep 15 18:59:05 volumio volumio[14249]: Updating browse sources language Sep 15 18:59:05 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::initPlayerControls Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:06 volumio volumio[14249]: Express server listening on port 3000 Sep 15 18:59:06 volumio volumio[14249]: [Metrics] WebUI: 4s 971.32ms Sep 15 18:59:06 volumio volumio[14249]: info: CoreStateMachine::resetVolumioState Sep 15 18:59:06 volumio volumio[14249]: info: CoreStateMachine::getcurrentVolume Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 18:59:06 volumio volumio[14249]: info: Volumio Network Manager: Network status updated: 1 Sep 15 18:59:06 volumio volumio[14249]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 15 18:59:06 volumio volumio[14249]: wlan0 Interface doesn't support scanning. Sep 15 18:59:06 volumio volumio[14249]: info: Cannot use regular scanning, forcing with ap-force Sep 15 18:59:06 volumio sudo[14348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 15 18:59:06 volumio sudo[14348]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:06 volumio sudo[14348]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:06 volumio volumio[14249]: command failed: No such device (-19) Sep 15 18:59:06 volumio volumio[14249]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 15 18:59:06 volumio volumio[14249]: command failed: No such device (-19) Sep 15 18:59:06 volumio volumio[14249]: Starting albumart workers Sep 15 18:59:06 volumio volumio[14249]: Starting albumart workers Sep 15 18:59:06 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:06 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 159. Sep 15 18:59:06 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:06 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:06 volumio volumio[14249]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 15 18:59:06 volumio volumio[14249]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 15 18:59:06 volumio volumio[14249]: Starting albumart workers Sep 15 18:59:06 volumio volumio-remote-updater[575]: [2025-09-15 18:59:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757955544 101 Sep 15 18:59:06 volumio volumio[14249]: 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: 3 Sep 15 18:59:06 volumio volumio[14249]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 15 18:59:06 volumio volumio[14352]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:06 volumio volumio[14249]: verbose: New Socket.io Connection to 192.168.188.162:3000 from 192.168.188.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 15 18:59:06 volumio volumio[14249]: info: CoreStateMachine::pushState Sep 15 18:59:06 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:06 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:06 volumio volumio[14249]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:06 volumio volumio[14249]: info: CoreStateMachine::updateTrackBlock Sep 15 18:59:06 volumio volumio[14249]: info: CorePlayQueue::getTrackBlock Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:06 volumio volumio[14249]: info: Reloading queue from file Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 15 18:59:06 volumio volumio[14249]: info: CoreStateMachine::pushState Sep 15 18:59:06 volumio volumio[14249]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:06 volumio volumio[14249]: info: CoreStateMachine::setRepeat null single undefined Sep 15 18:59:06 volumio volumio[14249]: info: CoreStateMachine::pushState Sep 15 18:59:06 volumio volumio[14249]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:06 volumio volumio[14249]: info: CoreStateMachine::setRandom null Sep 15 18:59:06 volumio volumio[14249]: info: CoreStateMachine::pushState Sep 15 18:59:06 volumio volumio[14249]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:06 volumio volumio[14249]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:06 volumio volumio[14249]: info: Setting Device type: Raspberry PI Sep 15 18:59:06 volumio volumio[14249]: info: Completed loading Core Plugins Sep 15 18:59:06 volumio volumio[14249]: info: Preparing to generate the ALSA configuration file Sep 15 18:59:07 volumio volumio[14249]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 18:59:07 volumio volumio[14249]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 18:59:07 volumio volumio[14249]: info: Reading ALSA contributions from plugins. Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:07 volumio volumio[14249]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:07 volumio volumio[14249]: info: Discovery: adding e470e2ce-0a34-4725-aaf5-aa5e94375e01 Sep 15 18:59:07 volumio volumio[14249]: info: Discovery: Found device VolumioHD Sep 15 18:59:07 volumio volumio[14249]: info: Discovery: Connecting to remote: 192.168.188.101 Sep 15 18:59:07 volumio volumio[14249]: info: Discovery: adding bed2b3d6-941c-4a98-93b9-b3df60fe4d86 Sep 15 18:59:07 volumio volumio[14249]: info: Discovery: Found device Volumio Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:07 volumio volumio[14249]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:07 volumio volumio[14249]: info: Discovery: Connected to remote: 192.168.188.101 Sep 15 18:59:07 volumio volumio[14249]: info: Asound.conf file unchanged, so no further update is needed Sep 15 18:59:07 volumio volumio[14249]: info: Output device has changed, restarting MPD Sep 15 18:59:07 volumio volumio[14249]: info: Output device has changed, restarting Shairport Sync Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:07 volumio sudo[14375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:07 volumio sudo[14375]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:07 volumio sudo[14375]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:07 volumio sudo[14377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:07 volumio sudo[14377]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:07 volumio volumio[14249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:07 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 18:59:07 volumio volumio[14249]: info: ___________ START PLUGINS ___________ Sep 15 18:59:07 volumio volumio[14249]: info: ControllerMpd::onStart: Initializing MPD Sep 15 18:59:07 volumio volumio[14249]: info: Creating MPD Configuration file Sep 15 18:59:07 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:07 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:07 volumio sudo[14392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:07 volumio sudo[14392]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:07 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:07 volumio volumio[14249]: info: [1757955547500] CoreMusicLibrary::Adding element Medienserver Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:07 volumio sudo[14392]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:07 volumio sudo[14396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:07 volumio sudo[14396]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:07 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 15 18:59:07 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:07 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:07 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:07 volumio volumio[14249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:07 volumio volumio[14249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:07 volumio volumio[14249]: info: [1757955547665] CoreMusicLibrary::Adding element Last_100 Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:07 volumio volumio[14249]: info: [1757955547670] CoreMusicLibrary::Adding element Webradio Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:07 volumio volumio[14249]: info: Initializing BBC Radios Sep 15 18:59:07 volumio sudo[14409]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 18:59:07 volumio sudo[14409]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:07 volumio sudo[14409]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:07 volumio volumio[14249]: info: Loading i18n strings for locale de Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 15 18:59:07 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:07 volumio volumio[14249]: info: FusionDsp - mixtype--------------------- Software Sep 15 18:59:07 volumio volumio[14249]: info: Volumio Calling Home Sep 15 18:59:07 volumio volumio[14249]: info: Preparing to generate the ALSA configuration file Sep 15 18:59:07 volumio volumio[14249]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 18:59:07 volumio volumio[14249]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 18:59:07 volumio volumio[14249]: info: Reading ALSA contributions from plugins. Sep 15 18:59:08 volumio volumio[14249]: info: MPD Permissions set Sep 15 18:59:08 volumio volumio[14249]: info: MPD Permissions set Sep 15 18:59:08 volumio volumio[14249]: info: Volumio called home Sep 15 18:59:08 volumio volumio[14249]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: Starting Shairport Sync Sep 15 18:59:08 volumio volumio[14249]: info: Starting Shairport Sync Sep 15 18:59:08 volumio volumio[14249]: info: Starting Shairport Sync Sep 15 18:59:08 volumio sudo[14437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:08 volumio sudo[14437]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:08 volumio sudo[14439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:08 volumio sudo[14439]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:08 volumio volumio[14249]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:08 volumio sudo[14442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:08 volumio sudo[14442]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:08 volumio volumio[14249]: info: Asound.conf file unchanged, so no further update is needed Sep 15 18:59:08 volumio volumio[14249]: info: Output device has changed, restarting MPD Sep 15 18:59:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 18:59:08 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 18:59:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:08 volumio volumio[14249]: info: Output device has changed, restarting Shairport Sync Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:08 volumio sudo[14446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:08 volumio sudo[14446]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:08 volumio sudo[14446]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:08 volumio volumio[14249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:08 volumio sudo[14448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:08 volumio sudo[14448]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:08 volumio sudo[14442]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:08 volumio sudo[14437]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:08 volumio sudo[14439]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:08 volumio volumio[14249]: info: MPD Permissions set Sep 15 18:59:08 volumio volumio[14249]: info: Shairport-Sync Started Sep 15 18:59:08 volumio volumio[14249]: Error adding Membership: Error: addMembership EINVAL Sep 15 18:59:08 volumio volumio[14249]: info: Shairport-Sync Started Sep 15 18:59:08 volumio volumio[14249]: info: Shairport-Sync Started Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 15 18:59:08 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:08 volumio volumio[14249]: info: Starting Shairport Sync Sep 15 18:59:08 volumio sudo[14466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:08 volumio sudo[14466]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:08 volumio sudo[14458]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 18:59:08 volumio sudo[14458]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 18:59:08 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:08 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 160. Sep 15 18:59:08 volumio sudo[14458]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:08 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 18:59:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:08 volumio sudo[14466]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:08 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:08 volumio volumio[14249]: info: Shairport-Sync Started Sep 15 18:59:08 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:08 volumio volumio[14471]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:08 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:08 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:09 volumio volumio[14249]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 15 18:59:09 volumio volumio[14249]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 15 18:59:09 volumio volumio[14249]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 15 18:59:09 volumio volumio[14249]: info: camilladsp service started and running in background, instance 1 Sep 15 18:59:09 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:09 volumio volumio[14249]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 18:59:09 volumio volumio[14249]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 15 18:59:09 volumio volumio[14249]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 18:59:09 volumio volumio[14249]: info: FusionDsp loaded Sep 15 18:59:09 volumio volumio[14249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 18:59:09 volumio sudo[14484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 15 18:59:09 volumio sudo[14484]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:09 volumio volumio[14249]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 15 18:59:09 volumio volumio[14249]: info: Adding Signal Path Element [object Object] Sep 15 18:59:09 volumio volumio[14249]: info: Adding fusiondspeq DSP Signal Path Element Sep 15 18:59:09 volumio volumio[14249]: info: FusionDsp - ---- installed callbackRead Sep 15 18:59:09 volumio volumio[14249]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:59:09 volumio volumio[14249]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 15 18:59:09 volumio volumio[14249]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 15 18:59:09 volumio volumio[14249]: at onErrorNT (internal/child_process.js:465:16) Sep 15 18:59:09 volumio volumio[14249]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 15 18:59:09 volumio volumio[14249]: errno: -2, Sep 15 18:59:09 volumio volumio[14249]: code: 'ENOENT', Sep 15 18:59:09 volumio volumio[14249]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 18:59:09 volumio volumio[14249]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 18:59:09 volumio volumio[14249]: spawnargs: [ Sep 15 18:59:09 volumio volumio[14249]: '-p', Sep 15 18:59:09 volumio volumio[14249]: 9876, Sep 15 18:59:09 volumio volumio[14249]: '-o', Sep 15 18:59:09 volumio volumio[14249]: '/tmp/camilladsp.log', Sep 15 18:59:09 volumio volumio[14249]: '-l', Sep 15 18:59:09 volumio volumio[14249]: 'warn', Sep 15 18:59:09 volumio volumio[14249]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 15 18:59:09 volumio volumio[14249]: ] Sep 15 18:59:09 volumio volumio[14249]: } Sep 15 18:59:09 volumio volumio[14249]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:59:10 volumio mpd[14469]: Sep 15 18:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 18:59:10 volumio systemd[1]: Started Music Player Daemon. Sep 15 18:59:10 volumio sudo[14377]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:10 volumio sudo[14448]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:10 volumio sudo[14396]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:10 volumio sudo[14498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 18:58 Sep 15 18:59:10 volumio sudo[14498]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:10 volumio sudo[14498]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:10 volumio volumio-remote-updater[575]: [2025-09-15 18:59:10] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 15 18:59:10 volumio volumio-remote-updater[575]: [2025-09-15 18:59:10] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 15 18:59:10 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 15 18:59:10 volumio sudo[14484]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:10 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 15 18:59:10 volumio systemd[1]: Started dynamicswap service. Sep 15 18:59:10 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 18:59:10 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:10 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 161. Sep 15 18:59:10 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:10 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:11 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 15 18:59:11 volumio volumio[14508]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:11 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 43. Sep 15 18:59:11 volumio systemd[1]: Stopped Volumio Backend Module. Sep 15 18:59:11 volumio systemd[1]: Started Volumio Backend Module. Sep 15 18:59:11 volumio systemd[1]: Started dynamicswap service. Sep 15 18:59:11 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:11 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:11 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 18:59:12 volumio volumio[14509]: info: ------------------------------------------- Sep 15 18:59:12 volumio volumio[14509]: info: ----- Volumio3 ---- Sep 15 18:59:12 volumio volumio[14509]: info: ------------------------------------------- Sep 15 18:59:12 volumio volumio[14509]: info: ----- System startup ---- Sep 15 18:59:12 volumio volumio[14509]: info: ------------------------------------------- Sep 15 18:59:13 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:13 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 162. Sep 15 18:59:13 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:13 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:13 volumio volumio[14530]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:13 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:13 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:13 volumio volumio[14509]: info: MYVOLUMIO Environment detected Sep 15 18:59:13 volumio volumio[14509]: info: Plugin folders cleanup Sep 15 18:59:13 volumio volumio[14509]: info: Scanning into folder /volumio/app/plugins/ Sep 15 18:59:13 volumio volumio[14509]: info: Scanning category audio_interface Sep 15 18:59:13 volumio volumio[14509]: info: Scanning category miscellanea Sep 15 18:59:13 volumio volumio[14509]: info: Scanning category music_service Sep 15 18:59:13 volumio volumio[14509]: info: Scanning category plugins.json Sep 15 18:59:13 volumio volumio[14509]: info: Scanning category system_controller Sep 15 18:59:13 volumio volumio[14509]: info: Scanning category user_interface Sep 15 18:59:13 volumio volumio[14509]: info: Scanning into folder /data/plugins/ Sep 15 18:59:13 volumio volumio[14509]: info: Scanning category audio_interface Sep 15 18:59:13 volumio volumio[14509]: info: Plugin folders cleanup completed Sep 15 18:59:13 volumio volumio[14509]: info: ------------------------------------------- Sep 15 18:59:13 volumio volumio[14509]: info: ----- Core plugins startup ---- Sep 15 18:59:13 volumio volumio[14509]: info: ------------------------------------------- Sep 15 18:59:13 volumio volumio[14509]: info: Loading plugins from folder /volumio/app/plugins/ Sep 15 18:59:13 volumio volumio[14509]: info: Adding plugin upnp to MyMusic Plugins Sep 15 18:59:13 volumio volumio[14509]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 15 18:59:13 volumio volumio[14509]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 15 18:59:13 volumio volumio[14509]: info: Loading plugins from folder /data/plugins/ Sep 15 18:59:13 volumio volumio[14509]: info: Loading plugin "system"... Sep 15 18:59:13 volumio volumio[14509]: info: Loading plugin "appearance"... Sep 15 18:59:14 volumio volumio[14509]: info: Loading plugin "network"... Sep 15 18:59:14 volumio volumio[14509]: info: Refreshing Cached IP Addresses Sep 15 18:59:14 volumio sudo[14537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 18:59:14 volumio sudo[14537]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:14 volumio sudo[14539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 18:59:14 volumio sudo[14537]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:14 volumio sudo[14539]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:14 volumio volumio[14509]: info: Loading plugin "services"... Sep 15 18:59:14 volumio volumio[14509]: info: Loading plugin "alsa_controller"... Sep 15 18:59:14 volumio sudo[14539]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:14 volumio sudo[14549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 18:59:14 volumio sudo[14549]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:14 volumio sudo[14549]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:14 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:14 volumio volumio[14509]: info: Loading plugin "wizard"... Sep 15 18:59:14 volumio volumio[14509]: info: Loading plugin "networkfs"... Sep 15 18:59:14 volumio volumio[14509]: info: Starting Udev Watcher for removable devices Sep 15 18:59:14 volumio volumio[14509]: info: Ignoring mount for partition: boot Sep 15 18:59:14 volumio volumio[14509]: info: Ignoring mount for partition: volumio Sep 15 18:59:14 volumio volumio[14509]: info: Ignoring mount for partition: volumio_data Sep 15 18:59:14 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:14 volumio volumio[14509]: info: Loading plugin "volumio_command_line_client"... Sep 15 18:59:14 volumio volumio[14509]: info: Loading plugin "upnp"... Sep 15 18:59:14 volumio volumio[14509]: info: [1757955554970] Starting Upmpd Daemon Sep 15 18:59:14 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:14 volumio volumio[14509]: info: Loading plugin "my_music"... Sep 15 18:59:14 volumio volumio[14509]: info: Loading plugin "mpd"... Sep 15 18:59:15 volumio volumio[14509]: info: Loading plugin "upnp_browser"... Sep 15 18:59:15 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:15 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 163. Sep 15 18:59:15 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:15 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:15 volumio volumio[14509]: info: Loading plugin "alarm-clock"... Sep 15 18:59:15 volumio volumio[14566]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:15 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:15 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:15 volumio volumio-remote-updater[575]: [2025-09-15 18:59:15] [connect] Successful connection Sep 15 18:59:15 volumio volumio[14509]: info: Loading plugin "airplay_emulation"... Sep 15 18:59:15 volumio volumio[14509]: info: Starting Shairport Sync Sep 15 18:59:15 volumio volumio[14509]: info: Loading plugin "last_100"... Sep 15 18:59:15 volumio volumio[14509]: info: Loading plugin "webradio"... Sep 15 18:59:15 volumio volumio[14509]: info: Loading plugin "i2s_dacs"... Sep 15 18:59:15 volumio volumio[14509]: info: Loading plugin "volumiodiscovery"... Sep 15 18:59:15 volumio volumio[14509]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:15 volumio volumio[14509]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:15 volumio volumio[14509]: *** WARNING *** For more information see Sep 15 18:59:15 volumio node[14509]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:15 volumio volumio[14509]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:15 volumio volumio[14509]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:15 volumio volumio[14509]: *** WARNING *** For more information see Sep 15 18:59:15 volumio node[14509]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:15 volumio node[14509]: *** WARNING *** For more information see Sep 15 18:59:15 volumio node[14509]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:15 volumio node[14509]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:15 volumio node[14509]: *** WARNING *** For more information see Sep 15 18:59:15 volumio volumio[14509]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 15 18:59:15 volumio volumio[14509]: info: Discovery: Started advertising with name: Volumio Sep 15 18:59:16 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:16 volumio volumio[14509]: info: Loading plugin "outputs"... Sep 15 18:59:16 volumio volumio[14509]: info: Loading plugin "albumart"... Sep 15 18:59:16 volumio volumio[14509]: info: Plugin example_plugin is not enabled Sep 15 18:59:16 volumio volumio[14509]: info: Loading plugin "inputs"... Sep 15 18:59:16 volumio volumio[14509]: info: Loading plugin "updater_comm"... Sep 15 18:59:16 volumio volumio[14509]: info: Plugin mpdemulation is not enabled Sep 15 18:59:16 volumio volumio[14509]: info: Loading plugin "rest_api"... Sep 15 18:59:16 volumio volumio[14509]: info: Loading plugin "websocket"... Sep 15 18:59:16 volumio volumio[14509]: info: Starting Socket.io Server version 2.3.0 Sep 15 18:59:16 volumio volumio[14509]: info: Loading plugin "fusiondsp"... Sep 15 18:59:16 volumio volumio[14509]: Forking 3 albumart workers Sep 15 18:59:16 volumio volumio[14509]: info: Applying required configuration parameters for plugin fusiondsp Sep 15 18:59:16 volumio volumio[14509]: info: Loading i18n strings for locale de Sep 15 18:59:16 volumio volumio[14509]: Updating browse sources language Sep 15 18:59:16 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::initPlayerControls Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:17 volumio volumio[14509]: Express server listening on port 3000 Sep 15 18:59:17 volumio volumio[14509]: [Metrics] WebUI: 4s 895.18ms Sep 15 18:59:17 volumio volumio[14509]: info: CoreStateMachine::resetVolumioState Sep 15 18:59:17 volumio volumio[14509]: info: CoreStateMachine::getcurrentVolume Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 18:59:17 volumio volumio[14509]: info: Volumio Network Manager: Network status updated: 1 Sep 15 18:59:17 volumio volumio[14509]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 15 18:59:17 volumio volumio[14509]: wlan0 Interface doesn't support scanning. Sep 15 18:59:17 volumio volumio[14509]: info: Cannot use regular scanning, forcing with ap-force Sep 15 18:59:17 volumio sudo[14607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 15 18:59:17 volumio sudo[14607]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:17 volumio sudo[14607]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:17 volumio volumio[14509]: command failed: No such device (-19) Sep 15 18:59:17 volumio volumio[14509]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 15 18:59:17 volumio volumio[14509]: command failed: No such device (-19) Sep 15 18:59:17 volumio volumio[14509]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 15 18:59:17 volumio volumio[14509]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 15 18:59:17 volumio volumio-remote-updater[575]: [2025-09-15 18:59:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757955555 101 Sep 15 18:59:17 volumio volumio[14509]: 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: 3 Sep 15 18:59:17 volumio volumio[14509]: verbose: New Socket.io Connection to 192.168.188.162:3000 from 192.168.188.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 15 18:59:17 volumio volumio[14509]: info: CoreStateMachine::pushState Sep 15 18:59:17 volumio volumio[14509]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:17 volumio volumio[14509]: Starting albumart workers Sep 15 18:59:17 volumio volumio[14509]: info: CoreStateMachine::updateTrackBlock Sep 15 18:59:17 volumio volumio[14509]: info: CorePlayQueue::getTrackBlock Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 18:59:17 volumio volumio[14509]: Starting albumart workers Sep 15 18:59:17 volumio volumio[14509]: Starting albumart workers Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:17 volumio volumio[14509]: info: Reloading queue from file Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 15 18:59:17 volumio volumio[14509]: info: CoreStateMachine::setRepeat null single undefined Sep 15 18:59:17 volumio volumio[14509]: info: CoreStateMachine::pushState Sep 15 18:59:17 volumio volumio[14509]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:17 volumio volumio[14509]: info: CoreStateMachine::setRandom null Sep 15 18:59:17 volumio volumio[14509]: info: CoreStateMachine::pushState Sep 15 18:59:17 volumio volumio[14509]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:17 volumio volumio[14509]: info: Setting Device type: Raspberry PI Sep 15 18:59:17 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:17 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 164. Sep 15 18:59:17 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:17 volumio volumio[14509]: info: Completed loading Core Plugins Sep 15 18:59:17 volumio volumio[14509]: info: Preparing to generate the ALSA configuration file Sep 15 18:59:17 volumio volumio[14509]: info: CoreStateMachine::pushState Sep 15 18:59:17 volumio volumio[14509]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:17 volumio volumio[14509]: info: Discovery: adding e470e2ce-0a34-4725-aaf5-aa5e94375e01 Sep 15 18:59:17 volumio volumio[14509]: info: Discovery: Found device VolumioHD Sep 15 18:59:17 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:17 volumio volumio[14509]: info: Discovery: Connecting to remote: 192.168.188.101 Sep 15 18:59:17 volumio volumio[14509]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 18:59:17 volumio volumio[14509]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 18:59:17 volumio volumio[14509]: info: Reading ALSA contributions from plugins. Sep 15 18:59:17 volumio volumio[14509]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:17 volumio volumio[14509]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:17 volumio volumio[14622]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:17 volumio volumio[14509]: info: Asound.conf file unchanged, so no further update is needed Sep 15 18:59:17 volumio volumio[14509]: info: Output device has changed, restarting MPD Sep 15 18:59:17 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:17 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:18 volumio volumio[14509]: info: Output device has changed, restarting Shairport Sync Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:18 volumio sudo[14625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:18 volumio sudo[14625]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:18 volumio sudo[14625]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:18 volumio sudo[14628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:18 volumio sudo[14628]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:18 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 18:59:18 volumio volumio[14509]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:18 volumio volumio[14509]: info: ___________ START PLUGINS ___________ Sep 15 18:59:18 volumio volumio[14509]: info: ControllerMpd::onStart: Initializing MPD Sep 15 18:59:18 volumio volumio[14509]: info: Creating MPD Configuration file Sep 15 18:59:18 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:18 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:18 volumio volumio[14509]: info: [1757955558221] CoreMusicLibrary::Adding element Medienserver Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:18 volumio sudo[14636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:18 volumio sudo[14636]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:18 volumio sudo[14638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:18 volumio sudo[14638]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:18 volumio sudo[14636]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:18 volumio sudo[14635]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 18:59:18 volumio sudo[14635]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:18 volumio sudo[14635]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:18 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 15 18:59:18 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:18 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:18 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:18 volumio volumio[14509]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:18 volumio volumio[14509]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:18 volumio volumio[14509]: info: [1757955558515] CoreMusicLibrary::Adding element Last_100 Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:18 volumio volumio[14509]: info: [1757955558525] CoreMusicLibrary::Adding element Webradio Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:18 volumio volumio[14509]: info: Initializing BBC Radios Sep 15 18:59:18 volumio sudo[14644]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 18:59:18 volumio sudo[14644]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:18 volumio sudo[14644]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:18 volumio volumio[14509]: info: Loading i18n strings for locale de Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 15 18:59:18 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:18 volumio volumio[14509]: info: FusionDsp - mixtype--------------------- Software Sep 15 18:59:18 volumio volumio[14509]: info: Volumio Calling Home Sep 15 18:59:18 volumio volumio[14509]: info: Preparing to generate the ALSA configuration file Sep 15 18:59:18 volumio volumio[14509]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 18:59:18 volumio volumio[14509]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 18:59:18 volumio volumio[14509]: info: Reading ALSA contributions from plugins. Sep 15 18:59:18 volumio volumio[14509]: info: Discovery: Connected to remote: 192.168.188.101 Sep 15 18:59:19 volumio volumio[14509]: info: MPD Permissions set Sep 15 18:59:19 volumio volumio[14509]: info: MPD Permissions set Sep 15 18:59:19 volumio volumio[14509]: info: Volumio called home Sep 15 18:59:19 volumio volumio[14509]: info: Discovery: adding bed2b3d6-941c-4a98-93b9-b3df60fe4d86 Sep 15 18:59:19 volumio volumio[14509]: info: Discovery: Found device Volumio Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:19 volumio volumio[14509]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:19 volumio volumio[14509]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: Starting Shairport Sync Sep 15 18:59:19 volumio volumio[14509]: info: Starting Shairport Sync Sep 15 18:59:19 volumio volumio[14509]: info: Starting Shairport Sync Sep 15 18:59:19 volumio sudo[14696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:19 volumio sudo[14696]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:19 volumio sudo[14698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:19 volumio sudo[14698]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:19 volumio volumio[14509]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:19 volumio sudo[14701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 18:59:19 volumio sudo[14701]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:19 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 18:59:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:19 volumio volumio[14509]: info: Asound.conf file unchanged, so no further update is needed Sep 15 18:59:19 volumio volumio[14509]: info: Output device has changed, restarting MPD Sep 15 18:59:19 volumio volumio[14509]: info: Output device has changed, restarting Shairport Sync Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:19 volumio sudo[14706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:19 volumio sudo[14706]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:19 volumio sudo[14696]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:19 volumio sudo[14708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:19 volumio sudo[14708]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:19 volumio sudo[14706]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:19 volumio sudo[14698]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 18:59:19 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 18:59:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:19 volumio volumio[14509]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:19 volumio sudo[14701]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:19 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 15 18:59:19 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:19 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:19 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:19 volumio volumio[14509]: info: MPD Permissions set Sep 15 18:59:19 volumio volumio[14509]: info: Shairport-Sync Started Sep 15 18:59:19 volumio volumio[14509]: Error adding Membership: Error: addMembership EINVAL Sep 15 18:59:19 volumio volumio[14509]: info: Shairport-Sync Started Sep 15 18:59:19 volumio volumio[14509]: info: Shairport-Sync Started Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:19 volumio volumio[14509]: info: Starting Shairport Sync Sep 15 18:59:19 volumio sudo[14726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:19 volumio sudo[14726]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:19 volumio sudo[14717]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 18:59:19 volumio sudo[14717]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 18:59:19 volumio sudo[14717]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:19 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 18:59:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:19 volumio sudo[14726]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:19 volumio volumio[14509]: info: Shairport-Sync Started Sep 15 18:59:20 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:20 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 165. Sep 15 18:59:20 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:20 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:20 volumio volumio[14733]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:20 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:20 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:20 volumio volumio[14509]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 15 18:59:20 volumio volumio[14509]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 15 18:59:20 volumio volumio[14509]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 15 18:59:20 volumio volumio[14509]: info: camilladsp service started and running in background, instance 1 Sep 15 18:59:20 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:20 volumio volumio[14509]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 18:59:20 volumio volumio[14509]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 15 18:59:20 volumio volumio[14509]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 18:59:20 volumio volumio[14509]: info: FusionDsp loaded Sep 15 18:59:20 volumio volumio[14509]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 18:59:20 volumio sudo[14744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 15 18:59:20 volumio sudo[14744]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:20 volumio volumio[14509]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 15 18:59:20 volumio volumio[14509]: info: Adding Signal Path Element [object Object] Sep 15 18:59:20 volumio volumio[14509]: info: Adding fusiondspeq DSP Signal Path Element Sep 15 18:59:20 volumio volumio[14509]: info: FusionDsp - ---- installed callbackRead Sep 15 18:59:20 volumio volumio[14509]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:59:20 volumio volumio[14509]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 15 18:59:20 volumio volumio[14509]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 15 18:59:20 volumio volumio[14509]: at onErrorNT (internal/child_process.js:465:16) Sep 15 18:59:20 volumio volumio[14509]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 15 18:59:20 volumio volumio[14509]: errno: -2, Sep 15 18:59:20 volumio volumio[14509]: code: 'ENOENT', Sep 15 18:59:20 volumio volumio[14509]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 18:59:20 volumio volumio[14509]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 18:59:20 volumio volumio[14509]: spawnargs: [ Sep 15 18:59:20 volumio volumio[14509]: '-p', Sep 15 18:59:20 volumio volumio[14509]: 9876, Sep 15 18:59:20 volumio volumio[14509]: '-o', Sep 15 18:59:20 volumio volumio[14509]: '/tmp/camilladsp.log', Sep 15 18:59:20 volumio volumio[14509]: '-l', Sep 15 18:59:20 volumio volumio[14509]: 'warn', Sep 15 18:59:20 volumio volumio[14509]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 15 18:59:20 volumio volumio[14509]: ] Sep 15 18:59:20 volumio volumio[14509]: } Sep 15 18:59:20 volumio volumio[14509]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:59:21 volumio mpd[14729]: Sep 15 18:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 18:59:21 volumio systemd[1]: Started Music Player Daemon. Sep 15 18:59:21 volumio sudo[14638]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:21 volumio sudo[14628]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:21 volumio sudo[14708]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:21 volumio sudo[14757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 18:58 Sep 15 18:59:21 volumio sudo[14757]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:21 volumio sudo[14757]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:21 volumio volumio-remote-updater[575]: [2025-09-15 18:59:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 15 18:59:21 volumio volumio-remote-updater[575]: [2025-09-15 18:59:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 15 18:59:21 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 15 18:59:21 volumio sudo[14744]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:21 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 15 18:59:21 volumio systemd[1]: Started dynamicswap service. Sep 15 18:59:21 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 18:59:22 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 15 18:59:22 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 44. Sep 15 18:59:22 volumio systemd[1]: Started dynamicswap service. Sep 15 18:59:22 volumio systemd[1]: Stopped Volumio Backend Module. Sep 15 18:59:22 volumio systemd[1]: Started Volumio Backend Module. Sep 15 18:59:22 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 18:59:22 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:22 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 166. Sep 15 18:59:22 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:22 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:22 volumio volumio[14780]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:22 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:22 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:23 volumio volumio[14768]: info: ------------------------------------------- Sep 15 18:59:23 volumio volumio[14768]: info: ----- Volumio3 ---- Sep 15 18:59:23 volumio volumio[14768]: info: ------------------------------------------- Sep 15 18:59:23 volumio volumio[14768]: info: ----- System startup ---- Sep 15 18:59:23 volumio volumio[14768]: info: ------------------------------------------- Sep 15 18:59:24 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:24 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 167. Sep 15 18:59:24 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:24 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:24 volumio volumio[14768]: info: MYVOLUMIO Environment detected Sep 15 18:59:24 volumio volumio[14789]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:24 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:24 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:24 volumio volumio[14768]: info: Plugin folders cleanup Sep 15 18:59:24 volumio volumio[14768]: info: Scanning into folder /volumio/app/plugins/ Sep 15 18:59:24 volumio volumio[14768]: info: Scanning category audio_interface Sep 15 18:59:24 volumio volumio[14768]: info: Scanning category miscellanea Sep 15 18:59:24 volumio volumio[14768]: info: Scanning category music_service Sep 15 18:59:24 volumio volumio[14768]: info: Scanning category plugins.json Sep 15 18:59:24 volumio volumio[14768]: info: Scanning category system_controller Sep 15 18:59:24 volumio volumio[14768]: info: Scanning category user_interface Sep 15 18:59:24 volumio volumio[14768]: info: Scanning into folder /data/plugins/ Sep 15 18:59:24 volumio volumio[14768]: info: Scanning category audio_interface Sep 15 18:59:24 volumio volumio[14768]: info: Plugin folders cleanup completed Sep 15 18:59:24 volumio volumio[14768]: info: ------------------------------------------- Sep 15 18:59:24 volumio volumio[14768]: info: ----- Core plugins startup ---- Sep 15 18:59:24 volumio volumio[14768]: info: ------------------------------------------- Sep 15 18:59:24 volumio volumio[14768]: info: Loading plugins from folder /volumio/app/plugins/ Sep 15 18:59:24 volumio volumio[14768]: info: Adding plugin upnp to MyMusic Plugins Sep 15 18:59:24 volumio volumio[14768]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 15 18:59:24 volumio volumio[14768]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 15 18:59:24 volumio volumio[14768]: info: Loading plugins from folder /data/plugins/ Sep 15 18:59:24 volumio volumio[14768]: info: Loading plugin "system"... Sep 15 18:59:24 volumio volumio[14768]: info: Loading plugin "appearance"... Sep 15 18:59:25 volumio volumio[14768]: info: Loading plugin "network"... Sep 15 18:59:25 volumio volumio[14768]: info: Refreshing Cached IP Addresses Sep 15 18:59:25 volumio sudo[14796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 18:59:25 volumio sudo[14796]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:25 volumio sudo[14796]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:25 volumio sudo[14798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 18:59:25 volumio sudo[14798]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:25 volumio volumio[14768]: info: Loading plugin "services"... Sep 15 18:59:25 volumio volumio[14768]: info: Loading plugin "alsa_controller"... Sep 15 18:59:25 volumio sudo[14798]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:25 volumio sudo[14808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 18:59:25 volumio sudo[14808]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:25 volumio sudo[14808]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:25 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:25 volumio volumio[14768]: info: Loading plugin "wizard"... Sep 15 18:59:25 volumio volumio[14768]: info: Loading plugin "networkfs"... Sep 15 18:59:25 volumio volumio[14768]: info: Starting Udev Watcher for removable devices Sep 15 18:59:25 volumio volumio[14768]: info: Ignoring mount for partition: boot Sep 15 18:59:25 volumio volumio[14768]: info: Ignoring mount for partition: volumio Sep 15 18:59:25 volumio volumio[14768]: info: Ignoring mount for partition: volumio_data Sep 15 18:59:25 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:25 volumio volumio[14768]: info: Loading plugin "volumio_command_line_client"... Sep 15 18:59:25 volumio volumio[14768]: info: Loading plugin "upnp"... Sep 15 18:59:25 volumio volumio[14768]: info: [1757955565976] Starting Upmpd Daemon Sep 15 18:59:25 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:25 volumio volumio[14768]: info: Loading plugin "my_music"... Sep 15 18:59:25 volumio volumio[14768]: info: Loading plugin "mpd"... Sep 15 18:59:26 volumio volumio[14768]: info: Loading plugin "upnp_browser"... Sep 15 18:59:26 volumio volumio[14768]: info: Loading plugin "alarm-clock"... Sep 15 18:59:26 volumio volumio-remote-updater[575]: [2025-09-15 18:59:26] [connect] Successful connection Sep 15 18:59:26 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:26 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 168. Sep 15 18:59:26 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:26 volumio volumio[14768]: info: Loading plugin "airplay_emulation"... Sep 15 18:59:26 volumio volumio[14768]: info: Starting Shairport Sync Sep 15 18:59:26 volumio volumio[14768]: info: Loading plugin "last_100"... Sep 15 18:59:26 volumio volumio[14768]: info: Loading plugin "webradio"... Sep 15 18:59:26 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:26 volumio volumio[14825]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:26 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:26 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:26 volumio volumio[14768]: info: Loading plugin "i2s_dacs"... Sep 15 18:59:26 volumio volumio[14768]: info: Loading plugin "volumiodiscovery"... Sep 15 18:59:26 volumio volumio[14768]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:26 volumio volumio[14768]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:26 volumio volumio[14768]: *** WARNING *** For more information see Sep 15 18:59:26 volumio node[14768]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:26 volumio volumio[14768]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:26 volumio volumio[14768]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:26 volumio volumio[14768]: *** WARNING *** For more information see Sep 15 18:59:26 volumio node[14768]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:26 volumio node[14768]: *** WARNING *** For more information see Sep 15 18:59:26 volumio node[14768]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:26 volumio node[14768]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:26 volumio node[14768]: *** WARNING *** For more information see Sep 15 18:59:26 volumio volumio[14768]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 15 18:59:27 volumio volumio[14768]: info: Discovery: Started advertising with name: Volumio Sep 15 18:59:27 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:27 volumio volumio[14768]: info: Loading plugin "outputs"... Sep 15 18:59:27 volumio volumio[14768]: info: Loading plugin "albumart"... Sep 15 18:59:27 volumio volumio[14768]: info: Plugin example_plugin is not enabled Sep 15 18:59:27 volumio volumio[14768]: info: Loading plugin "inputs"... Sep 15 18:59:27 volumio volumio[14768]: info: Loading plugin "updater_comm"... Sep 15 18:59:27 volumio volumio[14768]: info: Plugin mpdemulation is not enabled Sep 15 18:59:27 volumio volumio[14768]: info: Loading plugin "rest_api"... Sep 15 18:59:27 volumio volumio[14768]: info: Loading plugin "websocket"... Sep 15 18:59:27 volumio volumio[14768]: info: Starting Socket.io Server version 2.3.0 Sep 15 18:59:27 volumio volumio[14768]: info: Loading plugin "fusiondsp"... Sep 15 18:59:27 volumio volumio[14768]: Forking 3 albumart workers Sep 15 18:59:27 volumio volumio[14768]: info: Applying required configuration parameters for plugin fusiondsp Sep 15 18:59:27 volumio volumio[14768]: info: Loading i18n strings for locale de Sep 15 18:59:27 volumio volumio[14768]: Updating browse sources language Sep 15 18:59:27 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::initPlayerControls Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:28 volumio volumio[14768]: Express server listening on port 3000 Sep 15 18:59:28 volumio volumio[14768]: [Metrics] WebUI: 4s 937.20ms Sep 15 18:59:28 volumio volumio[14768]: info: CoreStateMachine::resetVolumioState Sep 15 18:59:28 volumio volumio[14768]: info: CoreStateMachine::getcurrentVolume Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 18:59:28 volumio volumio[14768]: info: Volumio Network Manager: Network status updated: 1 Sep 15 18:59:28 volumio volumio[14768]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 15 18:59:28 volumio volumio[14768]: wlan0 Interface doesn't support scanning. Sep 15 18:59:28 volumio volumio[14768]: info: Cannot use regular scanning, forcing with ap-force Sep 15 18:59:28 volumio sudo[14866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 15 18:59:28 volumio sudo[14866]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:28 volumio sudo[14866]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:28 volumio volumio[14768]: command failed: No such device (-19) Sep 15 18:59:28 volumio volumio[14768]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 15 18:59:28 volumio volumio[14768]: command failed: No such device (-19) Sep 15 18:59:28 volumio volumio[14768]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 15 18:59:28 volumio volumio[14768]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 15 18:59:28 volumio volumio[14768]: Starting albumart workers Sep 15 18:59:28 volumio volumio-remote-updater[575]: [2025-09-15 18:59:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757955566 101 Sep 15 18:59:28 volumio volumio[14768]: 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: 3 Sep 15 18:59:28 volumio volumio[14768]: verbose: New Socket.io Connection to 192.168.188.162:3000 from 192.168.188.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 15 18:59:28 volumio volumio[14768]: Starting albumart workers Sep 15 18:59:28 volumio volumio[14768]: info: CoreStateMachine::pushState Sep 15 18:59:28 volumio volumio[14768]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:28 volumio volumio[14768]: info: CoreStateMachine::updateTrackBlock Sep 15 18:59:28 volumio volumio[14768]: info: CorePlayQueue::getTrackBlock Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 18:59:28 volumio volumio[14768]: Starting albumart workers Sep 15 18:59:28 volumio volumio[14768]: info: Reloading queue from file Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 15 18:59:28 volumio volumio[14768]: info: CoreStateMachine::setRepeat null single undefined Sep 15 18:59:28 volumio volumio[14768]: info: CoreStateMachine::pushState Sep 15 18:59:28 volumio volumio[14768]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:28 volumio volumio[14768]: info: CoreStateMachine::setRandom null Sep 15 18:59:28 volumio volumio[14768]: info: CoreStateMachine::pushState Sep 15 18:59:28 volumio volumio[14768]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:28 volumio volumio[14768]: info: Setting Device type: Raspberry PI Sep 15 18:59:28 volumio volumio[14768]: info: Completed loading Core Plugins Sep 15 18:59:28 volumio volumio[14768]: info: Preparing to generate the ALSA configuration file Sep 15 18:59:28 volumio volumio[14768]: info: CoreStateMachine::pushState Sep 15 18:59:28 volumio volumio[14768]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:28 volumio volumio[14768]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 18:59:28 volumio volumio[14768]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 18:59:28 volumio volumio[14768]: info: Reading ALSA contributions from plugins. Sep 15 18:59:28 volumio volumio[14768]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:28 volumio volumio[14768]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:28 volumio volumio[14768]: info: Discovery: adding e470e2ce-0a34-4725-aaf5-aa5e94375e01 Sep 15 18:59:28 volumio volumio[14768]: info: Discovery: Found device VolumioHD Sep 15 18:59:29 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:29 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 169. Sep 15 18:59:29 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:29 volumio volumio[14768]: info: Discovery: Connecting to remote: 192.168.188.101 Sep 15 18:59:29 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:29 volumio volumio[14768]: info: Discovery: Connected to remote: 192.168.188.101 Sep 15 18:59:29 volumio volumio[14882]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:29 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:29 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:29 volumio volumio[14768]: info: Discovery: adding bed2b3d6-941c-4a98-93b9-b3df60fe4d86 Sep 15 18:59:29 volumio volumio[14768]: info: Discovery: Found device Volumio Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:29 volumio volumio[14768]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:29 volumio volumio[14768]: info: Asound.conf file unchanged, so no further update is needed Sep 15 18:59:29 volumio volumio[14768]: info: Output device has changed, restarting MPD Sep 15 18:59:29 volumio sudo[14889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:29 volumio volumio[14768]: info: Output device has changed, restarting Shairport Sync Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:29 volumio sudo[14889]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:29 volumio sudo[14889]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:29 volumio sudo[14898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:29 volumio sudo[14898]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:29 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 18:59:29 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:29 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:29 volumio volumio[14768]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:29 volumio volumio[14768]: info: ___________ START PLUGINS ___________ Sep 15 18:59:29 volumio volumio[14768]: info: ControllerMpd::onStart: Initializing MPD Sep 15 18:59:29 volumio volumio[14768]: info: Creating MPD Configuration file Sep 15 18:59:29 volumio sudo[14910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:29 volumio sudo[14910]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:29 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:29 volumio sudo[14910]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:29 volumio volumio[14768]: info: [1757955569535] CoreMusicLibrary::Adding element Medienserver Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:29 volumio sudo[14912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:29 volumio sudo[14912]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:29 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 15 18:59:29 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:29 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:29 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:29 volumio volumio[14768]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:29 volumio volumio[14768]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:29 volumio volumio[14768]: info: [1757955569711] CoreMusicLibrary::Adding element Last_100 Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:29 volumio volumio[14768]: info: [1757955569715] CoreMusicLibrary::Adding element Webradio Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:29 volumio volumio[14768]: info: Initializing BBC Radios Sep 15 18:59:29 volumio sudo[14915]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 18:59:29 volumio sudo[14915]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:29 volumio sudo[14915]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:29 volumio volumio[14768]: info: Loading i18n strings for locale de Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 15 18:59:29 volumio volumio[14768]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:29 volumio volumio[14768]: info: FusionDsp - mixtype--------------------- Software Sep 15 18:59:29 volumio volumio[14768]: info: Volumio Calling Home Sep 15 18:59:29 volumio volumio[14768]: info: Preparing to generate the ALSA configuration file Sep 15 18:59:30 volumio volumio[14768]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 18:59:30 volumio volumio[14768]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 18:59:30 volumio volumio[14768]: info: Reading ALSA contributions from plugins. Sep 15 18:59:30 volumio volumio[14768]: info: MPD Permissions set Sep 15 18:59:30 volumio volumio[14768]: info: MPD Permissions set Sep 15 18:59:30 volumio volumio[14768]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:59:30 volumio volumio[14768]: Error: read ECONNRESET Sep 15 18:59:30 volumio volumio[14768]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Sep 15 18:59:30 volumio volumio[14768]: errno: -104, Sep 15 18:59:30 volumio volumio[14768]: code: 'ECONNRESET', Sep 15 18:59:30 volumio volumio[14768]: syscall: 'read' Sep 15 18:59:30 volumio volumio[14768]: } Sep 15 18:59:30 volumio volumio[14768]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:59:30 volumio sudo[14956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 18:58 Sep 15 18:59:30 volumio sudo[14956]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:31 volumio sudo[14956]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:31 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:31 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 170. Sep 15 18:59:31 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:31 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:31 volumio mpd[14933]: Sep 15 18:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 18:59:31 volumio systemd[1]: Started Music Player Daemon. Sep 15 18:59:31 volumio sudo[14898]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:31 volumio sudo[14912]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:31 volumio volumio[14963]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:31 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:31 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:31 volumio volumio-remote-updater[575]: [2025-09-15 18:59:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 15 18:59:31 volumio volumio-remote-updater[575]: [2025-09-15 18:59:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 15 18:59:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 15 18:59:31 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 15 18:59:31 volumio systemd[1]: Started dynamicswap service. Sep 15 18:59:31 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 18:59:31 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 15 18:59:31 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 45. Sep 15 18:59:31 volumio systemd[1]: Started dynamicswap service. Sep 15 18:59:31 volumio systemd[1]: Stopped Volumio Backend Module. Sep 15 18:59:31 volumio systemd[1]: Started Volumio Backend Module. Sep 15 18:59:31 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 18:59:33 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:33 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 171. Sep 15 18:59:33 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:33 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:33 volumio volumio[14974]: info: ------------------------------------------- Sep 15 18:59:33 volumio volumio[14974]: info: ----- Volumio3 ---- Sep 15 18:59:33 volumio volumio[14974]: info: ------------------------------------------- Sep 15 18:59:33 volumio volumio[14974]: info: ----- System startup ---- Sep 15 18:59:33 volumio volumio[14974]: info: ------------------------------------------- Sep 15 18:59:33 volumio volumio[14994]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:33 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:33 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:34 volumio volumio[14974]: info: MYVOLUMIO Environment detected Sep 15 18:59:34 volumio volumio[14974]: info: Plugin folders cleanup Sep 15 18:59:34 volumio volumio[14974]: info: Scanning into folder /volumio/app/plugins/ Sep 15 18:59:34 volumio volumio[14974]: info: Scanning category audio_interface Sep 15 18:59:34 volumio volumio[14974]: info: Scanning category miscellanea Sep 15 18:59:34 volumio volumio[14974]: info: Scanning category music_service Sep 15 18:59:34 volumio volumio[14974]: info: Scanning category plugins.json Sep 15 18:59:34 volumio volumio[14974]: info: Scanning category system_controller Sep 15 18:59:34 volumio volumio[14974]: info: Scanning category user_interface Sep 15 18:59:34 volumio volumio[14974]: info: Scanning into folder /data/plugins/ Sep 15 18:59:34 volumio volumio[14974]: info: Scanning category audio_interface Sep 15 18:59:34 volumio volumio[14974]: info: Plugin folders cleanup completed Sep 15 18:59:34 volumio volumio[14974]: info: ------------------------------------------- Sep 15 18:59:34 volumio volumio[14974]: info: ----- Core plugins startup ---- Sep 15 18:59:34 volumio volumio[14974]: info: ------------------------------------------- Sep 15 18:59:34 volumio volumio[14974]: info: Loading plugins from folder /volumio/app/plugins/ Sep 15 18:59:34 volumio volumio[14974]: info: Adding plugin upnp to MyMusic Plugins Sep 15 18:59:34 volumio volumio[14974]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 15 18:59:34 volumio volumio[14974]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 15 18:59:34 volumio volumio[14974]: info: Loading plugins from folder /data/plugins/ Sep 15 18:59:34 volumio volumio[14974]: info: Loading plugin "system"... Sep 15 18:59:34 volumio volumio[14974]: info: Loading plugin "appearance"... Sep 15 18:59:35 volumio volumio[14974]: info: Loading plugin "network"... Sep 15 18:59:35 volumio volumio[14974]: info: Refreshing Cached IP Addresses Sep 15 18:59:35 volumio sudo[15001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 18:59:35 volumio sudo[15001]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:35 volumio sudo[15001]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:35 volumio sudo[15003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 18:59:35 volumio sudo[15003]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:35 volumio volumio[14974]: info: Loading plugin "services"... Sep 15 18:59:35 volumio volumio[14974]: info: Loading plugin "alsa_controller"... Sep 15 18:59:35 volumio sudo[15003]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:35 volumio sudo[15016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 18:59:35 volumio sudo[15016]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:35 volumio sudo[15016]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:35 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:35 volumio volumio[14974]: info: Loading plugin "wizard"... Sep 15 18:59:35 volumio volumio[14974]: info: Loading plugin "networkfs"... Sep 15 18:59:35 volumio volumio[14974]: info: Starting Udev Watcher for removable devices Sep 15 18:59:35 volumio volumio[14974]: info: Ignoring mount for partition: boot Sep 15 18:59:35 volumio volumio[14974]: info: Ignoring mount for partition: volumio Sep 15 18:59:35 volumio volumio[14974]: info: Ignoring mount for partition: volumio_data Sep 15 18:59:35 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:35 volumio volumio[14974]: info: Loading plugin "volumio_command_line_client"... Sep 15 18:59:35 volumio volumio[14974]: info: Loading plugin "upnp"... Sep 15 18:59:35 volumio volumio[14974]: info: [1757955575721] Starting Upmpd Daemon Sep 15 18:59:35 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:35 volumio volumio[14974]: info: Loading plugin "my_music"... Sep 15 18:59:35 volumio volumio[14974]: info: Loading plugin "mpd"... Sep 15 18:59:35 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:35 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 172. Sep 15 18:59:35 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:35 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:35 volumio volumio[15030]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:35 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:35 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:36 volumio volumio[14974]: info: Loading plugin "upnp_browser"... Sep 15 18:59:36 volumio volumio[14974]: info: Loading plugin "alarm-clock"... Sep 15 18:59:36 volumio volumio-remote-updater[575]: [2025-09-15 18:59:36] [connect] Successful connection Sep 15 18:59:36 volumio volumio[14974]: info: Loading plugin "airplay_emulation"... Sep 15 18:59:36 volumio volumio[14974]: info: Starting Shairport Sync Sep 15 18:59:36 volumio volumio[14974]: info: Loading plugin "last_100"... Sep 15 18:59:36 volumio volumio[14974]: info: Loading plugin "webradio"... Sep 15 18:59:36 volumio volumio[14974]: info: Loading plugin "i2s_dacs"... Sep 15 18:59:36 volumio volumio[14974]: info: Loading plugin "volumiodiscovery"... Sep 15 18:59:36 volumio volumio[14974]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:36 volumio volumio[14974]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:36 volumio volumio[14974]: *** WARNING *** For more information see Sep 15 18:59:36 volumio node[14974]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:36 volumio volumio[14974]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:36 volumio volumio[14974]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:36 volumio volumio[14974]: *** WARNING *** For more information see Sep 15 18:59:36 volumio node[14974]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:36 volumio node[14974]: *** WARNING *** For more information see Sep 15 18:59:36 volumio node[14974]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:36 volumio node[14974]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:36 volumio node[14974]: *** WARNING *** For more information see Sep 15 18:59:36 volumio volumio[14974]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 15 18:59:36 volumio volumio[14974]: info: Discovery: Started advertising with name: Volumio Sep 15 18:59:36 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:36 volumio volumio[14974]: info: Loading plugin "outputs"... Sep 15 18:59:36 volumio volumio[14974]: info: Loading plugin "albumart"... Sep 15 18:59:36 volumio volumio[14974]: info: Plugin example_plugin is not enabled Sep 15 18:59:36 volumio volumio[14974]: info: Loading plugin "inputs"... Sep 15 18:59:36 volumio volumio[14974]: info: Loading plugin "updater_comm"... Sep 15 18:59:37 volumio volumio[14974]: info: Plugin mpdemulation is not enabled Sep 15 18:59:37 volumio volumio[14974]: info: Loading plugin "rest_api"... Sep 15 18:59:37 volumio volumio[14974]: info: Loading plugin "websocket"... Sep 15 18:59:37 volumio volumio[14974]: info: Starting Socket.io Server version 2.3.0 Sep 15 18:59:37 volumio volumio[14974]: info: Loading plugin "fusiondsp"... Sep 15 18:59:37 volumio volumio[14974]: Forking 3 albumart workers Sep 15 18:59:37 volumio volumio[14974]: info: Applying required configuration parameters for plugin fusiondsp Sep 15 18:59:37 volumio volumio[14974]: info: Loading i18n strings for locale de Sep 15 18:59:37 volumio volumio[14974]: Updating browse sources language Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::initPlayerControls Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:37 volumio volumio[14974]: Express server listening on port 3000 Sep 15 18:59:37 volumio volumio[14974]: [Metrics] WebUI: 4s 878.16ms Sep 15 18:59:37 volumio volumio[14974]: info: CoreStateMachine::resetVolumioState Sep 15 18:59:37 volumio volumio[14974]: info: CoreStateMachine::getcurrentVolume Sep 15 18:59:37 volumio volumio[14974]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 18:59:38 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:38 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 173. Sep 15 18:59:38 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:38 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:38 volumio volumio[14974]: info: Volumio Network Manager: Network status updated: 1 Sep 15 18:59:38 volumio volumio[14974]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 15 18:59:38 volumio volumio[14974]: wlan0 Interface doesn't support scanning. Sep 15 18:59:38 volumio volumio[14974]: info: Cannot use regular scanning, forcing with ap-force Sep 15 18:59:38 volumio volumio[15070]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:38 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:38 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:38 volumio sudo[15072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 15 18:59:38 volumio sudo[15072]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:38 volumio sudo[15072]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:38 volumio volumio[14974]: command failed: No such device (-19) Sep 15 18:59:38 volumio volumio[14974]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 15 18:59:38 volumio volumio[14974]: command failed: No such device (-19) Sep 15 18:59:38 volumio volumio[14974]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 15 18:59:38 volumio volumio-remote-updater[575]: [2025-09-15 18:59:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757955576 101 Sep 15 18:59:38 volumio volumio[14974]: Starting albumart workers Sep 15 18:59:38 volumio volumio[14974]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Sep 15 18:59:38 volumio volumio[14974]: verbose: New Socket.io Connection to 192.168.188.162:3000 from 192.168.188.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 15 18:59:38 volumio volumio[14974]: info: CoreStateMachine::pushState Sep 15 18:59:38 volumio volumio[14974]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:38 volumio volumio[14974]: Starting albumart workers Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:38 volumio volumio[14974]: Starting albumart workers Sep 15 18:59:38 volumio volumio[14974]: info: CoreStateMachine::updateTrackBlock Sep 15 18:59:38 volumio volumio[14974]: info: CorePlayQueue::getTrackBlock Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:38 volumio volumio[14974]: info: Reloading queue from file Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 15 18:59:38 volumio volumio[14974]: info: Setting Device type: Raspberry PI Sep 15 18:59:38 volumio volumio[14974]: info: CoreStateMachine::setRepeat null single undefined Sep 15 18:59:38 volumio volumio[14974]: info: CoreStateMachine::pushState Sep 15 18:59:38 volumio volumio[14974]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:38 volumio volumio[14974]: info: CoreStateMachine::setRandom null Sep 15 18:59:38 volumio volumio[14974]: info: CoreStateMachine::pushState Sep 15 18:59:38 volumio volumio[14974]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:38 volumio volumio[14974]: info: Completed loading Core Plugins Sep 15 18:59:38 volumio volumio[14974]: info: Preparing to generate the ALSA configuration file Sep 15 18:59:38 volumio volumio[14974]: info: CoreStateMachine::pushState Sep 15 18:59:38 volumio volumio[14974]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:38 volumio volumio[14974]: info: Discovery: adding e470e2ce-0a34-4725-aaf5-aa5e94375e01 Sep 15 18:59:38 volumio volumio[14974]: info: Discovery: Found device VolumioHD Sep 15 18:59:38 volumio volumio[14974]: info: Discovery: Connecting to remote: 192.168.188.101 Sep 15 18:59:38 volumio volumio[14974]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 18:59:38 volumio volumio[14974]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 18:59:38 volumio volumio[14974]: info: Reading ALSA contributions from plugins. Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:38 volumio volumio[14974]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:38 volumio volumio[14974]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 15 18:59:38 volumio volumio[14974]: info: Discovery: adding bed2b3d6-941c-4a98-93b9-b3df60fe4d86 Sep 15 18:59:38 volumio volumio[14974]: info: Discovery: Found device Volumio Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:38 volumio volumio[14974]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:38 volumio volumio[14974]: info: Discovery: Connected to remote: 192.168.188.101 Sep 15 18:59:38 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 15 18:59:38 volumio volumio[14974]: info: Asound.conf file unchanged, so no further update is needed Sep 15 18:59:38 volumio volumio[14974]: info: Output device has changed, restarting MPD Sep 15 18:59:39 volumio sudo[15089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:39 volumio sudo[15089]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:39 volumio volumio[14974]: info: Output device has changed, restarting Shairport Sync Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:39 volumio sudo[15089]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:39 volumio sudo[15092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:39 volumio sudo[15092]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:39 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 18:59:39 volumio volumio[14974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:39 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:39 volumio volumio[14974]: info: ___________ START PLUGINS ___________ Sep 15 18:59:39 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:39 volumio volumio[14974]: info: ControllerMpd::onStart: Initializing MPD Sep 15 18:59:39 volumio volumio[14974]: info: Creating MPD Configuration file Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:39 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:39 volumio volumio[14974]: info: [1757955579232] CoreMusicLibrary::Adding element Medienserver Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:39 volumio sudo[15115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:39 volumio sudo[15115]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:39 volumio sudo[15117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:39 volumio sudo[15115]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:39 volumio sudo[15117]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:39 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 15 18:59:39 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:39 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:39 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:39 volumio volumio[14974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:39 volumio volumio[14974]: info: [1757955579404] CoreMusicLibrary::Adding element Last_100 Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:39 volumio volumio[14974]: info: [1757955579408] CoreMusicLibrary::Adding element Webradio Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:39 volumio volumio[14974]: info: Initializing BBC Radios Sep 15 18:59:39 volumio sudo[15132]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 18:59:39 volumio sudo[15132]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:39 volumio sudo[15132]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: Loading i18n strings for locale de Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: FusionDsp - mixtype--------------------- Software Sep 15 18:59:39 volumio volumio[14974]: info: Volumio Calling Home Sep 15 18:59:39 volumio volumio[14974]: info: Preparing to generate the ALSA configuration file Sep 15 18:59:39 volumio volumio[14974]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 18:59:39 volumio volumio[14974]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 18:59:39 volumio volumio[14974]: info: Reading ALSA contributions from plugins. Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::volumioGetVisibleSources Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:39 volumio volumio[14974]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:39 volumio volumio[14974]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 15 18:59:39 volumio volumio[14974]: info: Received Get System Info Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 18:59:39 volumio volumio[14974]: info: Discovery: Getting this device information Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:39 volumio volumio[14974]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:39 volumio volumio[14974]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:39 volumio volumio[14974]: info: Listing playlists Sep 15 18:59:39 volumio volumio[14974]: info: MPD Permissions set Sep 15 18:59:39 volumio volumio[14974]: info: MPD Permissions set Sep 15 18:59:39 volumio volumio[14974]: info: Volumio called home Sep 15 18:59:39 volumio volumio[14974]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:39 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:40 volumio volumio[14974]: info: Starting Shairport Sync Sep 15 18:59:40 volumio volumio[14974]: info: Starting Shairport Sync Sep 15 18:59:40 volumio volumio[14974]: info: Starting Shairport Sync Sep 15 18:59:40 volumio sudo[15160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:40 volumio sudo[15160]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:40 volumio sudo[15162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:40 volumio sudo[15162]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:40 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:40 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 174. Sep 15 18:59:40 volumio sudo[15165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:40 volumio sudo[15165]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:40 volumio volumio[14974]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 18:59:40 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:40 volumio volumio[14974]: info: Asound.conf file unchanged, so no further update is needed Sep 15 18:59:40 volumio volumio[14974]: info: Output device has changed, restarting MPD Sep 15 18:59:40 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:40 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 18:59:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:40 volumio volumio[14974]: info: Output device has changed, restarting Shairport Sync Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:40 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:40 volumio sudo[15170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 18:59:40 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 15 18:59:40 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 18:59:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:40 volumio sudo[15162]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:40 volumio sudo[15170]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:40 volumio sudo[15160]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:40 volumio volumio[15168]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:40 volumio sudo[15165]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:40 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:40 volumio sudo[15170]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:40 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:40 volumio sudo[15172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:40 volumio sudo[15172]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:40 volumio volumio[14974]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:40 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:40 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 15 18:59:40 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:40 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:40 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:40 volumio sudo[15182]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 18:59:40 volumio sudo[15182]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:40 volumio sudo[15182]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:40 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:41 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:41 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:41 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:41 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:41 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:42 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:42 volumio mpd[15184]: Sep 15 18:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 18:59:42 volumio systemd[1]: Started Music Player Daemon. Sep 15 18:59:42 volumio sudo[15172]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:42 volumio sudo[15092]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:42 volumio sudo[15117]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:42 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:42 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:42 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 175. Sep 15 18:59:42 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:42 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:42 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:42 volumio volumio[15187]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:42 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:42 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:42 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:43 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:43 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:43 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:43 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:43 volumio volumio[14974]: info: FusionDsp - Sep 15 18:59:43 volumio volumio[14974]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 15 18:59:43 volumio volumio[14974]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 15 18:59:43 volumio volumio[14974]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 15 18:59:43 volumio volumio[14974]: info: camilladsp service started and running in background, instance 1 Sep 15 18:59:43 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:43 volumio volumio[14974]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 18:59:43 volumio volumio[14974]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 15 18:59:43 volumio volumio[14974]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 18:59:44 volumio volumio[14974]: info: FusionDsp loaded Sep 15 18:59:44 volumio volumio[14974]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 18:59:44 volumio sudo[15198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 15 18:59:44 volumio sudo[15198]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:44 volumio volumio[14974]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 15 18:59:44 volumio volumio[14974]: info: Adding Signal Path Element [object Object] Sep 15 18:59:44 volumio volumio[14974]: info: Adding fusiondspeq DSP Signal Path Element Sep 15 18:59:44 volumio volumio[14974]: info: FusionDsp - ---- installed callbackRead Sep 15 18:59:44 volumio volumio[14974]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:59:44 volumio volumio[14974]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 15 18:59:44 volumio volumio[14974]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 15 18:59:44 volumio volumio[14974]: at onErrorNT (internal/child_process.js:465:16) Sep 15 18:59:44 volumio volumio[14974]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 15 18:59:44 volumio volumio[14974]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 15 18:59:44 volumio volumio[14974]: at listOnTimeout (internal/timers.js:523:9) Sep 15 18:59:44 volumio volumio[14974]: at processTimers (internal/timers.js:497:7) { Sep 15 18:59:44 volumio volumio[14974]: errno: -2, Sep 15 18:59:44 volumio volumio[14974]: code: 'ENOENT', Sep 15 18:59:44 volumio volumio[14974]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 18:59:44 volumio volumio[14974]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 18:59:44 volumio volumio[14974]: spawnargs: [ Sep 15 18:59:44 volumio volumio[14974]: '-p', Sep 15 18:59:44 volumio volumio[14974]: 9876, Sep 15 18:59:44 volumio volumio[14974]: '-o', Sep 15 18:59:44 volumio volumio[14974]: '/tmp/camilladsp.log', Sep 15 18:59:44 volumio volumio[14974]: '-l', Sep 15 18:59:44 volumio volumio[14974]: 'warn', Sep 15 18:59:44 volumio volumio[14974]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 15 18:59:44 volumio volumio[14974]: ] Sep 15 18:59:44 volumio volumio[14974]: } Sep 15 18:59:44 volumio volumio[14974]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:59:44 volumio sudo[15209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 18:58 Sep 15 18:59:44 volumio sudo[15209]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:44 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:44 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 176. Sep 15 18:59:44 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:44 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:44 volumio sudo[15198]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:44 volumio volumio[15211]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:44 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:44 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:44 volumio sudo[15209]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:45 volumio volumio-remote-updater[575]: [2025-09-15 18:59:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 15 18:59:45 volumio volumio-remote-updater[575]: [2025-09-15 18:59:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 15 18:59:45 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 15 18:59:45 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 15 18:59:45 volumio systemd[1]: Started dynamicswap service. Sep 15 18:59:45 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 18:59:45 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 15 18:59:45 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 46. Sep 15 18:59:45 volumio systemd[1]: Stopped Volumio Backend Module. Sep 15 18:59:45 volumio systemd[1]: Started Volumio Backend Module. Sep 15 18:59:45 volumio systemd[1]: Started dynamicswap service. Sep 15 18:59:45 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 18:59:47 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:47 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 177. Sep 15 18:59:47 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:47 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:47 volumio volumio[15236]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:47 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:47 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:47 volumio volumio[15223]: info: ------------------------------------------- Sep 15 18:59:47 volumio volumio[15223]: info: ----- Volumio3 ---- Sep 15 18:59:47 volumio volumio[15223]: info: ------------------------------------------- Sep 15 18:59:47 volumio volumio[15223]: info: ----- System startup ---- Sep 15 18:59:47 volumio volumio[15223]: info: ------------------------------------------- Sep 15 18:59:48 volumio volumio[15223]: info: MYVOLUMIO Environment detected Sep 15 18:59:48 volumio volumio[15223]: info: Plugin folders cleanup Sep 15 18:59:48 volumio volumio[15223]: info: Scanning into folder /volumio/app/plugins/ Sep 15 18:59:48 volumio volumio[15223]: info: Scanning category audio_interface Sep 15 18:59:48 volumio volumio[15223]: info: Scanning category miscellanea Sep 15 18:59:48 volumio volumio[15223]: info: Scanning category music_service Sep 15 18:59:48 volumio volumio[15223]: info: Scanning category plugins.json Sep 15 18:59:48 volumio volumio[15223]: info: Scanning category system_controller Sep 15 18:59:48 volumio volumio[15223]: info: Scanning category user_interface Sep 15 18:59:48 volumio volumio[15223]: info: Scanning into folder /data/plugins/ Sep 15 18:59:48 volumio volumio[15223]: info: Scanning category audio_interface Sep 15 18:59:48 volumio volumio[15223]: info: Plugin folders cleanup completed Sep 15 18:59:48 volumio volumio[15223]: info: ------------------------------------------- Sep 15 18:59:48 volumio volumio[15223]: info: ----- Core plugins startup ---- Sep 15 18:59:48 volumio volumio[15223]: info: ------------------------------------------- Sep 15 18:59:48 volumio volumio[15223]: info: Loading plugins from folder /volumio/app/plugins/ Sep 15 18:59:48 volumio volumio[15223]: info: Adding plugin upnp to MyMusic Plugins Sep 15 18:59:48 volumio volumio[15223]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 15 18:59:48 volumio volumio[15223]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 15 18:59:48 volumio volumio[15223]: info: Loading plugins from folder /data/plugins/ Sep 15 18:59:48 volumio volumio[15223]: info: Loading plugin "system"... Sep 15 18:59:48 volumio volumio[15223]: info: Loading plugin "appearance"... Sep 15 18:59:49 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:49 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 178. Sep 15 18:59:49 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:49 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:49 volumio volumio[15250]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:49 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:49 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:49 volumio volumio[15223]: info: Loading plugin "network"... Sep 15 18:59:49 volumio volumio[15223]: info: Refreshing Cached IP Addresses Sep 15 18:59:49 volumio sudo[15252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 18:59:49 volumio sudo[15252]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:49 volumio sudo[15252]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:49 volumio sudo[15254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 18:59:49 volumio sudo[15254]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:49 volumio volumio[15223]: info: Loading plugin "services"... Sep 15 18:59:49 volumio volumio[15223]: info: Loading plugin "alsa_controller"... Sep 15 18:59:49 volumio sudo[15254]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:49 volumio sudo[15268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 18:59:49 volumio sudo[15268]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:49 volumio sudo[15268]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:49 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:49 volumio volumio[15223]: info: Loading plugin "wizard"... Sep 15 18:59:49 volumio volumio[15223]: info: Loading plugin "networkfs"... Sep 15 18:59:49 volumio volumio[15223]: info: Starting Udev Watcher for removable devices Sep 15 18:59:49 volumio volumio[15223]: info: Ignoring mount for partition: boot Sep 15 18:59:49 volumio volumio[15223]: info: Ignoring mount for partition: volumio Sep 15 18:59:49 volumio volumio[15223]: info: Ignoring mount for partition: volumio_data Sep 15 18:59:49 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:49 volumio volumio[15223]: info: Loading plugin "volumio_command_line_client"... Sep 15 18:59:49 volumio volumio[15223]: info: Loading plugin "upnp"... Sep 15 18:59:49 volumio volumio[15223]: info: [1757955589697] Starting Upmpd Daemon Sep 15 18:59:49 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:49 volumio volumio[15223]: info: Loading plugin "my_music"... Sep 15 18:59:49 volumio volumio[15223]: info: Loading plugin "mpd"... Sep 15 18:59:50 volumio volumio[15223]: info: Loading plugin "upnp_browser"... Sep 15 18:59:50 volumio volumio[15223]: info: Loading plugin "alarm-clock"... Sep 15 18:59:50 volumio volumio-remote-updater[575]: [2025-09-15 18:59:50] [connect] Successful connection Sep 15 18:59:50 volumio volumio[15223]: info: Loading plugin "airplay_emulation"... Sep 15 18:59:50 volumio volumio[15223]: info: Starting Shairport Sync Sep 15 18:59:50 volumio volumio[15223]: info: Loading plugin "last_100"... Sep 15 18:59:50 volumio volumio[15223]: info: Loading plugin "webradio"... Sep 15 18:59:50 volumio volumio[15223]: info: Loading plugin "i2s_dacs"... Sep 15 18:59:50 volumio volumio[15223]: info: Loading plugin "volumiodiscovery"... Sep 15 18:59:50 volumio volumio[15223]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:50 volumio volumio[15223]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:50 volumio volumio[15223]: *** WARNING *** For more information see Sep 15 18:59:50 volumio volumio[15223]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:50 volumio volumio[15223]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:50 volumio node[15223]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:50 volumio volumio[15223]: *** WARNING *** For more information see Sep 15 18:59:50 volumio node[15223]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:50 volumio node[15223]: *** WARNING *** For more information see Sep 15 18:59:50 volumio node[15223]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 18:59:50 volumio node[15223]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 18:59:50 volumio node[15223]: *** WARNING *** For more information see Sep 15 18:59:50 volumio volumio[15223]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 15 18:59:50 volumio volumio[15223]: info: Discovery: Started advertising with name: Volumio Sep 15 18:59:50 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 18:59:50 volumio volumio[15223]: info: Loading plugin "outputs"... Sep 15 18:59:50 volumio volumio[15223]: info: Loading plugin "albumart"... Sep 15 18:59:50 volumio volumio[15223]: info: Plugin example_plugin is not enabled Sep 15 18:59:50 volumio volumio[15223]: info: Loading plugin "inputs"... Sep 15 18:59:50 volumio volumio[15223]: info: Loading plugin "updater_comm"... Sep 15 18:59:51 volumio volumio[15223]: info: Plugin mpdemulation is not enabled Sep 15 18:59:51 volumio volumio[15223]: info: Loading plugin "rest_api"... Sep 15 18:59:51 volumio volumio[15223]: info: Loading plugin "websocket"... Sep 15 18:59:51 volumio volumio[15223]: info: Starting Socket.io Server version 2.3.0 Sep 15 18:59:51 volumio volumio[15223]: info: Loading plugin "fusiondsp"... Sep 15 18:59:51 volumio volumio[15223]: Forking 3 albumart workers Sep 15 18:59:51 volumio volumio[15223]: info: Applying required configuration parameters for plugin fusiondsp Sep 15 18:59:51 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:51 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 179. Sep 15 18:59:51 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:51 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:51 volumio volumio[15367]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:51 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:51 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:51 volumio volumio[15223]: info: Loading i18n strings for locale de Sep 15 18:59:51 volumio volumio[15223]: Updating browse sources language Sep 15 18:59:51 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::initPlayerControls Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:52 volumio volumio[15223]: Express server listening on port 3000 Sep 15 18:59:52 volumio volumio[15223]: [Metrics] WebUI: 5s 149.96ms Sep 15 18:59:52 volumio volumio[15223]: info: CoreStateMachine::resetVolumioState Sep 15 18:59:52 volumio volumio[15223]: info: CoreStateMachine::getcurrentVolume Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 18:59:52 volumio volumio[15223]: info: Volumio Network Manager: Network status updated: 1 Sep 15 18:59:52 volumio volumio[15223]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 15 18:59:52 volumio volumio[15223]: wlan0 Interface doesn't support scanning. Sep 15 18:59:52 volumio volumio[15223]: info: Cannot use regular scanning, forcing with ap-force Sep 15 18:59:52 volumio sudo[15387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 15 18:59:52 volumio sudo[15387]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:52 volumio sudo[15387]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:52 volumio volumio[15223]: command failed: No such device (-19) Sep 15 18:59:52 volumio volumio[15223]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 15 18:59:52 volumio volumio[15223]: command failed: No such device (-19) Sep 15 18:59:52 volumio volumio[15223]: Starting albumart workers Sep 15 18:59:52 volumio volumio[15223]: Starting albumart workers Sep 15 18:59:52 volumio volumio[15223]: Starting albumart workers Sep 15 18:59:52 volumio volumio[15223]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 15 18:59:52 volumio volumio[15223]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 15 18:59:52 volumio volumio-remote-updater[575]: [2025-09-15 18:59:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757955590 101 Sep 15 18:59:52 volumio volumio[15223]: 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: 3 Sep 15 18:59:52 volumio volumio[15223]: verbose: New Socket.io Connection to 192.168.188.162:3000 from 192.168.188.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 15 18:59:52 volumio volumio[15223]: info: CoreStateMachine::pushState Sep 15 18:59:52 volumio volumio[15223]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:52 volumio volumio[15223]: info: CoreStateMachine::updateTrackBlock Sep 15 18:59:52 volumio volumio[15223]: info: CorePlayQueue::getTrackBlock Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:52 volumio volumio[15223]: info: Reloading queue from file Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 15 18:59:52 volumio volumio[15223]: info: CoreStateMachine::setRepeat null single undefined Sep 15 18:59:52 volumio volumio[15223]: info: CoreStateMachine::pushState Sep 15 18:59:52 volumio volumio[15223]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:52 volumio volumio[15223]: info: CoreStateMachine::setRandom null Sep 15 18:59:52 volumio volumio[15223]: info: CoreStateMachine::pushState Sep 15 18:59:52 volumio volumio[15223]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:52 volumio volumio[15223]: info: Setting Device type: Raspberry PI Sep 15 18:59:52 volumio volumio[15223]: info: Completed loading Core Plugins Sep 15 18:59:52 volumio volumio[15223]: info: Preparing to generate the ALSA configuration file Sep 15 18:59:52 volumio volumio[15223]: info: CoreStateMachine::pushState Sep 15 18:59:52 volumio volumio[15223]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::volumioPushState Sep 15 18:59:52 volumio volumio[15223]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 18:59:52 volumio volumio[15223]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 18:59:52 volumio volumio[15223]: info: Reading ALSA contributions from plugins. Sep 15 18:59:52 volumio volumio[15223]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:52 volumio volumio[15223]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:52 volumio volumio[15223]: info: Discovery: adding e470e2ce-0a34-4725-aaf5-aa5e94375e01 Sep 15 18:59:52 volumio volumio[15223]: info: Discovery: Found device VolumioHD Sep 15 18:59:52 volumio volumio[15223]: info: Discovery: Connecting to remote: 192.168.188.101 Sep 15 18:59:53 volumio volumio[15223]: info: Discovery: Connected to remote: 192.168.188.101 Sep 15 18:59:53 volumio volumio[15223]: info: Discovery: adding bed2b3d6-941c-4a98-93b9-b3df60fe4d86 Sep 15 18:59:53 volumio volumio[15223]: info: Discovery: Found device Volumio Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:53 volumio volumio[15223]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:53 volumio volumio[15223]: info: Asound.conf file unchanged, so no further update is needed Sep 15 18:59:53 volumio volumio[15223]: info: Output device has changed, restarting MPD Sep 15 18:59:53 volumio volumio[15223]: info: Output device has changed, restarting Shairport Sync Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:53 volumio sudo[15424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:53 volumio sudo[15424]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:53 volumio sudo[15424]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:53 volumio sudo[15430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:53 volumio sudo[15430]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:53 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 18:59:53 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:53 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:53 volumio volumio[15223]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:53 volumio volumio[15223]: info: ___________ START PLUGINS ___________ Sep 15 18:59:53 volumio volumio[15223]: info: ControllerMpd::onStart: Initializing MPD Sep 15 18:59:53 volumio volumio[15223]: info: Creating MPD Configuration file Sep 15 18:59:53 volumio sudo[15438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:53 volumio sudo[15438]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:53 volumio volumio[15223]: info: [1757955593354] CoreMusicLibrary::Adding element Medienserver Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:53 volumio sudo[15438]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:53 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:53 volumio sudo[15440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:53 volumio sudo[15440]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:53 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 15 18:59:53 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:53 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:53 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:53 volumio volumio[15223]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:53 volumio volumio[15223]: info: [1757955593497] CoreMusicLibrary::Adding element Last_100 Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 18:59:53 volumio volumio[15223]: info: [1757955593501] CoreMusicLibrary::Adding element Webradio Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:53 volumio volumio[15223]: info: Initializing BBC Radios Sep 15 18:59:53 volumio sudo[15446]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 18:59:53 volumio sudo[15446]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:53 volumio sudo[15446]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: Loading i18n strings for locale de Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: FusionDsp - mixtype--------------------- Software Sep 15 18:59:53 volumio volumio[15223]: info: Volumio Calling Home Sep 15 18:59:53 volumio volumio[15223]: info: Preparing to generate the ALSA configuration file Sep 15 18:59:53 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:53 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 180. Sep 15 18:59:53 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:53 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:53 volumio volumio[15223]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 18:59:53 volumio volumio[15223]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 18:59:53 volumio volumio[15223]: info: Reading ALSA contributions from plugins. Sep 15 18:59:53 volumio volumio[15465]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:53 volumio volumio[15223]: info: MPD Permissions set Sep 15 18:59:53 volumio volumio[15223]: info: MPD Permissions set Sep 15 18:59:53 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:53 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:53 volumio volumio[15223]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 15 18:59:53 volumio volumio[15223]: info: Volumio called home Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:53 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:54 volumio volumio[15223]: info: Starting Shairport Sync Sep 15 18:59:54 volumio volumio[15223]: info: Starting Shairport Sync Sep 15 18:59:54 volumio volumio[15223]: info: Starting Shairport Sync Sep 15 18:59:54 volumio sudo[15475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:54 volumio sudo[15475]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:54 volumio sudo[15477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:54 volumio sudo[15477]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 18:59:54 volumio sudo[15480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:54 volumio sudo[15480]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:54 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 18:59:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:54 volumio volumio[15223]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:54 volumio volumio[15223]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:54 volumio volumio[15223]: info: Asound.conf file unchanged, so no further update is needed Sep 15 18:59:54 volumio volumio[15223]: info: Output device has changed, restarting MPD Sep 15 18:59:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:54 volumio sudo[15475]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:54 volumio volumio[15223]: info: Output device has changed, restarting Shairport Sync Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 18:59:54 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 15 18:59:54 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 18:59:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:54 volumio sudo[15485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 18:59:54 volumio sudo[15485]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:54 volumio sudo[15477]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:54 volumio sudo[15485]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:54 volumio sudo[15480]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:54 volumio sudo[15487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 18:59:54 volumio sudo[15487]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:54 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 15 18:59:54 volumio systemd[1]: mpd.service: Succeeded. Sep 15 18:59:54 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 18:59:54 volumio systemd[1]: Starting Music Player Daemon... Sep 15 18:59:54 volumio volumio[15223]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 15 18:59:54 volumio volumio[15223]: info: MPD Permissions set Sep 15 18:59:54 volumio volumio[15223]: info: Shairport-Sync Started Sep 15 18:59:54 volumio volumio[15223]: Error adding Membership: Error: addMembership EINVAL Sep 15 18:59:54 volumio volumio[15223]: info: Shairport-Sync Started Sep 15 18:59:54 volumio volumio[15223]: info: Shairport-Sync Started Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:54 volumio sudo[15496]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 18:59:54 volumio sudo[15496]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:54 volumio sudo[15496]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::volumioGetVisibleSources Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:54 volumio volumio[15223]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:54 volumio volumio[15223]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 15 18:59:54 volumio volumio[15223]: info: Received Get System Info Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 18:59:54 volumio volumio[15223]: info: Discovery: Getting this device information Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:54 volumio volumio[15223]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::volumioGetState Sep 15 18:59:54 volumio volumio[15223]: info: CorePlayQueue::getTrack 0 Sep 15 18:59:54 volumio volumio[15223]: info: Listing playlists Sep 15 18:59:54 volumio volumio[15223]: info: Starting Shairport Sync Sep 15 18:59:54 volumio sudo[15505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 18:59:54 volumio sudo[15505]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:54 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 18:59:54 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 18:59:54 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:54 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 18:59:54 volumio sudo[15505]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: Shairport-Sync Started Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 18:59:54 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:55 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:55 volumio volumio[15223]: info: FusionDsp - undefined Sep 15 18:59:55 volumio volumio[15223]: info: FusionDsp - undefined Sep 15 18:59:55 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:55 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:55 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:56 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:56 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 181. Sep 15 18:59:56 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:56 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:56 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:56 volumio mpd[15503]: Sep 15 18:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 18:59:56 volumio systemd[1]: Started Music Player Daemon. Sep 15 18:59:56 volumio sudo[15440]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:56 volumio sudo[15487]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:56 volumio sudo[15430]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:56 volumio volumio[15510]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:56 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:56 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:56 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:56 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:56 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:56 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:57 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:57 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:57 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:57 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:58 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:58 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:58 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 18:59:58 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 182. Sep 15 18:59:58 volumio volumio[15223]: info: FusionDsp - Sep 15 18:59:58 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 18:59:58 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 18:59:58 volumio volumio[15513]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 18:59:58 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 18:59:58 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 18:59:58 volumio volumio[15223]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 15 18:59:58 volumio volumio[15223]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 15 18:59:58 volumio volumio[15223]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 15 18:59:58 volumio volumio[15223]: info: camilladsp service started and running in background, instance 1 Sep 15 18:59:58 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 18:59:58 volumio volumio[15223]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 18:59:58 volumio volumio[15223]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 15 18:59:58 volumio volumio[15223]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 18:59:58 volumio volumio[15223]: info: FusionDsp loaded Sep 15 18:59:58 volumio volumio[15223]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 18:59:58 volumio sudo[15524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 15 18:59:58 volumio sudo[15524]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:58 volumio volumio[15223]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 15 18:59:58 volumio volumio[15223]: info: Adding Signal Path Element [object Object] Sep 15 18:59:58 volumio volumio[15223]: info: Adding fusiondspeq DSP Signal Path Element Sep 15 18:59:58 volumio volumio[15223]: info: FusionDsp - ---- installed callbackRead Sep 15 18:59:58 volumio volumio[15223]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:59:58 volumio volumio[15223]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 15 18:59:58 volumio volumio[15223]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 15 18:59:58 volumio volumio[15223]: at onErrorNT (internal/child_process.js:465:16) Sep 15 18:59:58 volumio volumio[15223]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 15 18:59:58 volumio volumio[15223]: at runNextTicks (internal/process/task_queues.js:62:3) Sep 15 18:59:58 volumio volumio[15223]: at listOnTimeout (internal/timers.js:523:9) Sep 15 18:59:58 volumio volumio[15223]: at processTimers (internal/timers.js:497:7) { Sep 15 18:59:58 volumio volumio[15223]: errno: -2, Sep 15 18:59:58 volumio volumio[15223]: code: 'ENOENT', Sep 15 18:59:58 volumio volumio[15223]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 18:59:58 volumio volumio[15223]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 18:59:58 volumio volumio[15223]: spawnargs: [ Sep 15 18:59:58 volumio volumio[15223]: '-p', Sep 15 18:59:58 volumio volumio[15223]: 9876, Sep 15 18:59:58 volumio volumio[15223]: '-o', Sep 15 18:59:58 volumio volumio[15223]: '/tmp/camilladsp.log', Sep 15 18:59:58 volumio volumio[15223]: '-l', Sep 15 18:59:58 volumio volumio[15223]: 'warn', Sep 15 18:59:58 volumio volumio[15223]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 15 18:59:58 volumio volumio[15223]: ] Sep 15 18:59:58 volumio volumio[15223]: } Sep 15 18:59:58 volumio volumio[15223]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 18:59:59 volumio sudo[15535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 18:58 Sep 15 18:59:59 volumio sudo[15535]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 18:59:59 volumio sudo[15535]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:59 volumio volumio-remote-updater[575]: [2025-09-15 18:59:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 15 18:59:59 volumio volumio-remote-updater[575]: [2025-09-15 18:59:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 15 18:59:59 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 15 18:59:59 volumio sudo[15524]: pam_unix(sudo:session): session closed for user root Sep 15 18:59:59 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 15 18:59:59 volumio systemd[1]: Started dynamicswap service. Sep 15 18:59:59 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 18:59:59 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 15 18:59:59 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 47. Sep 15 18:59:59 volumio systemd[1]: Stopped Volumio Backend Module. Sep 15 18:59:59 volumio systemd[1]: Started Volumio Backend Module. Sep 15 18:59:59 volumio systemd[1]: Started dynamicswap service. Sep 15 18:59:59 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 19:00:00 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 19:00:00 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 183. Sep 15 19:00:00 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 19:00:00 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 19:00:00 volumio volumio[15560]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 19:00:00 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 19:00:00 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 19:00:01 volumio volumio[15547]: info: ------------------------------------------- Sep 15 19:00:01 volumio volumio[15547]: info: ----- Volumio3 ---- Sep 15 19:00:01 volumio volumio[15547]: info: ------------------------------------------- Sep 15 19:00:01 volumio volumio[15547]: info: ----- System startup ---- Sep 15 19:00:01 volumio volumio[15547]: info: ------------------------------------------- Sep 15 19:00:02 volumio volumio[15547]: info: MYVOLUMIO Environment detected Sep 15 19:00:02 volumio volumio[15547]: info: Plugin folders cleanup Sep 15 19:00:02 volumio volumio[15547]: info: Scanning into folder /volumio/app/plugins/ Sep 15 19:00:02 volumio volumio[15547]: info: Scanning category audio_interface Sep 15 19:00:02 volumio volumio[15547]: info: Scanning category miscellanea Sep 15 19:00:02 volumio volumio[15547]: info: Scanning category music_service Sep 15 19:00:02 volumio volumio[15547]: info: Scanning category plugins.json Sep 15 19:00:02 volumio volumio[15547]: info: Scanning category system_controller Sep 15 19:00:02 volumio volumio[15547]: info: Scanning category user_interface Sep 15 19:00:02 volumio volumio[15547]: info: Scanning into folder /data/plugins/ Sep 15 19:00:02 volumio volumio[15547]: info: Scanning category audio_interface Sep 15 19:00:02 volumio volumio[15547]: info: Plugin folders cleanup completed Sep 15 19:00:02 volumio volumio[15547]: info: ------------------------------------------- Sep 15 19:00:02 volumio volumio[15547]: info: ----- Core plugins startup ---- Sep 15 19:00:02 volumio volumio[15547]: info: ------------------------------------------- Sep 15 19:00:02 volumio volumio[15547]: info: Loading plugins from folder /volumio/app/plugins/ Sep 15 19:00:02 volumio volumio[15547]: info: Adding plugin upnp to MyMusic Plugins Sep 15 19:00:02 volumio volumio[15547]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 15 19:00:02 volumio volumio[15547]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 15 19:00:02 volumio volumio[15547]: info: Loading plugins from folder /data/plugins/ Sep 15 19:00:02 volumio volumio[15547]: info: Loading plugin "system"... Sep 15 19:00:02 volumio volumio[15547]: info: Loading plugin "appearance"... Sep 15 19:00:02 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 19:00:02 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 184. Sep 15 19:00:02 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 19:00:02 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 19:00:02 volumio volumio[15574]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 19:00:02 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 19:00:02 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 19:00:03 volumio volumio[15547]: info: Loading plugin "network"... Sep 15 19:00:03 volumio volumio[15547]: info: Refreshing Cached IP Addresses Sep 15 19:00:03 volumio sudo[15576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 19:00:03 volumio sudo[15576]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:03 volumio sudo[15576]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:03 volumio sudo[15578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 19:00:03 volumio sudo[15578]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:03 volumio volumio[15547]: info: Loading plugin "services"... Sep 15 19:00:03 volumio volumio[15547]: info: Loading plugin "alsa_controller"... Sep 15 19:00:03 volumio sudo[15578]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:03 volumio sudo[15588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 19:00:03 volumio sudo[15588]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:03 volumio sudo[15588]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:03 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 19:00:03 volumio volumio[15547]: info: Loading plugin "wizard"... Sep 15 19:00:03 volumio volumio[15547]: info: Loading plugin "networkfs"... Sep 15 19:00:03 volumio volumio[15547]: info: Starting Udev Watcher for removable devices Sep 15 19:00:03 volumio volumio[15547]: info: Ignoring mount for partition: boot Sep 15 19:00:03 volumio volumio[15547]: info: Ignoring mount for partition: volumio Sep 15 19:00:03 volumio volumio[15547]: info: Ignoring mount for partition: volumio_data Sep 15 19:00:03 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 19:00:03 volumio volumio[15547]: info: Loading plugin "volumio_command_line_client"... Sep 15 19:00:03 volumio volumio[15547]: info: Loading plugin "upnp"... Sep 15 19:00:03 volumio volumio[15547]: info: [1757955603617] Starting Upmpd Daemon Sep 15 19:00:03 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 19:00:03 volumio volumio[15547]: info: Loading plugin "my_music"... Sep 15 19:00:03 volumio volumio[15547]: info: Loading plugin "mpd"... Sep 15 19:00:03 volumio volumio[15547]: info: Loading plugin "upnp_browser"... Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "alarm-clock"... Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "airplay_emulation"... Sep 15 19:00:04 volumio volumio[15547]: info: Starting Shairport Sync Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "last_100"... Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "webradio"... Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "i2s_dacs"... Sep 15 19:00:04 volumio volumio-remote-updater[575]: [2025-09-15 19:00:04] [connect] Successful connection Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "volumiodiscovery"... Sep 15 19:00:04 volumio volumio[15547]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 19:00:04 volumio volumio[15547]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 19:00:04 volumio volumio[15547]: *** WARNING *** For more information see Sep 15 19:00:04 volumio node[15547]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 19:00:04 volumio volumio[15547]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 19:00:04 volumio volumio[15547]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 19:00:04 volumio volumio[15547]: *** WARNING *** For more information see Sep 15 19:00:04 volumio node[15547]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 19:00:04 volumio node[15547]: *** WARNING *** For more information see Sep 15 19:00:04 volumio node[15547]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 19:00:04 volumio node[15547]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 19:00:04 volumio node[15547]: *** WARNING *** For more information see Sep 15 19:00:04 volumio volumio[15547]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 15 19:00:04 volumio volumio[15547]: info: Discovery: Started advertising with name: Volumio Sep 15 19:00:04 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "outputs"... Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "albumart"... Sep 15 19:00:04 volumio volumio[15547]: info: Plugin example_plugin is not enabled Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "inputs"... Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "updater_comm"... Sep 15 19:00:04 volumio volumio[15547]: info: Plugin mpdemulation is not enabled Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "rest_api"... Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "websocket"... Sep 15 19:00:04 volumio volumio[15547]: info: Starting Socket.io Server version 2.3.0 Sep 15 19:00:04 volumio volumio[15547]: info: Loading plugin "fusiondsp"... Sep 15 19:00:05 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 19:00:05 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 185. Sep 15 19:00:05 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 19:00:05 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 19:00:05 volumio volumio[15547]: Forking 3 albumart workers Sep 15 19:00:05 volumio volumio[15614]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 19:00:05 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 19:00:05 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 19:00:05 volumio volumio[15547]: info: Applying required configuration parameters for plugin fusiondsp Sep 15 19:00:05 volumio volumio[15547]: info: Loading i18n strings for locale de Sep 15 19:00:05 volumio volumio[15547]: Updating browse sources language Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::initPlayerControls Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:00:05 volumio volumio[15547]: Express server listening on port 3000 Sep 15 19:00:05 volumio volumio[15547]: [Metrics] WebUI: 4s 839.76ms Sep 15 19:00:05 volumio volumio[15547]: info: CoreStateMachine::resetVolumioState Sep 15 19:00:05 volumio volumio[15547]: info: CoreStateMachine::getcurrentVolume Sep 15 19:00:05 volumio volumio[15547]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 19:00:05 volumio volumio[15547]: info: Volumio Network Manager: Network status updated: 1 Sep 15 19:00:06 volumio volumio[15547]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 15 19:00:06 volumio volumio[15547]: wlan0 Interface doesn't support scanning. Sep 15 19:00:06 volumio volumio[15547]: info: Cannot use regular scanning, forcing with ap-force Sep 15 19:00:06 volumio sudo[15646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 15 19:00:06 volumio sudo[15646]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:06 volumio sudo[15646]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:06 volumio volumio[15547]: command failed: No such device (-19) Sep 15 19:00:06 volumio volumio[15547]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 15 19:00:06 volumio volumio[15547]: command failed: No such device (-19) Sep 15 19:00:06 volumio volumio[15547]: Starting albumart workers Sep 15 19:00:06 volumio volumio[15547]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 15 19:00:06 volumio volumio[15547]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 15 19:00:06 volumio volumio-remote-updater[575]: [2025-09-15 19:00:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757955604 101 Sep 15 19:00:06 volumio volumio[15547]: 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: 3 Sep 15 19:00:06 volumio volumio[15547]: verbose: New Socket.io Connection to 192.168.188.162:3000 from 192.168.188.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 15 19:00:06 volumio volumio[15547]: info: CoreStateMachine::pushState Sep 15 19:00:06 volumio volumio[15547]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::volumioPushState Sep 15 19:00:06 volumio volumio[15547]: info: CoreStateMachine::updateTrackBlock Sep 15 19:00:06 volumio volumio[15547]: info: CorePlayQueue::getTrackBlock Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 19:00:06 volumio volumio[15547]: Starting albumart workers Sep 15 19:00:06 volumio volumio[15547]: Starting albumart workers Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:06 volumio volumio[15547]: info: Reloading queue from file Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 15 19:00:06 volumio volumio[15547]: info: Setting Device type: Raspberry PI Sep 15 19:00:06 volumio volumio[15547]: info: CoreStateMachine::setRepeat null single undefined Sep 15 19:00:06 volumio volumio[15547]: info: CoreStateMachine::pushState Sep 15 19:00:06 volumio volumio[15547]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::volumioPushState Sep 15 19:00:06 volumio volumio[15547]: info: CoreStateMachine::setRandom null Sep 15 19:00:06 volumio volumio[15547]: info: CoreStateMachine::pushState Sep 15 19:00:06 volumio volumio[15547]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::volumioPushState Sep 15 19:00:06 volumio volumio[15547]: info: Completed loading Core Plugins Sep 15 19:00:06 volumio volumio[15547]: info: Preparing to generate the ALSA configuration file Sep 15 19:00:06 volumio volumio[15547]: info: CoreStateMachine::pushState Sep 15 19:00:06 volumio volumio[15547]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::volumioPushState Sep 15 19:00:06 volumio volumio[15547]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 19:00:06 volumio volumio[15547]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 19:00:06 volumio volumio[15547]: info: Reading ALSA contributions from plugins. Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::volumioGetState Sep 15 19:00:06 volumio volumio[15547]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:06 volumio volumio[15547]: info: Discovery: adding e470e2ce-0a34-4725-aaf5-aa5e94375e01 Sep 15 19:00:06 volumio volumio[15547]: info: Discovery: Found device VolumioHD Sep 15 19:00:06 volumio volumio[15547]: info: Discovery: Connecting to remote: 192.168.188.101 Sep 15 19:00:06 volumio volumio[15547]: info: Discovery: adding bed2b3d6-941c-4a98-93b9-b3df60fe4d86 Sep 15 19:00:06 volumio volumio[15547]: info: Discovery: Found device Volumio Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::volumioGetState Sep 15 19:00:06 volumio volumio[15547]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:06 volumio volumio[15547]: info: Discovery: Connected to remote: 192.168.188.101 Sep 15 19:00:06 volumio volumio[15547]: info: Asound.conf file unchanged, so no further update is needed Sep 15 19:00:06 volumio volumio[15547]: info: Output device has changed, restarting MPD Sep 15 19:00:06 volumio volumio[15547]: info: Output device has changed, restarting Shairport Sync Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:06 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:06 volumio sudo[15663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 19:00:06 volumio sudo[15663]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:06 volumio sudo[15663]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:06 volumio sudo[15665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 19:00:06 volumio sudo[15665]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:06 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 19:00:07 volumio systemd[1]: mpd.service: Succeeded. Sep 15 19:00:07 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 19:00:07 volumio volumio[15547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 19:00:07 volumio volumio[15547]: info: ___________ START PLUGINS ___________ Sep 15 19:00:07 volumio volumio[15547]: info: ControllerMpd::onStart: Initializing MPD Sep 15 19:00:07 volumio volumio[15547]: info: Creating MPD Configuration file Sep 15 19:00:07 volumio systemd[1]: Starting Music Player Daemon... Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 19:00:07 volumio volumio[15547]: info: [1757955607136] CoreMusicLibrary::Adding element Medienserver Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 19:00:07 volumio sudo[15681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 19:00:07 volumio sudo[15681]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:07 volumio sudo[15683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 19:00:07 volumio sudo[15683]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:07 volumio sudo[15681]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:07 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 15 19:00:07 volumio systemd[1]: mpd.service: Succeeded. Sep 15 19:00:07 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 19:00:07 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 19:00:07 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 186. Sep 15 19:00:07 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 19:00:07 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 19:00:07 volumio systemd[1]: Starting Music Player Daemon... Sep 15 19:00:07 volumio volumio[15547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:07 volumio volumio[15695]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 19:00:07 volumio volumio[15547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 19:00:07 volumio volumio[15547]: info: [1757955607339] CoreMusicLibrary::Adding element Last_100 Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 19:00:07 volumio volumio[15547]: info: [1757955607344] CoreMusicLibrary::Adding element Webradio Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 19:00:07 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 19:00:07 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 19:00:07 volumio volumio[15547]: info: Initializing BBC Radios Sep 15 19:00:07 volumio sudo[15698]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 19:00:07 volumio sudo[15698]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:07 volumio sudo[15698]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:07 volumio volumio[15547]: info: Loading i18n strings for locale de Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 15 19:00:07 volumio volumio[15547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:07 volumio volumio[15547]: info: FusionDsp - mixtype--------------------- Software Sep 15 19:00:07 volumio volumio[15547]: info: Volumio Calling Home Sep 15 19:00:07 volumio volumio[15547]: info: Preparing to generate the ALSA configuration file Sep 15 19:00:07 volumio volumio[15547]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 19:00:07 volumio volumio[15547]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 19:00:07 volumio volumio[15547]: info: Reading ALSA contributions from plugins. Sep 15 19:00:07 volumio volumio[15547]: info: MPD Permissions set Sep 15 19:00:07 volumio volumio[15547]: info: MPD Permissions set Sep 15 19:00:07 volumio volumio[15547]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 19:00:07 volumio volumio[15547]: Error: read ECONNRESET Sep 15 19:00:07 volumio volumio[15547]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Sep 15 19:00:07 volumio volumio[15547]: errno: -104, Sep 15 19:00:07 volumio volumio[15547]: code: 'ECONNRESET', Sep 15 19:00:07 volumio volumio[15547]: syscall: 'read' Sep 15 19:00:07 volumio volumio[15547]: } Sep 15 19:00:07 volumio volumio[15547]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 19:00:08 volumio sudo[15734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 18:59 Sep 15 19:00:08 volumio sudo[15734]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:08 volumio sudo[15734]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:08 volumio mpd[15717]: Sep 15 19:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 19:00:08 volumio systemd[1]: Started Music Player Daemon. Sep 15 19:00:08 volumio sudo[15665]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:08 volumio sudo[15683]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:09 volumio volumio-remote-updater[575]: [2025-09-15 19:00:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 15 19:00:09 volumio volumio-remote-updater[575]: [2025-09-15 19:00:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 15 19:00:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 15 19:00:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 15 19:00:09 volumio systemd[1]: Started dynamicswap service. Sep 15 19:00:09 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 19:00:09 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 15 19:00:09 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 48. Sep 15 19:00:09 volumio systemd[1]: Stopped Volumio Backend Module. Sep 15 19:00:09 volumio systemd[1]: Started Volumio Backend Module. Sep 15 19:00:09 volumio systemd[1]: Started dynamicswap service. Sep 15 19:00:09 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 19:00:09 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 187. Sep 15 19:00:09 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 19:00:09 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 19:00:09 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 19:00:09 volumio volumio[15751]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 19:00:09 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 19:00:09 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 19:00:11 volumio volumio[15749]: info: ------------------------------------------- Sep 15 19:00:11 volumio volumio[15749]: info: ----- Volumio3 ---- Sep 15 19:00:11 volumio volumio[15749]: info: ------------------------------------------- Sep 15 19:00:11 volumio volumio[15749]: info: ----- System startup ---- Sep 15 19:00:11 volumio volumio[15749]: info: ------------------------------------------- Sep 15 19:00:11 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 19:00:11 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 188. Sep 15 19:00:11 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 19:00:11 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 19:00:11 volumio volumio[15774]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 19:00:11 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 19:00:11 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 19:00:11 volumio volumio[15749]: info: MYVOLUMIO Environment detected Sep 15 19:00:11 volumio volumio[15749]: info: Plugin folders cleanup Sep 15 19:00:11 volumio volumio[15749]: info: Scanning into folder /volumio/app/plugins/ Sep 15 19:00:11 volumio volumio[15749]: info: Scanning category audio_interface Sep 15 19:00:11 volumio volumio[15749]: info: Scanning category miscellanea Sep 15 19:00:11 volumio volumio[15749]: info: Scanning category music_service Sep 15 19:00:11 volumio volumio[15749]: info: Scanning category plugins.json Sep 15 19:00:11 volumio volumio[15749]: info: Scanning category system_controller Sep 15 19:00:11 volumio volumio[15749]: info: Scanning category user_interface Sep 15 19:00:11 volumio volumio[15749]: info: Scanning into folder /data/plugins/ Sep 15 19:00:11 volumio volumio[15749]: info: Scanning category audio_interface Sep 15 19:00:11 volumio volumio[15749]: info: Plugin folders cleanup completed Sep 15 19:00:11 volumio volumio[15749]: info: ------------------------------------------- Sep 15 19:00:11 volumio volumio[15749]: info: ----- Core plugins startup ---- Sep 15 19:00:11 volumio volumio[15749]: info: ------------------------------------------- Sep 15 19:00:11 volumio volumio[15749]: info: Loading plugins from folder /volumio/app/plugins/ Sep 15 19:00:11 volumio volumio[15749]: info: Adding plugin upnp to MyMusic Plugins Sep 15 19:00:11 volumio volumio[15749]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 15 19:00:11 volumio volumio[15749]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 15 19:00:11 volumio volumio[15749]: info: Loading plugins from folder /data/plugins/ Sep 15 19:00:11 volumio volumio[15749]: info: Loading plugin "system"... Sep 15 19:00:12 volumio volumio[15749]: info: Loading plugin "appearance"... Sep 15 19:00:12 volumio volumio[15749]: info: Loading plugin "network"... Sep 15 19:00:13 volumio volumio[15749]: info: Refreshing Cached IP Addresses Sep 15 19:00:13 volumio sudo[15782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 19:00:13 volumio sudo[15782]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:13 volumio sudo[15782]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:13 volumio sudo[15784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 19:00:13 volumio sudo[15784]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:13 volumio volumio[15749]: info: Loading plugin "services"... Sep 15 19:00:13 volumio volumio[15749]: info: Loading plugin "alsa_controller"... Sep 15 19:00:13 volumio sudo[15793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 19:00:13 volumio sudo[15793]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:13 volumio sudo[15784]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:13 volumio sudo[15793]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:13 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 19:00:13 volumio volumio[15749]: info: Loading plugin "wizard"... Sep 15 19:00:13 volumio volumio[15749]: info: Loading plugin "networkfs"... Sep 15 19:00:13 volumio volumio[15749]: info: Starting Udev Watcher for removable devices Sep 15 19:00:13 volumio volumio[15749]: info: Ignoring mount for partition: boot Sep 15 19:00:13 volumio volumio[15749]: info: Ignoring mount for partition: volumio Sep 15 19:00:13 volumio volumio[15749]: info: Ignoring mount for partition: volumio_data Sep 15 19:00:13 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 19:00:13 volumio volumio[15749]: info: Loading plugin "volumio_command_line_client"... Sep 15 19:00:13 volumio volumio[15749]: info: Loading plugin "upnp"... Sep 15 19:00:13 volumio volumio[15749]: info: [1757955613234] Starting Upmpd Daemon Sep 15 19:00:13 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 19:00:13 volumio volumio[15749]: info: Loading plugin "my_music"... Sep 15 19:00:13 volumio volumio[15749]: info: Loading plugin "mpd"... Sep 15 19:00:13 volumio volumio[15749]: info: Loading plugin "upnp_browser"... Sep 15 19:00:13 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 19:00:13 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 189. Sep 15 19:00:13 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 19:00:13 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 19:00:13 volumio volumio[15811]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 19:00:13 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 19:00:13 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 19:00:13 volumio volumio[15749]: info: Loading plugin "alarm-clock"... Sep 15 19:00:14 volumio volumio-remote-updater[575]: [2025-09-15 19:00:14] [connect] Successful connection Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "airplay_emulation"... Sep 15 19:00:14 volumio volumio[15749]: info: Starting Shairport Sync Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "last_100"... Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "webradio"... Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "i2s_dacs"... Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "volumiodiscovery"... Sep 15 19:00:14 volumio volumio[15749]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 19:00:14 volumio volumio[15749]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 19:00:14 volumio volumio[15749]: *** WARNING *** For more information see Sep 15 19:00:14 volumio node[15749]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 19:00:14 volumio volumio[15749]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 19:00:14 volumio volumio[15749]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 19:00:14 volumio volumio[15749]: *** WARNING *** For more information see Sep 15 19:00:14 volumio node[15749]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 19:00:14 volumio node[15749]: *** WARNING *** For more information see Sep 15 19:00:14 volumio node[15749]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 19:00:14 volumio node[15749]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 19:00:14 volumio node[15749]: *** WARNING *** For more information see Sep 15 19:00:14 volumio volumio[15749]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 15 19:00:14 volumio volumio[15749]: info: Discovery: Started advertising with name: Volumio Sep 15 19:00:14 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "outputs"... Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "albumart"... Sep 15 19:00:14 volumio volumio[15749]: info: Plugin example_plugin is not enabled Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "inputs"... Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "updater_comm"... Sep 15 19:00:14 volumio volumio[15749]: info: Plugin mpdemulation is not enabled Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "rest_api"... Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "websocket"... Sep 15 19:00:14 volumio volumio[15749]: info: Starting Socket.io Server version 2.3.0 Sep 15 19:00:14 volumio volumio[15749]: info: Loading plugin "fusiondsp"... Sep 15 19:00:14 volumio volumio[15749]: Forking 3 albumart workers Sep 15 19:00:15 volumio volumio[15749]: info: Applying required configuration parameters for plugin fusiondsp Sep 15 19:00:15 volumio volumio[15749]: info: Loading i18n strings for locale de Sep 15 19:00:15 volumio volumio[15749]: Updating browse sources language Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::initPlayerControls Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:00:15 volumio volumio[15749]: Express server listening on port 3000 Sep 15 19:00:15 volumio volumio[15749]: [Metrics] WebUI: 4s 978.89ms Sep 15 19:00:15 volumio volumio[15749]: info: CoreStateMachine::resetVolumioState Sep 15 19:00:15 volumio volumio[15749]: info: CoreStateMachine::getcurrentVolume Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 19:00:15 volumio volumio[15749]: info: Volumio Network Manager: Network status updated: 1 Sep 15 19:00:15 volumio volumio[15749]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 15 19:00:15 volumio volumio[15749]: wlan0 Interface doesn't support scanning. Sep 15 19:00:15 volumio volumio[15749]: info: Cannot use regular scanning, forcing with ap-force Sep 15 19:00:15 volumio sudo[15852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 15 19:00:15 volumio sudo[15852]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:15 volumio sudo[15852]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:15 volumio volumio[15749]: command failed: No such device (-19) Sep 15 19:00:15 volumio volumio[15749]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 15 19:00:15 volumio volumio[15749]: command failed: No such device (-19) Sep 15 19:00:15 volumio volumio[15749]: verbose: New Socket.io Connection to 192.168.188.162 from 192.168.188.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 15 19:00:15 volumio volumio-remote-updater[575]: [2025-09-15 19:00:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757955614 101 Sep 15 19:00:15 volumio volumio[15749]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Sep 15 19:00:15 volumio volumio[15749]: verbose: New Socket.io Connection to 192.168.188.162:3000 from 192.168.188.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 15 19:00:15 volumio volumio[15749]: Starting albumart workers Sep 15 19:00:15 volumio volumio[15749]: info: CoreStateMachine::pushState Sep 15 19:00:15 volumio volumio[15749]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::volumioPushState Sep 15 19:00:15 volumio volumio[15749]: info: CoreStateMachine::updateTrackBlock Sep 15 19:00:15 volumio volumio[15749]: info: CorePlayQueue::getTrackBlock Sep 15 19:00:15 volumio volumio[15749]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 19:00:15 volumio volumio[15749]: Starting albumart workers Sep 15 19:00:15 volumio volumio[15749]: Starting albumart workers Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:16 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 19:00:16 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 190. Sep 15 19:00:16 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 19:00:16 volumio volumio[15749]: info: Reloading queue from file Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 15 19:00:16 volumio volumio[15749]: info: CoreStateMachine::setRepeat null single undefined Sep 15 19:00:16 volumio volumio[15749]: info: CoreStateMachine::pushState Sep 15 19:00:16 volumio volumio[15749]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::volumioPushState Sep 15 19:00:16 volumio volumio[15749]: info: CoreStateMachine::setRandom null Sep 15 19:00:16 volumio volumio[15749]: info: CoreStateMachine::pushState Sep 15 19:00:16 volumio volumio[15749]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::volumioPushState Sep 15 19:00:16 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 19:00:16 volumio volumio[15749]: info: Setting Device type: Raspberry PI Sep 15 19:00:16 volumio volumio[15749]: info: Completed loading Core Plugins Sep 15 19:00:16 volumio volumio[15749]: info: Preparing to generate the ALSA configuration file Sep 15 19:00:16 volumio volumio[15749]: info: CoreStateMachine::pushState Sep 15 19:00:16 volumio volumio[15749]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::volumioPushState Sep 15 19:00:16 volumio volumio[15749]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 19:00:16 volumio volumio[15749]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 19:00:16 volumio volumio[15749]: info: Reading ALSA contributions from plugins. Sep 15 19:00:16 volumio volumio[15867]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::volumioGetState Sep 15 19:00:16 volumio volumio[15749]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:16 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 19:00:16 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 19:00:16 volumio volumio[15749]: info: Discovery: adding e470e2ce-0a34-4725-aaf5-aa5e94375e01 Sep 15 19:00:16 volumio volumio[15749]: info: Discovery: Found device VolumioHD Sep 15 19:00:16 volumio volumio[15749]: info: Discovery: Connecting to remote: 192.168.188.101 Sep 15 19:00:16 volumio volumio[15749]: info: Asound.conf file unchanged, so no further update is needed Sep 15 19:00:16 volumio volumio[15749]: info: Output device has changed, restarting MPD Sep 15 19:00:16 volumio sudo[15870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 19:00:16 volumio volumio[15749]: info: Output device has changed, restarting Shairport Sync Sep 15 19:00:16 volumio sudo[15870]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:16 volumio sudo[15870]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:16 volumio sudo[15873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 19:00:16 volumio sudo[15873]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:16 volumio volumio[15749]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 19:00:16 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 19:00:16 volumio volumio[15749]: info: ___________ START PLUGINS ___________ Sep 15 19:00:16 volumio volumio[15749]: info: ControllerMpd::onStart: Initializing MPD Sep 15 19:00:16 volumio volumio[15749]: info: Creating MPD Configuration file Sep 15 19:00:16 volumio systemd[1]: mpd.service: Succeeded. Sep 15 19:00:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 19:00:16 volumio sudo[15879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 19:00:16 volumio sudo[15879]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 19:00:16 volumio volumio[15749]: info: [1757955616512] CoreMusicLibrary::Adding element Medienserver Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 19:00:16 volumio systemd[1]: Starting Music Player Daemon... Sep 15 19:00:16 volumio sudo[15879]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:16 volumio sudo[15882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 19:00:16 volumio sudo[15882]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 15 19:00:16 volumio systemd[1]: mpd.service: Succeeded. Sep 15 19:00:16 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 19:00:16 volumio systemd[1]: Starting Music Player Daemon... Sep 15 19:00:16 volumio volumio[15749]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:16 volumio volumio[15749]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 19:00:16 volumio volumio[15749]: info: [1757955616707] CoreMusicLibrary::Adding element Last_100 Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 19:00:16 volumio volumio[15749]: info: [1757955616711] CoreMusicLibrary::Adding element Webradio Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 19:00:16 volumio volumio[15749]: info: Initializing BBC Radios Sep 15 19:00:16 volumio sudo[15888]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 19:00:16 volumio sudo[15888]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:16 volumio sudo[15888]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:16 volumio volumio[15749]: info: Loading i18n strings for locale de Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 15 19:00:16 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:16 volumio volumio[15749]: info: FusionDsp - mixtype--------------------- Software Sep 15 19:00:16 volumio volumio[15749]: info: Volumio Calling Home Sep 15 19:00:17 volumio volumio[15749]: info: Preparing to generate the ALSA configuration file Sep 15 19:00:17 volumio volumio[15749]: info: Discovery: adding bed2b3d6-941c-4a98-93b9-b3df60fe4d86 Sep 15 19:00:17 volumio volumio[15749]: info: Discovery: Found device Volumio Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::volumioGetState Sep 15 19:00:17 volumio volumio[15749]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:17 volumio volumio[15749]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 15 19:00:17 volumio volumio[15749]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 19:00:17 volumio volumio[15749]: info: Reading ALSA contributions from plugins. Sep 15 19:00:17 volumio volumio[15749]: info: Discovery: Connected to remote: 192.168.188.101 Sep 15 19:00:17 volumio volumio[15749]: info: MPD Permissions set Sep 15 19:00:17 volumio volumio[15749]: info: MPD Permissions set Sep 15 19:00:17 volumio volumio[15749]: info: Volumio called home Sep 15 19:00:17 volumio volumio[15749]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: Starting Shairport Sync Sep 15 19:00:17 volumio volumio[15749]: info: Starting Shairport Sync Sep 15 19:00:17 volumio volumio[15749]: info: Starting Shairport Sync Sep 15 19:00:17 volumio sudo[15940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 19:00:17 volumio sudo[15940]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:17 volumio sudo[15942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::volumioGetState Sep 15 19:00:17 volumio volumio[15749]: info: CorePlayQueue::getTrack 0 Sep 15 19:00:17 volumio sudo[15942]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:17 volumio sudo[15945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 19:00:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 19:00:17 volumio sudo[15945]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:17 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 19:00:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 19:00:17 volumio volumio[15749]: info: Asound.conf file unchanged, so no further update is needed Sep 15 19:00:17 volumio volumio[15749]: info: Output device has changed, restarting MPD Sep 15 19:00:17 volumio sudo[15950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 19:00:17 volumio sudo[15950]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:17 volumio volumio[15749]: info: Output device has changed, restarting Shairport Sync Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:17 volumio sudo[15950]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:17 volumio sudo[15952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 19:00:17 volumio sudo[15952]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 19:00:17 volumio sudo[15940]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:17 volumio volumio[15749]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 19:00:17 volumio shairport-sync[15949]: free(): double free detected in tcache 2 Sep 15 19:00:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 19:00:17 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=6/ABRT Sep 15 19:00:17 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. Sep 15 19:00:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 19:00:17 volumio volumio[15749]: info: MPD Permissions set Sep 15 19:00:17 volumio volumio[15749]: info: Shairport-Sync Started Sep 15 19:00:17 volumio volumio[15749]: Error adding Membership: Error: addMembership EINVAL Sep 15 19:00:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 15 19:00:17 volumio systemd[1]: mpd.service: Succeeded. Sep 15 19:00:17 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 19:00:17 volumio sudo[15942]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:17 volumio sudo[15945]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 19:00:17 volumio systemd[1]: Starting Music Player Daemon... Sep 15 19:00:17 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:17 volumio volumio[15749]: info: Shairport-Sync Started Sep 15 19:00:17 volumio volumio[15749]: info: Shairport-Sync Started Sep 15 19:00:17 volumio volumio[15749]: info: Starting Shairport Sync Sep 15 19:00:17 volumio sudo[15970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 19:00:17 volumio sudo[15970]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 19:00:18 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 19:00:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 19:00:18 volumio sudo[15965]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 19:00:18 volumio sudo[15965]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:18 volumio sudo[15965]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 19:00:18 volumio sudo[15970]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:18 volumio volumio[15749]: info: Shairport-Sync Started Sep 15 19:00:18 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 19:00:18 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 191. Sep 15 19:00:18 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 19:00:18 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 19:00:18 volumio volumio[15978]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Sep 15 19:00:18 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Sep 15 19:00:18 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 19:00:18 volumio volumio[15749]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 15 19:00:18 volumio volumio[15749]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 15 19:00:18 volumio volumio[15749]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 15 19:00:18 volumio volumio[15749]: info: camilladsp service started and running in background, instance 1 Sep 15 19:00:18 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 19:00:18 volumio volumio[15749]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 19:00:18 volumio volumio[15749]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 15 19:00:18 volumio volumio[15749]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 19:00:18 volumio volumio[15749]: info: FusionDsp loaded Sep 15 19:00:18 volumio volumio[15749]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 19:00:19 volumio sudo[15989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 15 19:00:19 volumio sudo[15989]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 19:00:19 volumio volumio[15749]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 15 19:00:19 volumio volumio[15749]: info: Adding Signal Path Element [object Object] Sep 15 19:00:19 volumio volumio[15749]: info: Adding fusiondspeq DSP Signal Path Element Sep 15 19:00:19 volumio volumio[15749]: info: FusionDsp - ---- installed callbackRead Sep 15 19:00:19 volumio volumio[15749]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 19:00:19 volumio volumio[15749]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 15 19:00:19 volumio volumio[15749]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 15 19:00:19 volumio volumio[15749]: at onErrorNT (internal/child_process.js:465:16) Sep 15 19:00:19 volumio volumio[15749]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 15 19:00:19 volumio volumio[15749]: errno: -2, Sep 15 19:00:19 volumio volumio[15749]: code: 'ENOENT', Sep 15 19:00:19 volumio volumio[15749]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 19:00:19 volumio volumio[15749]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 19:00:19 volumio volumio[15749]: spawnargs: [ Sep 15 19:00:19 volumio volumio[15749]: '-p', Sep 15 19:00:19 volumio volumio[15749]: 9876, Sep 15 19:00:19 volumio volumio[15749]: '-o', Sep 15 19:00:19 volumio volumio[15749]: '/tmp/camilladsp.log', Sep 15 19:00:19 volumio volumio[15749]: '-l', Sep 15 19:00:19 volumio volumio[15749]: 'warn', Sep 15 19:00:19 volumio volumio[15749]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 15 19:00:19 volumio volumio[15749]: ] Sep 15 19:00:19 volumio volumio[15749]: } Sep 15 19:00:19 volumio volumio[15749]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 19:00:19 volumio mpd[15975]: Sep 15 19:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 19:00:19 volumio systemd[1]: Started Music Player Daemon. Sep 15 19:00:19 volumio sudo[15952]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:19 volumio sudo[15873]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:19 volumio sudo[15882]: pam_unix(sudo:session): session closed for user root Sep 15 19:00:19 volumio sudo[16002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 18:59 Sep 15 19:00:19 volumio sudo[16002]: 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"