-- Logs begin at Wed 2025-07-02 16:13:11 CEST, end at Sat 2025-07-05 09:39:36 CEST. -- Jul 05 09:38:00 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:00 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:00 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:00 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:00 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:01 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:01 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:01 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:01 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:02 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:02 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:02 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:02 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:03 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:03 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:03 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:04 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:04 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:04 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:05 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:05 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:05 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:05 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:06 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:06 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:06 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:07 denon volumio[3155]: info: FusionDsp - Jul 05 09:38:08 denon volumio[3155]: <--- Last few GCs ---> Jul 05 09:38:08 denon volumio[3155]: [3155:0x3c75788] 30640 ms: Scavenge 124.6 (128.1) -> 124.5 (128.4) MB, 10.0 / 0.0 ms (average mu = 0.811, current mu = 0.808) allocation failure Jul 05 09:38:08 denon volumio[3155]: [3155:0x3c75788] 30663 ms: Scavenge 124.8 (128.4) -> 124.7 (128.4) MB, 5.2 / 0.0 ms (average mu = 0.811, current mu = 0.808) allocation failure Jul 05 09:38:08 denon volumio[3155]: [3155:0x3c75788] 30685 ms: Scavenge 125.0 (128.4) -> 124.8 (128.6) MB, 4.9 / 0.0 ms (average mu = 0.811, current mu = 0.808) allocation failure Jul 05 09:38:08 denon volumio[3155]: <--- JS stacktrace ---> Jul 05 09:38:08 denon volumio[3155]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory Jul 05 09:38:08 denon volumio-remote-updater[493]: [2025-07-05 09:38:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 09:38:08 denon volumio-remote-updater[493]: [2025-07-05 09:38:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 09:38:08 denon systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT Jul 05 09:38:08 denon systemd[1]: volumio.service: Failed with result 'signal'. Jul 05 09:38:08 denon systemd[1]: Started dynamicswap service. Jul 05 09:38:08 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:38:08 denon systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 09:38:08 denon systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Jul 05 09:38:08 denon systemd[1]: Started dynamicswap service. Jul 05 09:38:08 denon systemd[1]: Stopped Volumio Backend Module. Jul 05 09:38:08 denon systemd[1]: Started Volumio Backend Module. Jul 05 09:38:08 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:38:10 denon volumio[3365]: info: ------------------------------------------- Jul 05 09:38:10 denon volumio[3365]: info: ----- Volumio3 ---- Jul 05 09:38:10 denon volumio[3365]: info: ------------------------------------------- Jul 05 09:38:10 denon volumio[3365]: info: ----- System startup ---- Jul 05 09:38:10 denon volumio[3365]: info: ------------------------------------------- Jul 05 09:38:12 denon volumio[3365]: info: MYVOLUMIO Environment detected Jul 05 09:38:12 denon volumio[3365]: info: Plugin folders cleanup Jul 05 09:38:12 denon volumio[3365]: info: Scanning into folder /volumio/app/plugins/ Jul 05 09:38:12 denon volumio[3365]: info: Scanning category audio_interface Jul 05 09:38:12 denon volumio[3365]: info: Scanning category miscellanea Jul 05 09:38:12 denon volumio[3365]: info: Scanning category music_service Jul 05 09:38:12 denon volumio[3365]: info: Scanning category plugins.json Jul 05 09:38:12 denon volumio[3365]: info: Scanning category system_controller Jul 05 09:38:12 denon volumio[3365]: info: Scanning category user_interface Jul 05 09:38:12 denon volumio[3365]: info: Scanning into folder /data/plugins/ Jul 05 09:38:12 denon volumio[3365]: info: Scanning category audio_interface Jul 05 09:38:12 denon volumio[3365]: info: Plugin folders cleanup completed Jul 05 09:38:12 denon volumio[3365]: info: ------------------------------------------- Jul 05 09:38:12 denon volumio[3365]: info: ----- Core plugins startup ---- Jul 05 09:38:12 denon volumio[3365]: info: ------------------------------------------- Jul 05 09:38:12 denon volumio[3365]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 09:38:12 denon volumio[3365]: info: Adding plugin upnp to MyMusic Plugins Jul 05 09:38:12 denon volumio[3365]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 09:38:12 denon volumio[3365]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 09:38:12 denon volumio[3365]: info: Loading plugins from folder /data/plugins/ Jul 05 09:38:12 denon volumio[3365]: info: Loading plugin "system"... Jul 05 09:38:12 denon volumio[3365]: info: Loading plugin "appearance"... Jul 05 09:38:13 denon volumio-remote-updater[493]: [2025-07-05 09:38:13] [connect] Successful connection Jul 05 09:38:13 denon volumio[3365]: info: Loading plugin "network"... Jul 05 09:38:13 denon volumio[3365]: info: Refreshing Cached IP Addresses Jul 05 09:38:14 denon volumio[3365]: info: Loading plugin "services"... Jul 05 09:38:14 denon volumio[3365]: info: Loading plugin "alsa_controller"... Jul 05 09:38:14 denon sudo[3394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 09:38:14 denon sudo[3396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 09:38:14 denon sudo[3394]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:14 denon sudo[3396]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:14 denon sudo[3396]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:14 denon sudo[3404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 09:38:14 denon sudo[3404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:14 denon sudo[3394]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:14 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:14 denon volumio[3365]: info: Loading plugin "wizard"... Jul 05 09:38:14 denon volumio[3365]: info: Loading plugin "networkfs"... Jul 05 09:38:14 denon volumio[3365]: info: Starting Udev Watcher for removable devices Jul 05 09:38:14 denon volumio[3365]: info: Ignoring mount for partition: boot Jul 05 09:38:14 denon volumio[3365]: info: Ignoring mount for partition: volumio Jul 05 09:38:14 denon volumio[3365]: info: Ignoring mount for partition: volumio_data Jul 05 09:38:14 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:14 denon volumio[3365]: info: Loading plugin "volumio_command_line_client"... Jul 05 09:38:14 denon volumio[3365]: info: Loading plugin "upnp"... Jul 05 09:38:14 denon volumio[3365]: info: [1751701094440] Starting Upmpd Daemon Jul 05 09:38:14 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:14 denon volumio[3365]: info: Loading plugin "my_music"... Jul 05 09:38:14 denon volumio[3365]: info: Loading plugin "mpd"... Jul 05 09:38:14 denon sudo[3404]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:14 denon volumio[3365]: info: Loading plugin "upnp_browser"... Jul 05 09:38:15 denon volumio[3365]: info: Loading plugin "alarm-clock"... Jul 05 09:38:15 denon volumio[3365]: info: Loading plugin "airplay_emulation"... Jul 05 09:38:15 denon volumio[3365]: info: Starting Shairport Sync Jul 05 09:38:15 denon volumio[3365]: info: Loading plugin "last_100"... Jul 05 09:38:15 denon volumio[3365]: info: Loading plugin "webradio"... Jul 05 09:38:15 denon volumio[3365]: info: Loading plugin "i2s_dacs"... Jul 05 09:38:15 denon volumio[3365]: info: I2S DAC not set, start Auto-detection Jul 05 09:38:16 denon volumio[3365]: info: Loading plugin "volumiodiscovery"... Jul 05 09:38:16 denon volumio[3365]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:16 denon volumio[3365]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:16 denon volumio[3365]: *** WARNING *** For more information see Jul 05 09:38:16 denon volumio[3365]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:16 denon volumio[3365]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:16 denon node[3365]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:16 denon volumio[3365]: *** WARNING *** For more information see Jul 05 09:38:16 denon node[3365]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:16 denon node[3365]: *** WARNING *** For more information see Jul 05 09:38:16 denon node[3365]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:16 denon node[3365]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:16 denon node[3365]: *** WARNING *** For more information see Jul 05 09:38:16 denon volumio[3365]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 09:38:16 denon volumio[3365]: info: Discovery: Started advertising with name: Denon Jul 05 09:38:16 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:16 denon volumio[3365]: info: Loading plugin "outputs"... Jul 05 09:38:16 denon volumio[3365]: info: Loading plugin "albumart"... Jul 05 09:38:16 denon volumio[3365]: info: Plugin example_plugin is not enabled Jul 05 09:38:16 denon volumio[3365]: info: Loading plugin "inputs"... Jul 05 09:38:16 denon volumio[3365]: info: Loading plugin "updater_comm"... Jul 05 09:38:16 denon volumio[3365]: info: Plugin mpdemulation is not enabled Jul 05 09:38:16 denon volumio[3365]: info: Loading plugin "rest_api"... Jul 05 09:38:16 denon volumio[3365]: info: Loading plugin "websocket"... Jul 05 09:38:16 denon volumio[3365]: info: Starting Socket.io Server version 2.3.0 Jul 05 09:38:16 denon volumio[3365]: info: Loading plugin "fusiondsp"... Jul 05 09:38:16 denon volumio[3365]: Forking 3 albumart workers Jul 05 09:38:17 denon volumio[3365]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 09:38:17 denon volumio[3365]: info: Loading i18n strings for locale de Jul 05 09:38:17 denon volumio[3365]: Updating browse sources language Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::initPlayerControls Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:17 denon volumio[3365]: Starting albumart workers Jul 05 09:38:17 denon volumio[3365]: Express server listening on port 3000 Jul 05 09:38:17 denon volumio[3365]: [Metrics] WebUI: 7s 677.22ms Jul 05 09:38:17 denon volumio[3365]: info: CoreStateMachine::resetVolumioState Jul 05 09:38:17 denon volumio[3365]: info: CoreStateMachine::getcurrentVolume Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:38:17 denon volumio[3365]: Starting albumart workers Jul 05 09:38:17 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:18 denon volumio[3365]: Starting albumart workers Jul 05 09:38:18 denon volumio[3365]: info: Volumio Network Manager: Network status updated: 2 Jul 05 09:38:18 denon volumio-remote-updater[493]: [2025-07-05 09:38:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751701093 101 Jul 05 09:38:18 denon volumio-remote-updater[493]: [2025-07-05 09:38:18] [disconnect] Disconnect close local:[1006,The opening handshake timed out] remote:[1006] Jul 05 09:38:18 denon volumio[3365]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jul 05 09:38:18 denon volumio[3365]: info: Reloading queue from file Jul 05 09:38:18 denon volumio[3365]: info: CoreStateMachine::setRepeat null single undefined Jul 05 09:38:18 denon volumio[3365]: info: CoreStateMachine::pushState Jul 05 09:38:18 denon volumio[3365]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:18 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:38:18 denon volumio[3365]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:18 denon volumio[3365]: info: CoreStateMachine::setRandom null Jul 05 09:38:18 denon volumio[3365]: info: CoreStateMachine::pushState Jul 05 09:38:18 denon volumio[3365]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:18 denon volumio[3365]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:18 denon volumio[3365]: info: Setting Device type: Raspberry PI Jul 05 09:38:18 denon volumio[3365]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:38:18 denon volumio[3365]: info: CoreStateMachine::pushState Jul 05 09:38:18 denon volumio[3365]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:18 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:38:18 denon volumio[3365]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:18 denon volumio[3365]: info: CoreStateMachine::updateTrackBlock Jul 05 09:38:18 denon volumio[3365]: info: CorePlayQueue::getTrackBlock Jul 05 09:38:18 denon volumio[3365]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:38:18 denon volumio[3365]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 09:38:18 denon volumio[3365]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 09:38:18 denon volumio[3365]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 09:38:18 denon volumio[3365]: info: Completed loading Core Plugins Jul 05 09:38:18 denon volumio[3365]: info: Preparing to generate the ALSA configuration file Jul 05 09:38:18 denon volumio[3365]: info: Discovery: adding 1af3cffb-92c8-4da2-aa66-3855daa60f7d Jul 05 09:38:18 denon volumio[3365]: info: Discovery: Found device Denon Jul 05 09:38:18 denon volumio[3365]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:18 denon volumio[3365]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:18 denon volumio[3365]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:38:18 denon volumio[3365]: info: Reading ALSA contributions from plugins. Jul 05 09:38:18 denon volumio[3365]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:38:18 denon volumio[3365]: info: CoreStateMachine::pushState Jul 05 09:38:18 denon volumio[3365]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:18 denon volumio[3365]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:18 denon volumio[3365]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:38:18 denon volumio[3365]: info: Output device has changed, restarting MPD Jul 05 09:38:18 denon volumio[3365]: info: Output device has changed, restarting Shairport Sync Jul 05 09:38:18 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:18 denon sudo[3487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:18 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:18 denon sudo[3487]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:18 denon sudo[3487]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:18 denon sudo[3493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:18 denon sudo[3493]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:18 denon volumio[3365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:18 denon systemd[1]: Stopping Music Player Daemon... Jul 05 09:38:18 denon volumio[3365]: info: ___________ START PLUGINS ___________ Jul 05 09:38:18 denon volumio[3365]: info: ControllerMpd::onStart: Initializing MPD Jul 05 09:38:18 denon volumio[3365]: info: Creating MPD Configuration file Jul 05 09:38:18 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:18 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:18 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:18 denon sudo[3500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:18 denon sudo[3500]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:19 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:19 denon volumio[3365]: info: [1751701098996] CoreMusicLibrary::Adding element Medienserver Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:19 denon sudo[3500]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:19 denon sudo[3503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:19 denon sudo[3503]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:19 denon systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 09:38:19 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:19 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:19 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:19 denon volumio[3365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:19 denon volumio[3365]: info: [1751701099161] CoreMusicLibrary::Adding element Last_100 Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:19 denon volumio[3365]: info: [1751701099165] CoreMusicLibrary::Adding element Webradio Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:19 denon volumio[3365]: info: Initializing BBC Radios Jul 05 09:38:19 denon volumio[3365]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:19 denon sudo[3509]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:38:19 denon sudo[3509]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:19 denon sudo[3509]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: Loading i18n strings for locale de Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: FusionDsp - mixtype--------------------- Hardware Jul 05 09:38:19 denon volumio[3365]: info: Volumio Calling Home Jul 05 09:38:19 denon volumio[3365]: info: Preparing to generate the ALSA configuration file Jul 05 09:38:19 denon volumio[3365]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:38:19 denon volumio[3365]: info: Reading ALSA contributions from plugins. Jul 05 09:38:19 denon volumio[3365]: info: MPD Permissions set Jul 05 09:38:19 denon volumio[3365]: info: MPD Permissions set Jul 05 09:38:19 denon volumio[3365]: info: Volumio called home Jul 05 09:38:19 denon volumio[3365]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:19 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:20 denon sudo[3537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 09:38:20 denon sudo[3537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:20 denon volumio[3365]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:20 denon volumio[3365]: info: Starting Shairport Sync Jul 05 09:38:20 denon sudo[3539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 09:38:20 denon sudo[3539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:20 denon sudo[3537]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:20 denon volumio[3365]: info: Starting Shairport Sync Jul 05 09:38:20 denon sudo[3539]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:20 denon volumio[3365]: info: Starting Shairport Sync Jul 05 09:38:20 denon sudo[3545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:20 denon sudo[3545]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:20 denon sudo[3547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:20 denon sudo[3550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:20 denon sudo[3550]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:20 denon sudo[3547]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:20 denon volumio[3365]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:38:20 denon volumio[3365]: info: Output device has changed, restarting MPD Jul 05 09:38:20 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:38:20 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:38:20 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:20 denon sudo[3554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:20 denon sudo[3554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:20 denon volumio[3365]: info: Output device has changed, restarting Shairport Sync Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:20 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:20 denon sudo[3554]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:20 denon sudo[3557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:20 denon sudo[3557]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:20 denon sudo[3545]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:20 denon sudo[3547]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:20 denon sudo[3550]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:20 denon volumio[3365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:20 denon systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 09:38:20 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:20 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:20 denon volumio[3365]: info: MPD Permissions set Jul 05 09:38:20 denon volumio[3365]: info: Shairport-Sync Started Jul 05 09:38:20 denon volumio[3365]: Error adding Membership: Error: addMembership EINVAL Jul 05 09:38:20 denon volumio[3365]: info: Shairport-Sync Started Jul 05 09:38:20 denon volumio[3365]: info: Shairport-Sync Started Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:20 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:20 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:20 denon volumio[3365]: info: Starting Shairport Sync Jul 05 09:38:20 denon volumio[3365]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jul 05 09:38:20 denon sudo[3572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:20 denon sudo[3572]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:20 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:38:20 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:38:20 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:20 denon sudo[3564]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:38:20 denon sudo[3564]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:20 denon sudo[3564]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:20 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:20 denon sudo[3572]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:20 denon volumio[3365]: info: Shairport-Sync Started Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:21 denon volumio[3365]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 09:38:21 denon volumio[3365]: info: Received Get System Info Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 09:38:21 denon volumio[3365]: info: Discovery: Getting this device information Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:21 denon volumio[3365]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:21 denon volumio[3365]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:21 denon volumio[3365]: info: Listing playlists Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 05 09:38:21 denon volumio[3365]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:38:21 denon volumio[3365]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jul 05 09:38:21 denon volumio[3365]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:38:21 denon volumio[3365]: info: camilladsp service started and running in background, instance 1 Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:21 denon volumio[3365]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:38:21 denon volumio[3365]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 09:38:21 denon volumio[3365]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:38:21 denon volumio[3365]: info: FusionDsp loaded Jul 05 09:38:21 denon volumio[3365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:38:21 denon sudo[3590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 09:38:21 denon sudo[3590]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:21 denon volumio[3365]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 09:38:21 denon volumio[3365]: info: Adding Signal Path Element [object Object] Jul 05 09:38:21 denon volumio[3365]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 09:38:21 denon sudo[3590]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:21 denon volumio[3365]: info: FusionDsp - ---- installed callbackRead Jul 05 09:38:21 denon volumio[3365]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:38:21 denon volumio[3365]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 09:38:21 denon volumio[3365]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 09:38:21 denon volumio[3365]: at onErrorNT (internal/child_process.js:465:16) Jul 05 09:38:21 denon volumio[3365]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 05 09:38:21 denon volumio[3365]: errno: -2, Jul 05 09:38:21 denon volumio[3365]: code: 'ENOENT', Jul 05 09:38:21 denon volumio[3365]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:38:21 denon volumio[3365]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:38:21 denon volumio[3365]: spawnargs: [ Jul 05 09:38:21 denon volumio[3365]: '-p', Jul 05 09:38:21 denon volumio[3365]: 9876, Jul 05 09:38:21 denon volumio[3365]: '-o', Jul 05 09:38:21 denon volumio[3365]: '/tmp/camilladsp.log', Jul 05 09:38:21 denon volumio[3365]: '-l', Jul 05 09:38:21 denon volumio[3365]: 'warn', Jul 05 09:38:21 denon volumio[3365]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 09:38:21 denon volumio[3365]: ] Jul 05 09:38:21 denon volumio[3365]: } Jul 05 09:38:21 denon volumio[3365]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:38:22 denon sudo[3638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 09:37 Jul 05 09:38:22 denon sudo[3638]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:22 denon sudo[3638]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:22 denon systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 09:38:22 denon sudo[3557]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:22 denon sudo[3493]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:22 denon sudo[3503]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:22 denon systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 09:38:22 denon systemd[1]: Started dynamicswap service. Jul 05 09:38:22 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:38:22 denon mpd[3576]: Jul 05 09:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 09:38:22 denon systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 09:38:22 denon systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Jul 05 09:38:22 denon systemd[1]: Started Music Player Daemon. Jul 05 09:38:22 denon systemd[1]: Started dynamicswap service. Jul 05 09:38:22 denon systemd[1]: Stopped Volumio Backend Module. Jul 05 09:38:22 denon systemd[1]: Started Volumio Backend Module. Jul 05 09:38:23 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:38:23 denon volumio-remote-updater[493]: [2025-07-05 09:38:23] [info] asio async_connect error: system:111 (Connection refused) Jul 05 09:38:23 denon volumio-remote-updater[493]: [2025-07-05 09:38:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 05 09:38:23 denon volumio-remote-updater[493]: [2025-07-05 09:38:23] [error] handle_connect error: Underlying Transport Error Jul 05 09:38:25 denon volumio[3651]: info: ------------------------------------------- Jul 05 09:38:25 denon volumio[3651]: info: ----- Volumio3 ---- Jul 05 09:38:25 denon volumio[3651]: info: ------------------------------------------- Jul 05 09:38:25 denon volumio[3651]: info: ----- System startup ---- Jul 05 09:38:25 denon volumio[3651]: info: ------------------------------------------- Jul 05 09:38:25 denon volumio[3651]: info: MYVOLUMIO Environment detected Jul 05 09:38:26 denon volumio[3651]: info: Plugin folders cleanup Jul 05 09:38:26 denon volumio[3651]: info: Scanning into folder /volumio/app/plugins/ Jul 05 09:38:26 denon volumio[3651]: info: Scanning category audio_interface Jul 05 09:38:26 denon volumio[3651]: info: Scanning category miscellanea Jul 05 09:38:26 denon volumio[3651]: info: Scanning category music_service Jul 05 09:38:26 denon volumio[3651]: info: Scanning category plugins.json Jul 05 09:38:26 denon volumio[3651]: info: Scanning category system_controller Jul 05 09:38:26 denon volumio[3651]: info: Scanning category user_interface Jul 05 09:38:26 denon volumio[3651]: info: Scanning into folder /data/plugins/ Jul 05 09:38:26 denon volumio[3651]: info: Scanning category audio_interface Jul 05 09:38:26 denon volumio[3651]: info: Plugin folders cleanup completed Jul 05 09:38:26 denon volumio[3651]: info: ------------------------------------------- Jul 05 09:38:26 denon volumio[3651]: info: ----- Core plugins startup ---- Jul 05 09:38:26 denon volumio[3651]: info: ------------------------------------------- Jul 05 09:38:26 denon volumio[3651]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 09:38:26 denon volumio[3651]: info: Adding plugin upnp to MyMusic Plugins Jul 05 09:38:26 denon volumio[3651]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 09:38:26 denon volumio[3651]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 09:38:26 denon volumio[3651]: info: Loading plugins from folder /data/plugins/ Jul 05 09:38:26 denon volumio[3651]: info: Loading plugin "system"... Jul 05 09:38:26 denon volumio[3651]: info: Loading plugin "appearance"... Jul 05 09:38:27 denon volumio[3651]: info: Loading plugin "network"... Jul 05 09:38:27 denon volumio[3651]: info: Refreshing Cached IP Addresses Jul 05 09:38:27 denon sudo[3677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 09:38:27 denon sudo[3677]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:27 denon sudo[3679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 09:38:27 denon sudo[3679]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:27 denon sudo[3679]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:27 denon volumio[3651]: info: Loading plugin "services"... Jul 05 09:38:27 denon volumio[3651]: info: Loading plugin "alsa_controller"... Jul 05 09:38:27 denon sudo[3677]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:27 denon sudo[3690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 09:38:27 denon sudo[3690]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:27 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:27 denon volumio[3651]: info: Loading plugin "wizard"... Jul 05 09:38:27 denon volumio[3651]: info: Loading plugin "networkfs"... Jul 05 09:38:27 denon volumio[3651]: info: Starting Udev Watcher for removable devices Jul 05 09:38:27 denon volumio[3651]: info: Ignoring mount for partition: boot Jul 05 09:38:27 denon volumio[3651]: info: Ignoring mount for partition: volumio Jul 05 09:38:27 denon volumio[3651]: info: Ignoring mount for partition: volumio_data Jul 05 09:38:27 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:27 denon volumio[3651]: info: Loading plugin "volumio_command_line_client"... Jul 05 09:38:27 denon volumio[3651]: info: Loading plugin "upnp"... Jul 05 09:38:27 denon volumio[3651]: info: [1751701107588] Starting Upmpd Daemon Jul 05 09:38:27 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:27 denon volumio[3651]: info: Loading plugin "my_music"... Jul 05 09:38:27 denon volumio[3651]: info: Loading plugin "mpd"... Jul 05 09:38:28 denon volumio[3651]: info: Loading plugin "upnp_browser"... Jul 05 09:38:28 denon sudo[3690]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:28 denon volumio[3651]: info: Loading plugin "alarm-clock"... Jul 05 09:38:28 denon volumio[3651]: info: Loading plugin "airplay_emulation"... Jul 05 09:38:28 denon volumio[3651]: info: Starting Shairport Sync Jul 05 09:38:28 denon volumio[3651]: info: Loading plugin "last_100"... Jul 05 09:38:28 denon volumio[3651]: info: Loading plugin "webradio"... Jul 05 09:38:28 denon volumio[3651]: info: Loading plugin "i2s_dacs"... Jul 05 09:38:28 denon volumio[3651]: info: I2S DAC not set, start Auto-detection Jul 05 09:38:28 denon volumio[3651]: info: Loading plugin "volumiodiscovery"... Jul 05 09:38:28 denon volumio[3651]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:28 denon volumio[3651]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:28 denon volumio[3651]: *** WARNING *** For more information see Jul 05 09:38:28 denon node[3651]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:28 denon volumio[3651]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:28 denon volumio[3651]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:28 denon volumio[3651]: *** WARNING *** For more information see Jul 05 09:38:28 denon node[3651]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:28 denon node[3651]: *** WARNING *** For more information see Jul 05 09:38:28 denon node[3651]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:28 denon node[3651]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:28 denon node[3651]: *** WARNING *** For more information see Jul 05 09:38:28 denon volumio[3651]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 09:38:28 denon volumio[3651]: info: Discovery: Started advertising with name: Denon Jul 05 09:38:28 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:28 denon volumio[3651]: info: Loading plugin "outputs"... Jul 05 09:38:28 denon volumio[3651]: info: Loading plugin "albumart"... Jul 05 09:38:28 denon volumio[3651]: info: Plugin example_plugin is not enabled Jul 05 09:38:28 denon volumio[3651]: info: Loading plugin "inputs"... Jul 05 09:38:28 denon volumio[3651]: info: Loading plugin "updater_comm"... Jul 05 09:38:29 denon volumio[3651]: info: Plugin mpdemulation is not enabled Jul 05 09:38:29 denon volumio[3651]: info: Loading plugin "rest_api"... Jul 05 09:38:29 denon volumio[3651]: info: Loading plugin "websocket"... Jul 05 09:38:29 denon volumio[3651]: info: Starting Socket.io Server version 2.3.0 Jul 05 09:38:29 denon volumio[3651]: info: Loading plugin "fusiondsp"... Jul 05 09:38:29 denon volumio[3651]: Forking 3 albumart workers Jul 05 09:38:29 denon volumio[3651]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 09:38:29 denon volumio[3651]: info: Loading i18n strings for locale de Jul 05 09:38:29 denon volumio[3651]: Updating browse sources language Jul 05 09:38:29 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::initPlayerControls Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:30 denon volumio[3651]: Express server listening on port 3000 Jul 05 09:38:30 denon volumio[3651]: [Metrics] WebUI: 5s 899.54ms Jul 05 09:38:30 denon volumio[3651]: info: CoreStateMachine::resetVolumioState Jul 05 09:38:30 denon volumio[3651]: info: CoreStateMachine::getcurrentVolume Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:30 denon volumio[3651]: info: Volumio Network Manager: Network status updated: 2 Jul 05 09:38:30 denon volumio[3651]: Starting albumart workers Jul 05 09:38:30 denon volumio[3651]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 05 09:38:30 denon volumio[3651]: Starting albumart workers Jul 05 09:38:30 denon volumio[3651]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jul 05 09:38:30 denon volumio[3651]: Starting albumart workers Jul 05 09:38:30 denon volumio-remote-updater[493]: [2025-07-05 09:38:30] [connect] Successful connection Jul 05 09:38:30 denon volumio[3651]: info: Reloading queue from file Jul 05 09:38:30 denon volumio[3651]: info: CoreStateMachine::setRepeat null single undefined Jul 05 09:38:30 denon volumio[3651]: info: CoreStateMachine::pushState Jul 05 09:38:30 denon volumio[3651]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:30 denon volumio[3651]: info: CoreStateMachine::setRandom null Jul 05 09:38:30 denon volumio[3651]: info: CoreStateMachine::pushState Jul 05 09:38:30 denon volumio[3651]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:30 denon volumio[3651]: info: Setting Device type: Raspberry PI Jul 05 09:38:30 denon volumio[3651]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:38:30 denon volumio[3651]: info: CoreStateMachine::pushState Jul 05 09:38:30 denon volumio[3651]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:30 denon volumio[3651]: info: CoreStateMachine::updateTrackBlock Jul 05 09:38:30 denon volumio[3651]: info: CorePlayQueue::getTrackBlock Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:38:30 denon volumio[3651]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 09:38:30 denon volumio[3651]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 09:38:30 denon volumio[3651]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 09:38:30 denon volumio-remote-updater[493]: [2025-07-05 09:38:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751701110 101 Jul 05 09:38:30 denon volumio[3651]: 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 Jul 05 09:38:30 denon volumio[3651]: info: Discovery: adding 1af3cffb-92c8-4da2-aa66-3855daa60f7d Jul 05 09:38:30 denon volumio[3651]: info: Discovery: Found device Denon Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:30 denon volumio[3651]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:30 denon volumio[3651]: info: Completed loading Core Plugins Jul 05 09:38:30 denon volumio[3651]: info: Preparing to generate the ALSA configuration file Jul 05 09:38:30 denon volumio[3651]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:38:30 denon volumio[3651]: info: Reading ALSA contributions from plugins. Jul 05 09:38:30 denon volumio[3651]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:38:30 denon volumio[3651]: info: CoreStateMachine::pushState Jul 05 09:38:30 denon volumio[3651]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:30 denon volumio[3651]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:31 denon volumio[3651]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:38:31 denon volumio[3651]: info: Output device has changed, restarting MPD Jul 05 09:38:31 denon volumio[3651]: info: Output device has changed, restarting Shairport Sync Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:31 denon sudo[3749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:31 denon sudo[3749]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:31 denon sudo[3749]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:31 denon sudo[3751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:31 denon sudo[3751]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:31 denon volumio[3651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:31 denon volumio[3651]: info: ___________ START PLUGINS ___________ Jul 05 09:38:31 denon volumio[3651]: info: ControllerMpd::onStart: Initializing MPD Jul 05 09:38:31 denon volumio[3651]: info: Creating MPD Configuration file Jul 05 09:38:31 denon systemd[1]: Stopping Music Player Daemon... Jul 05 09:38:31 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:31 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:31 denon sudo[3758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:31 denon sudo[3758]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:31 denon volumio[3651]: info: [1751701111258] CoreMusicLibrary::Adding element Medienserver Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:31 denon sudo[3758]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:31 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:31 denon sudo[3761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:31 denon sudo[3761]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:31 denon systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 09:38:31 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:31 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:31 denon volumio[3651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:31 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:31 denon volumio[3651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:31 denon volumio[3651]: info: [1751701111526] CoreMusicLibrary::Adding element Last_100 Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:31 denon volumio[3651]: info: [1751701111537] CoreMusicLibrary::Adding element Webradio Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:31 denon volumio[3651]: info: Initializing BBC Radios Jul 05 09:38:31 denon volumio[3651]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:31 denon sudo[3769]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:38:31 denon sudo[3769]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:31 denon sudo[3769]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:31 denon volumio[3651]: info: Loading i18n strings for locale de Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 09:38:31 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:31 denon volumio[3651]: info: FusionDsp - mixtype--------------------- Hardware Jul 05 09:38:31 denon volumio[3651]: info: Volumio Calling Home Jul 05 09:38:31 denon volumio[3651]: info: Preparing to generate the ALSA configuration file Jul 05 09:38:31 denon volumio[3651]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:38:31 denon volumio[3651]: info: Reading ALSA contributions from plugins. Jul 05 09:38:32 denon volumio[3651]: info: MPD Permissions set Jul 05 09:38:32 denon volumio[3651]: info: MPD Permissions set Jul 05 09:38:32 denon volumio[3651]: info: Volumio called home Jul 05 09:38:32 denon volumio[3651]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: Starting Shairport Sync Jul 05 09:38:32 denon volumio[3651]: info: Starting Shairport Sync Jul 05 09:38:32 denon volumio[3651]: info: Starting Shairport Sync Jul 05 09:38:32 denon sudo[3821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:32 denon sudo[3821]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:32 denon sudo[3823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:32 denon sudo[3823]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:32 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:38:32 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:38:32 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:32 denon sudo[3826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:32 denon sudo[3826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:32 denon volumio[3651]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:32 denon volumio[3651]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:38:32 denon volumio[3651]: info: Output device has changed, restarting MPD Jul 05 09:38:32 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:32 denon volumio[3651]: info: Output device has changed, restarting Shairport Sync Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:32 denon sudo[3821]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:32 denon sudo[3823]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:32 denon sudo[3831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:32 denon sudo[3833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:32 denon sudo[3833]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:32 denon sudo[3826]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:32 denon sudo[3831]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:32 denon sudo[3831]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:32 denon systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 09:38:32 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:32 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:32 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:32 denon volumio[3651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:32 denon volumio[3651]: info: MPD Permissions set Jul 05 09:38:32 denon volumio[3651]: info: Shairport-Sync Started Jul 05 09:38:32 denon volumio[3651]: Error adding Membership: Error: addMembership EINVAL Jul 05 09:38:32 denon volumio[3651]: info: Shairport-Sync Started Jul 05 09:38:32 denon volumio[3651]: info: Shairport-Sync Started Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon sudo[3840]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:38:32 denon sudo[3840]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:32 denon sudo[3840]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:32 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:32 denon volumio[3651]: info: Starting Shairport Sync Jul 05 09:38:32 denon sudo[3850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:32 denon sudo[3850]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:32 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:38:32 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:38:32 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:32 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:32 denon sudo[3850]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:32 denon volumio[3651]: info: Shairport-Sync Started Jul 05 09:38:33 denon volumio[3651]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:38:33 denon volumio[3651]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jul 05 09:38:33 denon volumio[3651]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:38:33 denon volumio[3651]: info: camilladsp service started and running in background, instance 1 Jul 05 09:38:33 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:33 denon volumio[3651]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:38:33 denon volumio[3651]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 09:38:33 denon volumio[3651]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:38:33 denon volumio[3651]: info: FusionDsp loaded Jul 05 09:38:33 denon volumio[3651]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:38:33 denon sudo[3865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 09:38:33 denon sudo[3865]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:33 denon volumio[3651]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 09:38:33 denon volumio[3651]: info: Adding Signal Path Element [object Object] Jul 05 09:38:33 denon volumio[3651]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 09:38:33 denon sudo[3865]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:33 denon volumio[3651]: info: FusionDsp - ---- installed callbackRead Jul 05 09:38:33 denon volumio[3651]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:38:33 denon volumio[3651]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 09:38:33 denon volumio[3651]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 09:38:33 denon volumio[3651]: at onErrorNT (internal/child_process.js:465:16) Jul 05 09:38:33 denon volumio[3651]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 05 09:38:33 denon volumio[3651]: errno: -2, Jul 05 09:38:33 denon volumio[3651]: code: 'ENOENT', Jul 05 09:38:33 denon volumio[3651]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:38:33 denon volumio[3651]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:38:33 denon volumio[3651]: spawnargs: [ Jul 05 09:38:33 denon volumio[3651]: '-p', Jul 05 09:38:33 denon volumio[3651]: 9876, Jul 05 09:38:33 denon volumio[3651]: '-o', Jul 05 09:38:33 denon volumio[3651]: '/tmp/camilladsp.log', Jul 05 09:38:33 denon volumio[3651]: '-l', Jul 05 09:38:33 denon volumio[3651]: 'warn', Jul 05 09:38:33 denon volumio[3651]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 09:38:33 denon volumio[3651]: ] Jul 05 09:38:33 denon volumio[3651]: } Jul 05 09:38:33 denon volumio[3651]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:38:34 denon mpd[3848]: Jul 05 09:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 09:38:34 denon systemd[1]: Started Music Player Daemon. Jul 05 09:38:34 denon sudo[3751]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:34 denon sudo[3761]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:34 denon sudo[3833]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:34 denon sudo[3878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 09:37 Jul 05 09:38:34 denon sudo[3878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:34 denon sudo[3878]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:34 denon volumio-remote-updater[493]: [2025-07-05 09:38:34] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 09:38:34 denon volumio-remote-updater[493]: [2025-07-05 09:38:34] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 09:38:34 denon systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 09:38:34 denon systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 09:38:34 denon systemd[1]: Started dynamicswap service. Jul 05 09:38:35 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:38:35 denon systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 09:38:35 denon systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. Jul 05 09:38:35 denon systemd[1]: Started dynamicswap service. Jul 05 09:38:35 denon systemd[1]: Stopped Volumio Backend Module. Jul 05 09:38:35 denon systemd[1]: Started Volumio Backend Module. Jul 05 09:38:35 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:38:37 denon volumio[3890]: info: ------------------------------------------- Jul 05 09:38:37 denon volumio[3890]: info: ----- Volumio3 ---- Jul 05 09:38:37 denon volumio[3890]: info: ------------------------------------------- Jul 05 09:38:37 denon volumio[3890]: info: ----- System startup ---- Jul 05 09:38:37 denon volumio[3890]: info: ------------------------------------------- Jul 05 09:38:38 denon volumio[3890]: info: MYVOLUMIO Environment detected Jul 05 09:38:38 denon volumio[3890]: info: Plugin folders cleanup Jul 05 09:38:38 denon volumio[3890]: info: Scanning into folder /volumio/app/plugins/ Jul 05 09:38:38 denon volumio[3890]: info: Scanning category audio_interface Jul 05 09:38:38 denon volumio[3890]: info: Scanning category miscellanea Jul 05 09:38:38 denon volumio[3890]: info: Scanning category music_service Jul 05 09:38:38 denon volumio[3890]: info: Scanning category plugins.json Jul 05 09:38:38 denon volumio[3890]: info: Scanning category system_controller Jul 05 09:38:38 denon volumio[3890]: info: Scanning category user_interface Jul 05 09:38:38 denon volumio[3890]: info: Scanning into folder /data/plugins/ Jul 05 09:38:38 denon volumio[3890]: info: Scanning category audio_interface Jul 05 09:38:38 denon volumio[3890]: info: Plugin folders cleanup completed Jul 05 09:38:38 denon volumio[3890]: info: ------------------------------------------- Jul 05 09:38:38 denon volumio[3890]: info: ----- Core plugins startup ---- Jul 05 09:38:38 denon volumio[3890]: info: ------------------------------------------- Jul 05 09:38:38 denon volumio[3890]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 09:38:38 denon volumio[3890]: info: Adding plugin upnp to MyMusic Plugins Jul 05 09:38:38 denon volumio[3890]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 09:38:38 denon volumio[3890]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 09:38:38 denon volumio[3890]: info: Loading plugins from folder /data/plugins/ Jul 05 09:38:38 denon volumio[3890]: info: Loading plugin "system"... Jul 05 09:38:38 denon volumio[3890]: info: Loading plugin "appearance"... Jul 05 09:38:39 denon volumio[3890]: info: Loading plugin "network"... Jul 05 09:38:39 denon volumio[3890]: info: Refreshing Cached IP Addresses Jul 05 09:38:39 denon sudo[3917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 09:38:39 denon sudo[3917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:39 denon sudo[3919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 09:38:39 denon sudo[3919]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:39 denon sudo[3919]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:39 denon sudo[3917]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:39 denon volumio[3890]: info: Loading plugin "services"... Jul 05 09:38:39 denon volumio[3890]: info: Loading plugin "alsa_controller"... Jul 05 09:38:39 denon sudo[3930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 09:38:39 denon sudo[3930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:39 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:39 denon volumio[3890]: info: Loading plugin "wizard"... Jul 05 09:38:39 denon volumio[3890]: info: Loading plugin "networkfs"... Jul 05 09:38:39 denon volumio[3890]: info: Starting Udev Watcher for removable devices Jul 05 09:38:39 denon volumio[3890]: info: Ignoring mount for partition: boot Jul 05 09:38:39 denon volumio[3890]: info: Ignoring mount for partition: volumio Jul 05 09:38:39 denon volumio[3890]: info: Ignoring mount for partition: volumio_data Jul 05 09:38:39 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:39 denon volumio[3890]: info: Loading plugin "volumio_command_line_client"... Jul 05 09:38:39 denon volumio[3890]: info: Loading plugin "upnp"... Jul 05 09:38:39 denon volumio[3890]: info: [1751701119815] Starting Upmpd Daemon Jul 05 09:38:39 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:39 denon volumio[3890]: info: Loading plugin "my_music"... Jul 05 09:38:39 denon volumio[3890]: info: Loading plugin "mpd"... Jul 05 09:38:39 denon volumio-remote-updater[493]: [2025-07-05 09:38:39] [connect] Successful connection Jul 05 09:38:40 denon volumio[3890]: info: Loading plugin "upnp_browser"... Jul 05 09:38:40 denon sudo[3930]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:40 denon volumio[3890]: info: Loading plugin "alarm-clock"... Jul 05 09:38:40 denon volumio[3890]: info: Loading plugin "airplay_emulation"... Jul 05 09:38:40 denon volumio[3890]: info: Starting Shairport Sync Jul 05 09:38:40 denon volumio[3890]: info: Loading plugin "last_100"... Jul 05 09:38:40 denon volumio[3890]: info: Loading plugin "webradio"... Jul 05 09:38:40 denon volumio[3890]: info: Loading plugin "i2s_dacs"... Jul 05 09:38:40 denon volumio[3890]: info: I2S DAC not set, start Auto-detection Jul 05 09:38:40 denon volumio[3890]: info: Loading plugin "volumiodiscovery"... Jul 05 09:38:41 denon volumio[3890]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:41 denon volumio[3890]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:41 denon volumio[3890]: *** WARNING *** For more information see Jul 05 09:38:41 denon node[3890]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:41 denon volumio[3890]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:41 denon volumio[3890]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:41 denon volumio[3890]: *** WARNING *** For more information see Jul 05 09:38:41 denon node[3890]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:41 denon node[3890]: *** WARNING *** For more information see Jul 05 09:38:41 denon node[3890]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:41 denon node[3890]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:41 denon node[3890]: *** WARNING *** For more information see Jul 05 09:38:41 denon volumio[3890]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 09:38:41 denon volumio[3890]: info: Discovery: Started advertising with name: Denon Jul 05 09:38:41 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:41 denon volumio[3890]: info: Loading plugin "outputs"... Jul 05 09:38:41 denon volumio[3890]: info: Loading plugin "albumart"... Jul 05 09:38:41 denon volumio[3890]: info: Plugin example_plugin is not enabled Jul 05 09:38:41 denon volumio[3890]: info: Loading plugin "inputs"... Jul 05 09:38:41 denon volumio[3890]: info: Loading plugin "updater_comm"... Jul 05 09:38:41 denon volumio[3890]: info: Plugin mpdemulation is not enabled Jul 05 09:38:41 denon volumio[3890]: info: Loading plugin "rest_api"... Jul 05 09:38:41 denon volumio[3890]: info: Loading plugin "websocket"... Jul 05 09:38:41 denon volumio[3890]: info: Starting Socket.io Server version 2.3.0 Jul 05 09:38:41 denon volumio[3890]: info: Loading plugin "fusiondsp"... Jul 05 09:38:41 denon volumio[3890]: Forking 3 albumart workers Jul 05 09:38:41 denon volumio[3890]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 09:38:42 denon volumio[3890]: info: Loading i18n strings for locale de Jul 05 09:38:42 denon volumio[3890]: Updating browse sources language Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::initPlayerControls Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:42 denon volumio[3890]: Express server listening on port 3000 Jul 05 09:38:42 denon volumio[3890]: [Metrics] WebUI: 5s 859.38ms Jul 05 09:38:42 denon volumio[3890]: info: CoreStateMachine::resetVolumioState Jul 05 09:38:42 denon volumio[3890]: info: CoreStateMachine::getcurrentVolume Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:42 denon volumio[3890]: info: Volumio Network Manager: Network status updated: 2 Jul 05 09:38:42 denon volumio[3890]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 05 09:38:42 denon volumio-remote-updater[493]: [2025-07-05 09:38:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751701119 101 Jul 05 09:38:42 denon volumio[3890]: Starting albumart workers Jul 05 09:38:42 denon volumio[3890]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jul 05 09:38:42 denon volumio[3890]: Starting albumart workers Jul 05 09:38:42 denon volumio[3890]: Starting albumart workers Jul 05 09:38:42 denon volumio[3890]: info: Reloading queue from file Jul 05 09:38:42 denon volumio[3890]: info: CoreStateMachine::setRepeat null single undefined Jul 05 09:38:42 denon volumio[3890]: info: CoreStateMachine::pushState Jul 05 09:38:42 denon volumio[3890]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:42 denon volumio[3890]: info: CoreStateMachine::setRandom null Jul 05 09:38:42 denon volumio[3890]: info: CoreStateMachine::pushState Jul 05 09:38:42 denon volumio[3890]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:42 denon volumio[3890]: info: Setting Device type: Raspberry PI Jul 05 09:38:42 denon volumio[3890]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 09:38:42 denon volumio[3890]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 09:38:42 denon volumio[3890]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 09:38:42 denon volumio[3890]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:38:42 denon volumio[3890]: info: CoreStateMachine::pushState Jul 05 09:38:42 denon volumio[3890]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:42 denon volumio[3890]: info: CoreStateMachine::updateTrackBlock Jul 05 09:38:42 denon volumio[3890]: info: CorePlayQueue::getTrackBlock Jul 05 09:38:42 denon volumio[3890]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:38:43 denon volumio[3890]: info: Completed loading Core Plugins Jul 05 09:38:43 denon volumio[3890]: info: Preparing to generate the ALSA configuration file Jul 05 09:38:43 denon volumio[3890]: info: Discovery: adding 1af3cffb-92c8-4da2-aa66-3855daa60f7d Jul 05 09:38:43 denon volumio[3890]: info: Discovery: Found device Denon Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:43 denon volumio[3890]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:43 denon volumio[3890]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:38:43 denon volumio[3890]: info: Reading ALSA contributions from plugins. Jul 05 09:38:43 denon volumio[3890]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:38:43 denon volumio[3890]: info: Output device has changed, restarting MPD Jul 05 09:38:43 denon volumio[3890]: info: Output device has changed, restarting Shairport Sync Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:43 denon sudo[3989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:43 denon sudo[3989]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:43 denon sudo[3991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:43 denon sudo[3989]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:43 denon sudo[3991]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:43 denon volumio[3890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:43 denon volumio[3890]: info: ___________ START PLUGINS ___________ Jul 05 09:38:43 denon volumio[3890]: info: ControllerMpd::onStart: Initializing MPD Jul 05 09:38:43 denon volumio[3890]: info: Creating MPD Configuration file Jul 05 09:38:43 denon systemd[1]: Stopping Music Player Daemon... Jul 05 09:38:43 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:43 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:43 denon volumio[3890]: info: [1751701123453] CoreMusicLibrary::Adding element Medienserver Jul 05 09:38:43 denon sudo[3998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:43 denon sudo[3998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:43 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:43 denon sudo[3998]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:43 denon sudo[4001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:43 denon sudo[4001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:43 denon systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 09:38:43 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:43 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:43 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:43 denon volumio[3890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:43 denon sudo[4007]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:38:43 denon sudo[4007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:43 denon sudo[4007]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:43 denon volumio[3890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:43 denon volumio[3890]: info: [1751701123815] CoreMusicLibrary::Adding element Last_100 Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:43 denon volumio[3890]: info: [1751701123826] CoreMusicLibrary::Adding element Webradio Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:43 denon volumio[3890]: info: Initializing BBC Radios Jul 05 09:38:43 denon volumio[3890]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:43 denon volumio[3890]: info: Loading i18n strings for locale de Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 09:38:43 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:43 denon volumio[3890]: info: FusionDsp - mixtype--------------------- Hardware Jul 05 09:38:43 denon volumio[3890]: info: Volumio Calling Home Jul 05 09:38:44 denon volumio[3890]: info: Preparing to generate the ALSA configuration file Jul 05 09:38:44 denon volumio[3890]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:38:44 denon volumio[3890]: info: Reading ALSA contributions from plugins. Jul 05 09:38:44 denon volumio[3890]: info: MPD Permissions set Jul 05 09:38:44 denon volumio[3890]: info: MPD Permissions set Jul 05 09:38:44 denon volumio[3890]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:38:44 denon volumio[3890]: info: CoreStateMachine::pushState Jul 05 09:38:44 denon volumio[3890]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:44 denon volumio[3890]: info: Volumio called home Jul 05 09:38:44 denon volumio[3890]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: Starting Shairport Sync Jul 05 09:38:44 denon volumio[3890]: info: Starting Shairport Sync Jul 05 09:38:44 denon volumio[3890]: info: Starting Shairport Sync Jul 05 09:38:44 denon sudo[4059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:44 denon sudo[4059]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:44 denon sudo[4061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:44 denon sudo[4061]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:44 denon volumio[3890]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:44 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:38:44 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:38:44 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:44 denon sudo[4064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:44 denon sudo[4064]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:44 denon volumio[3890]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:38:44 denon volumio[3890]: info: Output device has changed, restarting MPD Jul 05 09:38:44 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:44 denon sudo[4059]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:44 denon sudo[4069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:44 denon sudo[4069]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:44 denon sudo[4069]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:44 denon volumio[3890]: info: Output device has changed, restarting Shairport Sync Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:44 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:44 denon sudo[4072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:44 denon sudo[4072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:44 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:38:44 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:38:44 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:44 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:44 denon sudo[4064]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:44 denon sudo[4061]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:44 denon systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 09:38:44 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:44 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:44 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:45 denon volumio[3890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:45 denon volumio[3890]: info: MPD Permissions set Jul 05 09:38:45 denon volumio[3890]: info: Shairport-Sync Started Jul 05 09:38:45 denon volumio[3890]: Error adding Membership: Error: addMembership EINVAL Jul 05 09:38:45 denon volumio[3890]: info: Shairport-Sync Started Jul 05 09:38:45 denon volumio[3890]: info: Shairport-Sync Started Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:45 denon volumio[3890]: info: Starting Shairport Sync Jul 05 09:38:45 denon sudo[4081]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:38:45 denon sudo[4081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:45 denon sudo[4081]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:45 denon sudo[4091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:45 denon sudo[4091]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:45 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:38:45 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:38:45 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:45 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:45 denon sudo[4091]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:45 denon volumio[3890]: info: Shairport-Sync Started Jul 05 09:38:45 denon volumio[3890]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 05 09:38:45 denon volumio[3890]: info: Received Get System Info Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 09:38:45 denon volumio[3890]: info: Discovery: Getting this device information Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:45 denon volumio[3890]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::Close All Modals sent Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 05 09:38:45 denon volumio[3890]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 05 09:38:45 denon volumio[3890]: info: Received Get System Version Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:45 denon volumio[3890]: info: Received Get System Info Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 09:38:45 denon volumio[3890]: info: Discovery: Getting this device information Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:45 denon volumio[3890]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:45 denon volumio[3890]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 09:38:45 denon volumio[3890]: info: Received Get System Info Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 09:38:45 denon volumio[3890]: info: Discovery: Getting this device information Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:45 denon volumio[3890]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:45 denon volumio[3890]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:45 denon volumio[3890]: info: Listing playlists Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:38:45 denon volumio[3890]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:38:45 denon volumio[3890]: info: FusionDsp - Jul 05 09:38:46 denon volumio[3890]: info: FusionDsp - Jul 05 09:38:46 denon volumio[3890]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:38:46 denon volumio[3890]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jul 05 09:38:46 denon volumio[3890]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:38:46 denon volumio[3890]: info: camilladsp service started and running in background, instance 1 Jul 05 09:38:46 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:46 denon volumio[3890]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:38:46 denon volumio[3890]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 09:38:46 denon volumio[3890]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:38:46 denon volumio[3890]: info: FusionDsp loaded Jul 05 09:38:46 denon volumio[3890]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:38:46 denon sudo[4106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 09:38:46 denon sudo[4106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:46 denon volumio[3890]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 09:38:46 denon volumio[3890]: info: Adding Signal Path Element [object Object] Jul 05 09:38:46 denon volumio[3890]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 09:38:46 denon volumio[3890]: info: FusionDsp - ---- installed callbackRead Jul 05 09:38:46 denon volumio[3890]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:38:46 denon sudo[4106]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:46 denon volumio[3890]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 09:38:46 denon volumio[3890]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 09:38:46 denon volumio[3890]: at onErrorNT (internal/child_process.js:465:16) Jul 05 09:38:46 denon volumio[3890]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 05 09:38:46 denon volumio[3890]: at runNextTicks (internal/process/task_queues.js:62:3) Jul 05 09:38:46 denon volumio[3890]: at listOnTimeout (internal/timers.js:523:9) Jul 05 09:38:46 denon volumio[3890]: at processTimers (internal/timers.js:497:7) { Jul 05 09:38:46 denon volumio[3890]: errno: -2, Jul 05 09:38:46 denon volumio[3890]: code: 'ENOENT', Jul 05 09:38:46 denon volumio[3890]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:38:46 denon volumio[3890]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:38:46 denon volumio[3890]: spawnargs: [ Jul 05 09:38:46 denon volumio[3890]: '-p', Jul 05 09:38:46 denon volumio[3890]: 9876, Jul 05 09:38:46 denon volumio[3890]: '-o', Jul 05 09:38:46 denon volumio[3890]: '/tmp/camilladsp.log', Jul 05 09:38:46 denon volumio[3890]: '-l', Jul 05 09:38:46 denon volumio[3890]: 'warn', Jul 05 09:38:46 denon volumio[3890]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 09:38:46 denon volumio[3890]: ] Jul 05 09:38:46 denon volumio[3890]: } Jul 05 09:38:46 denon volumio[3890]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:38:47 denon mpd[4090]: Jul 05 09:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 09:38:47 denon systemd[1]: Started Music Player Daemon. Jul 05 09:38:47 denon sudo[3991]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:47 denon sudo[4072]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:47 denon sudo[4001]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:47 denon sudo[4119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 09:37 Jul 05 09:38:47 denon sudo[4119]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:47 denon sudo[4119]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:47 denon volumio-remote-updater[493]: [2025-07-05 09:38:47] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 09:38:47 denon volumio-remote-updater[493]: [2025-07-05 09:38:47] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 09:38:47 denon systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 09:38:47 denon systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 09:38:47 denon systemd[1]: Started dynamicswap service. Jul 05 09:38:47 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:38:47 denon systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 09:38:47 denon systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13. Jul 05 09:38:47 denon systemd[1]: Started dynamicswap service. Jul 05 09:38:47 denon systemd[1]: Stopped Volumio Backend Module. Jul 05 09:38:47 denon systemd[1]: Started Volumio Backend Module. Jul 05 09:38:47 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:38:49 denon volumio[4130]: info: ------------------------------------------- Jul 05 09:38:49 denon volumio[4130]: info: ----- Volumio3 ---- Jul 05 09:38:49 denon volumio[4130]: info: ------------------------------------------- Jul 05 09:38:49 denon volumio[4130]: info: ----- System startup ---- Jul 05 09:38:49 denon volumio[4130]: info: ------------------------------------------- Jul 05 09:38:50 denon volumio[4130]: info: MYVOLUMIO Environment detected Jul 05 09:38:50 denon volumio[4130]: info: Plugin folders cleanup Jul 05 09:38:50 denon volumio[4130]: info: Scanning into folder /volumio/app/plugins/ Jul 05 09:38:50 denon volumio[4130]: info: Scanning category audio_interface Jul 05 09:38:50 denon volumio[4130]: info: Scanning category miscellanea Jul 05 09:38:50 denon volumio[4130]: info: Scanning category music_service Jul 05 09:38:50 denon volumio[4130]: info: Scanning category plugins.json Jul 05 09:38:50 denon volumio[4130]: info: Scanning category system_controller Jul 05 09:38:50 denon volumio[4130]: info: Scanning category user_interface Jul 05 09:38:50 denon volumio[4130]: info: Scanning into folder /data/plugins/ Jul 05 09:38:50 denon volumio[4130]: info: Scanning category audio_interface Jul 05 09:38:50 denon volumio[4130]: info: Plugin folders cleanup completed Jul 05 09:38:50 denon volumio[4130]: info: ------------------------------------------- Jul 05 09:38:50 denon volumio[4130]: info: ----- Core plugins startup ---- Jul 05 09:38:50 denon volumio[4130]: info: ------------------------------------------- Jul 05 09:38:50 denon volumio[4130]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 09:38:50 denon volumio[4130]: info: Adding plugin upnp to MyMusic Plugins Jul 05 09:38:50 denon volumio[4130]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 09:38:50 denon volumio[4130]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 09:38:50 denon volumio[4130]: info: Loading plugins from folder /data/plugins/ Jul 05 09:38:50 denon volumio[4130]: info: Loading plugin "system"... Jul 05 09:38:50 denon volumio[4130]: info: Loading plugin "appearance"... Jul 05 09:38:52 denon volumio[4130]: info: Loading plugin "network"... Jul 05 09:38:52 denon volumio[4130]: info: Refreshing Cached IP Addresses Jul 05 09:38:52 denon sudo[4156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 09:38:52 denon sudo[4156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:52 denon sudo[4158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 09:38:52 denon sudo[4158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:52 denon sudo[4158]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:52 denon sudo[4156]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:52 denon volumio[4130]: info: Loading plugin "services"... Jul 05 09:38:52 denon volumio[4130]: info: Loading plugin "alsa_controller"... Jul 05 09:38:52 denon sudo[4169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 09:38:52 denon sudo[4169]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:52 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:52 denon volumio[4130]: info: Loading plugin "wizard"... Jul 05 09:38:52 denon volumio[4130]: info: Loading plugin "networkfs"... Jul 05 09:38:52 denon volumio[4130]: info: Starting Udev Watcher for removable devices Jul 05 09:38:52 denon volumio[4130]: info: Ignoring mount for partition: boot Jul 05 09:38:52 denon volumio[4130]: info: Ignoring mount for partition: volumio Jul 05 09:38:52 denon volumio[4130]: info: Ignoring mount for partition: volumio_data Jul 05 09:38:52 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:52 denon volumio[4130]: info: Loading plugin "volumio_command_line_client"... Jul 05 09:38:52 denon volumio[4130]: info: Loading plugin "upnp"... Jul 05 09:38:52 denon volumio[4130]: info: [1751701132404] Starting Upmpd Daemon Jul 05 09:38:52 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:52 denon volumio[4130]: info: Loading plugin "my_music"... Jul 05 09:38:52 denon volumio[4130]: info: Loading plugin "mpd"... Jul 05 09:38:52 denon volumio-remote-updater[493]: [2025-07-05 09:38:52] [connect] Successful connection Jul 05 09:38:52 denon volumio[4130]: info: Loading plugin "upnp_browser"... Jul 05 09:38:53 denon sudo[4169]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "alarm-clock"... Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "airplay_emulation"... Jul 05 09:38:53 denon volumio[4130]: info: Starting Shairport Sync Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "last_100"... Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "webradio"... Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "i2s_dacs"... Jul 05 09:38:53 denon volumio[4130]: info: I2S DAC not set, start Auto-detection Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "volumiodiscovery"... Jul 05 09:38:53 denon volumio[4130]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:53 denon volumio[4130]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:53 denon volumio[4130]: *** WARNING *** For more information see Jul 05 09:38:53 denon node[4130]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:53 denon volumio[4130]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:53 denon volumio[4130]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:53 denon volumio[4130]: *** WARNING *** For more information see Jul 05 09:38:53 denon node[4130]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:53 denon node[4130]: *** WARNING *** For more information see Jul 05 09:38:53 denon node[4130]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:38:53 denon node[4130]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:38:53 denon node[4130]: *** WARNING *** For more information see Jul 05 09:38:53 denon volumio[4130]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 09:38:53 denon volumio[4130]: info: Discovery: Started advertising with name: Denon Jul 05 09:38:53 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "outputs"... Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "albumart"... Jul 05 09:38:53 denon volumio[4130]: info: Plugin example_plugin is not enabled Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "inputs"... Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "updater_comm"... Jul 05 09:38:53 denon volumio[4130]: info: Plugin mpdemulation is not enabled Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "rest_api"... Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "websocket"... Jul 05 09:38:53 denon volumio[4130]: info: Starting Socket.io Server version 2.3.0 Jul 05 09:38:53 denon volumio[4130]: info: Loading plugin "fusiondsp"... Jul 05 09:38:54 denon volumio[4130]: Forking 3 albumart workers Jul 05 09:38:54 denon volumio[4130]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 09:38:54 denon volumio[4130]: info: Loading i18n strings for locale de Jul 05 09:38:54 denon volumio[4130]: Updating browse sources language Jul 05 09:38:54 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::initPlayerControls Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:55 denon volumio[4130]: Express server listening on port 3000 Jul 05 09:38:55 denon volumio[4130]: [Metrics] WebUI: 5s 892.27ms Jul 05 09:38:55 denon volumio[4130]: info: CoreStateMachine::resetVolumioState Jul 05 09:38:55 denon volumio[4130]: info: CoreStateMachine::getcurrentVolume Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:55 denon volumio[4130]: info: Volumio Network Manager: Network status updated: 2 Jul 05 09:38:55 denon volumio[4130]: Starting albumart workers Jul 05 09:38:55 denon volumio[4130]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 05 09:38:55 denon volumio-remote-updater[493]: [2025-07-05 09:38:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751701132 101 Jul 05 09:38:55 denon volumio[4130]: Starting albumart workers Jul 05 09:38:55 denon volumio[4130]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jul 05 09:38:55 denon volumio[4130]: info: Reloading queue from file Jul 05 09:38:55 denon volumio[4130]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:38:55 denon volumio[4130]: info: CoreStateMachine::pushState Jul 05 09:38:55 denon volumio[4130]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:38:55 denon volumio[4130]: Starting albumart workers Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:55 denon volumio[4130]: info: CoreStateMachine::updateTrackBlock Jul 05 09:38:55 denon volumio[4130]: info: CorePlayQueue::getTrackBlock Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:38:55 denon volumio[4130]: info: CoreStateMachine::setRepeat null single undefined Jul 05 09:38:55 denon volumio[4130]: info: CoreStateMachine::pushState Jul 05 09:38:55 denon volumio[4130]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:55 denon volumio[4130]: info: CoreStateMachine::setRandom null Jul 05 09:38:55 denon volumio[4130]: info: CoreStateMachine::pushState Jul 05 09:38:55 denon volumio[4130]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:55 denon volumio[4130]: info: Setting Device type: Raspberry PI Jul 05 09:38:55 denon volumio[4130]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 09:38:55 denon volumio[4130]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 09:38:55 denon volumio[4130]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 09:38:55 denon volumio[4130]: info: Discovery: adding 1af3cffb-92c8-4da2-aa66-3855daa60f7d Jul 05 09:38:55 denon volumio[4130]: info: Discovery: Found device Denon Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:55 denon volumio[4130]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:55 denon volumio[4130]: info: Completed loading Core Plugins Jul 05 09:38:55 denon volumio[4130]: info: Preparing to generate the ALSA configuration file Jul 05 09:38:55 denon volumio[4130]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:38:55 denon volumio[4130]: info: Reading ALSA contributions from plugins. Jul 05 09:38:55 denon volumio[4130]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:38:55 denon volumio[4130]: info: CoreStateMachine::pushState Jul 05 09:38:55 denon volumio[4130]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::volumioPushState Jul 05 09:38:55 denon volumio[4130]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:38:55 denon volumio[4130]: info: Output device has changed, restarting MPD Jul 05 09:38:55 denon volumio[4130]: info: Output device has changed, restarting Shairport Sync Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:55 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:55 denon sudo[4232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:55 denon sudo[4232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:55 denon sudo[4234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:55 denon sudo[4234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:55 denon sudo[4232]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:55 denon volumio[4130]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:55 denon volumio[4130]: info: ___________ START PLUGINS ___________ Jul 05 09:38:55 denon volumio[4130]: info: ControllerMpd::onStart: Initializing MPD Jul 05 09:38:55 denon volumio[4130]: info: Creating MPD Configuration file Jul 05 09:38:55 denon systemd[1]: Stopping Music Player Daemon... Jul 05 09:38:56 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:56 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:56 denon volumio[4130]: info: [1751701136017] CoreMusicLibrary::Adding element Medienserver Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:56 denon sudo[4241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:56 denon sudo[4241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:56 denon sudo[4243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:56 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:56 denon sudo[4243]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:56 denon sudo[4241]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:56 denon systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 09:38:56 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:56 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:56 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:56 denon sudo[4247]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:38:56 denon sudo[4247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:56 denon volumio[4130]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:56 denon sudo[4247]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:56 denon volumio[4130]: info: [1751701136456] CoreMusicLibrary::Adding element Last_100 Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:38:56 denon volumio[4130]: info: [1751701136474] CoreMusicLibrary::Adding element Webradio Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:56 denon volumio[4130]: info: Initializing BBC Radios Jul 05 09:38:56 denon volumio[4130]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: Loading i18n strings for locale de Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: FusionDsp - mixtype--------------------- Hardware Jul 05 09:38:56 denon volumio[4130]: info: Volumio Calling Home Jul 05 09:38:56 denon volumio[4130]: info: Preparing to generate the ALSA configuration file Jul 05 09:38:56 denon volumio[4130]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:38:56 denon volumio[4130]: info: Reading ALSA contributions from plugins. Jul 05 09:38:56 denon volumio[4130]: info: MPD Permissions set Jul 05 09:38:56 denon volumio[4130]: info: MPD Permissions set Jul 05 09:38:56 denon volumio[4130]: info: Volumio called home Jul 05 09:38:56 denon volumio[4130]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:56 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 05 09:38:57 denon volumio[4130]: info: Starting Shairport Sync Jul 05 09:38:57 denon volumio[4130]: info: Starting Shairport Sync Jul 05 09:38:57 denon volumio[4130]: info: Starting Shairport Sync Jul 05 09:38:57 denon sudo[4302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:57 denon sudo[4302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:57 denon sudo[4304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:57 denon sudo[4304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:57 denon sudo[4307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:57 denon sudo[4307]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:57 denon volumio[4130]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:57 denon volumio[4130]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:38:57 denon volumio[4130]: info: Output device has changed, restarting MPD Jul 05 09:38:57 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:38:57 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:38:57 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:57 denon volumio[4130]: info: Output device has changed, restarting Shairport Sync Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:57 denon sudo[4311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:38:57 denon sudo[4311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:57 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:57 denon sudo[4311]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:57 denon sudo[4314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:38:57 denon sudo[4314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:57 denon sudo[4307]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:57 denon volumio[4130]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:38:57 denon sudo[4302]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:57 denon sudo[4304]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 05 09:38:57 denon systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 09:38:57 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:38:57 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:38:57 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:38:57 denon volumio[4130]: info: MPD Permissions set Jul 05 09:38:57 denon volumio[4130]: info: Shairport-Sync Started Jul 05 09:38:57 denon volumio[4130]: Error adding Membership: Error: addMembership EINVAL Jul 05 09:38:57 denon volumio[4130]: info: Shairport-Sync Started Jul 05 09:38:57 denon volumio[4130]: info: Shairport-Sync Started Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:57 denon volumio[4130]: info: Starting Shairport Sync Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:57 denon volumio[4130]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 09:38:57 denon sudo[4329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:38:57 denon volumio[4130]: info: Received Get System Info Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 09:38:57 denon sudo[4329]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 09:38:57 denon volumio[4130]: info: Discovery: Getting this device information Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:57 denon volumio[4130]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::volumioGetState Jul 05 09:38:57 denon volumio[4130]: info: CorePlayQueue::getTrack 0 Jul 05 09:38:57 denon sudo[4321]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:38:57 denon sudo[4321]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:57 denon volumio[4130]: info: Listing playlists Jul 05 09:38:57 denon sudo[4321]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:57 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:38:57 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:38:57 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:57 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:38:57 denon sudo[4329]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:57 denon volumio[4130]: info: Shairport-Sync Started Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:38:57 denon volumio[4130]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:38:58 denon volumio[4130]: info: FusionDsp - Jul 05 09:38:58 denon volumio[4130]: info: FusionDsp - Jul 05 09:38:58 denon volumio[4130]: info: FusionDsp - Jul 05 09:38:58 denon volumio[4130]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:38:58 denon volumio[4130]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jul 05 09:38:58 denon volumio[4130]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:38:59 denon volumio[4130]: info: camilladsp service started and running in background, instance 1 Jul 05 09:38:59 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:38:59 denon volumio[4130]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:38:59 denon volumio[4130]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 09:38:59 denon volumio[4130]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:38:59 denon volumio[4130]: info: FusionDsp loaded Jul 05 09:38:59 denon volumio[4130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:38:59 denon sudo[4346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 09:38:59 denon sudo[4346]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:38:59 denon volumio[4130]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 09:38:59 denon volumio[4130]: info: Adding Signal Path Element [object Object] Jul 05 09:38:59 denon volumio[4130]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 09:38:59 denon volumio[4130]: info: FusionDsp - ---- installed callbackRead Jul 05 09:38:59 denon volumio[4130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:38:59 denon sudo[4346]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:59 denon volumio[4130]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 09:38:59 denon volumio[4130]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 09:38:59 denon volumio[4130]: at onErrorNT (internal/child_process.js:465:16) Jul 05 09:38:59 denon volumio[4130]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 05 09:38:59 denon volumio[4130]: at runNextTicks (internal/process/task_queues.js:62:3) Jul 05 09:38:59 denon volumio[4130]: at listOnTimeout (internal/timers.js:523:9) Jul 05 09:38:59 denon volumio[4130]: at processTimers (internal/timers.js:497:7) { Jul 05 09:38:59 denon volumio[4130]: errno: -2, Jul 05 09:38:59 denon volumio[4130]: code: 'ENOENT', Jul 05 09:38:59 denon volumio[4130]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:38:59 denon volumio[4130]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:38:59 denon volumio[4130]: spawnargs: [ Jul 05 09:38:59 denon volumio[4130]: '-p', Jul 05 09:38:59 denon volumio[4130]: 9876, Jul 05 09:38:59 denon volumio[4130]: '-o', Jul 05 09:38:59 denon volumio[4130]: '/tmp/camilladsp.log', Jul 05 09:38:59 denon volumio[4130]: '-l', Jul 05 09:38:59 denon volumio[4130]: 'warn', Jul 05 09:38:59 denon volumio[4130]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 09:38:59 denon volumio[4130]: ] Jul 05 09:38:59 denon volumio[4130]: } Jul 05 09:38:59 denon volumio[4130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:38:59 denon mpd[4332]: Jul 05 09:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 09:38:59 denon systemd[1]: Started Music Player Daemon. Jul 05 09:38:59 denon sudo[4314]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:59 denon sudo[4234]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:59 denon sudo[4243]: pam_unix(sudo:session): session closed for user root Jul 05 09:38:59 denon sudo[4359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 09:37 Jul 05 09:38:59 denon sudo[4359]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:00 denon sudo[4359]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:00 denon volumio-remote-updater[493]: [2025-07-05 09:39:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 09:39:00 denon volumio-remote-updater[493]: [2025-07-05 09:39:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 09:39:00 denon systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 09:39:00 denon systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 09:39:00 denon systemd[1]: Started dynamicswap service. Jul 05 09:39:00 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:39:00 denon systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 09:39:00 denon systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14. Jul 05 09:39:00 denon systemd[1]: Stopped Volumio Backend Module. Jul 05 09:39:00 denon systemd[1]: Started Volumio Backend Module. Jul 05 09:39:00 denon systemd[1]: Started dynamicswap service. Jul 05 09:39:00 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:39:02 denon volumio[4369]: info: ------------------------------------------- Jul 05 09:39:02 denon volumio[4369]: info: ----- Volumio3 ---- Jul 05 09:39:02 denon volumio[4369]: info: ------------------------------------------- Jul 05 09:39:02 denon volumio[4369]: info: ----- System startup ---- Jul 05 09:39:02 denon volumio[4369]: info: ------------------------------------------- Jul 05 09:39:03 denon volumio[4369]: info: MYVOLUMIO Environment detected Jul 05 09:39:03 denon volumio[4369]: info: Plugin folders cleanup Jul 05 09:39:03 denon volumio[4369]: info: Scanning into folder /volumio/app/plugins/ Jul 05 09:39:03 denon volumio[4369]: info: Scanning category audio_interface Jul 05 09:39:03 denon volumio[4369]: info: Scanning category miscellanea Jul 05 09:39:03 denon volumio[4369]: info: Scanning category music_service Jul 05 09:39:03 denon volumio[4369]: info: Scanning category plugins.json Jul 05 09:39:03 denon volumio[4369]: info: Scanning category system_controller Jul 05 09:39:03 denon volumio[4369]: info: Scanning category user_interface Jul 05 09:39:03 denon volumio[4369]: info: Scanning into folder /data/plugins/ Jul 05 09:39:03 denon volumio[4369]: info: Scanning category audio_interface Jul 05 09:39:03 denon volumio[4369]: info: Plugin folders cleanup completed Jul 05 09:39:03 denon volumio[4369]: info: ------------------------------------------- Jul 05 09:39:03 denon volumio[4369]: info: ----- Core plugins startup ---- Jul 05 09:39:03 denon volumio[4369]: info: ------------------------------------------- Jul 05 09:39:03 denon volumio[4369]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 09:39:03 denon volumio[4369]: info: Adding plugin upnp to MyMusic Plugins Jul 05 09:39:03 denon volumio[4369]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 09:39:03 denon volumio[4369]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 09:39:03 denon volumio[4369]: info: Loading plugins from folder /data/plugins/ Jul 05 09:39:03 denon volumio[4369]: info: Loading plugin "system"... Jul 05 09:39:03 denon volumio[4369]: info: Loading plugin "appearance"... Jul 05 09:39:04 denon volumio[4369]: info: Loading plugin "network"... Jul 05 09:39:04 denon volumio[4369]: info: Refreshing Cached IP Addresses Jul 05 09:39:04 denon sudo[4396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 09:39:04 denon sudo[4396]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:04 denon sudo[4398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 09:39:04 denon sudo[4398]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:04 denon sudo[4398]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:04 denon sudo[4396]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:04 denon volumio[4369]: info: Loading plugin "services"... Jul 05 09:39:04 denon volumio[4369]: info: Loading plugin "alsa_controller"... Jul 05 09:39:04 denon sudo[4409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 09:39:04 denon sudo[4409]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:04 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:39:04 denon volumio[4369]: info: Loading plugin "wizard"... Jul 05 09:39:04 denon volumio[4369]: info: Loading plugin "networkfs"... Jul 05 09:39:04 denon volumio[4369]: info: Starting Udev Watcher for removable devices Jul 05 09:39:04 denon volumio[4369]: info: Ignoring mount for partition: boot Jul 05 09:39:04 denon volumio[4369]: info: Ignoring mount for partition: volumio Jul 05 09:39:04 denon volumio[4369]: info: Ignoring mount for partition: volumio_data Jul 05 09:39:04 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:39:04 denon volumio[4369]: info: Loading plugin "volumio_command_line_client"... Jul 05 09:39:04 denon volumio[4369]: info: Loading plugin "upnp"... Jul 05 09:39:05 denon volumio[4369]: info: [1751701145002] Starting Upmpd Daemon Jul 05 09:39:05 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:39:05 denon volumio[4369]: info: Loading plugin "my_music"... Jul 05 09:39:05 denon volumio[4369]: info: Loading plugin "mpd"... Jul 05 09:39:05 denon volumio-remote-updater[493]: [2025-07-05 09:39:05] [connect] Successful connection Jul 05 09:39:05 denon volumio[4369]: info: Loading plugin "upnp_browser"... Jul 05 09:39:05 denon sudo[4409]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:05 denon volumio[4369]: info: Loading plugin "alarm-clock"... Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "airplay_emulation"... Jul 05 09:39:06 denon volumio[4369]: info: Starting Shairport Sync Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "last_100"... Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "webradio"... Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "i2s_dacs"... Jul 05 09:39:06 denon volumio[4369]: info: I2S DAC not set, start Auto-detection Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "volumiodiscovery"... Jul 05 09:39:06 denon volumio[4369]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:06 denon volumio[4369]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:06 denon volumio[4369]: *** WARNING *** For more information see Jul 05 09:39:06 denon node[4369]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:06 denon volumio[4369]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:06 denon volumio[4369]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:06 denon volumio[4369]: *** WARNING *** For more information see Jul 05 09:39:06 denon node[4369]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:06 denon node[4369]: *** WARNING *** For more information see Jul 05 09:39:06 denon node[4369]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:06 denon node[4369]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:06 denon node[4369]: *** WARNING *** For more information see Jul 05 09:39:06 denon volumio[4369]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 09:39:06 denon volumio[4369]: info: Discovery: Started advertising with name: Denon Jul 05 09:39:06 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "outputs"... Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "albumart"... Jul 05 09:39:06 denon volumio[4369]: info: Plugin example_plugin is not enabled Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "inputs"... Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "updater_comm"... Jul 05 09:39:06 denon volumio[4369]: info: Plugin mpdemulation is not enabled Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "rest_api"... Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "websocket"... Jul 05 09:39:06 denon volumio[4369]: info: Starting Socket.io Server version 2.3.0 Jul 05 09:39:06 denon volumio[4369]: info: Loading plugin "fusiondsp"... Jul 05 09:39:06 denon volumio[4369]: Forking 3 albumart workers Jul 05 09:39:06 denon volumio[4369]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 09:39:07 denon volumio[4369]: info: Loading i18n strings for locale de Jul 05 09:39:07 denon volumio[4369]: Updating browse sources language Jul 05 09:39:07 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:07 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:39:07 denon volumio[4369]: info: CoreCommandRouter::initPlayerControls Jul 05 09:39:07 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:07 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:07 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:07 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:07 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:39:07 denon volumio[4369]: Express server listening on port 3000 Jul 05 09:39:07 denon volumio[4369]: [Metrics] WebUI: 5s 731.48ms Jul 05 09:39:07 denon volumio[4369]: info: CoreStateMachine::resetVolumioState Jul 05 09:39:07 denon volumio[4369]: info: CoreStateMachine::getcurrentVolume Jul 05 09:39:07 denon volumio[4369]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:39:07 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:07 denon volumio[4369]: info: Volumio Network Manager: Network status updated: 2 Jul 05 09:39:07 denon volumio[4369]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 05 09:39:07 denon volumio-remote-updater[493]: [2025-07-05 09:39:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751701145 101 Jul 05 09:39:07 denon volumio[4369]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jul 05 09:39:08 denon volumio[4369]: info: Reloading queue from file Jul 05 09:39:08 denon volumio[4369]: info: CoreStateMachine::setRepeat null single undefined Jul 05 09:39:08 denon volumio[4369]: info: CoreStateMachine::pushState Jul 05 09:39:08 denon volumio[4369]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:08 denon volumio[4369]: info: CoreStateMachine::setRandom null Jul 05 09:39:08 denon volumio[4369]: info: CoreStateMachine::pushState Jul 05 09:39:08 denon volumio[4369]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:08 denon volumio[4369]: info: Setting Device type: Raspberry PI Jul 05 09:39:08 denon volumio[4369]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 09:39:08 denon volumio[4369]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 09:39:08 denon volumio[4369]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 09:39:08 denon volumio[4369]: Starting albumart workers Jul 05 09:39:08 denon volumio[4369]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:39:08 denon volumio[4369]: info: CoreStateMachine::pushState Jul 05 09:39:08 denon volumio[4369]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:08 denon volumio[4369]: info: CoreStateMachine::updateTrackBlock Jul 05 09:39:08 denon volumio[4369]: Starting albumart workers Jul 05 09:39:08 denon volumio[4369]: info: CorePlayQueue::getTrackBlock Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:39:08 denon volumio[4369]: Starting albumart workers Jul 05 09:39:08 denon volumio[4369]: info: Completed loading Core Plugins Jul 05 09:39:08 denon volumio[4369]: info: Preparing to generate the ALSA configuration file Jul 05 09:39:08 denon volumio[4369]: info: Discovery: adding 1af3cffb-92c8-4da2-aa66-3855daa60f7d Jul 05 09:39:08 denon volumio[4369]: info: Discovery: Found device Denon Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::volumioGetState Jul 05 09:39:08 denon volumio[4369]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:08 denon volumio[4369]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:39:08 denon volumio[4369]: info: Reading ALSA contributions from plugins. Jul 05 09:39:08 denon volumio[4369]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:39:08 denon volumio[4369]: info: Output device has changed, restarting MPD Jul 05 09:39:08 denon volumio[4369]: info: Output device has changed, restarting Shairport Sync Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:08 denon sudo[4468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:39:08 denon sudo[4470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:39:08 denon sudo[4470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:08 denon sudo[4468]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:08 denon sudo[4468]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:08 denon systemd[1]: Stopping Music Player Daemon... Jul 05 09:39:08 denon volumio[4369]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:39:08 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:39:08 denon volumio[4369]: info: ___________ START PLUGINS ___________ Jul 05 09:39:08 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:39:08 denon volumio[4369]: info: ControllerMpd::onStart: Initializing MPD Jul 05 09:39:08 denon volumio[4369]: info: Creating MPD Configuration file Jul 05 09:39:08 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:39:08 denon volumio[4369]: info: [1751701148563] CoreMusicLibrary::Adding element Medienserver Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:08 denon sudo[4478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:39:08 denon sudo[4480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:39:08 denon sudo[4480]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:08 denon sudo[4478]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:08 denon sudo[4478]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:08 denon systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 09:39:08 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:39:08 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:39:08 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:08 denon volumio[4369]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:08 denon volumio[4369]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:39:08 denon volumio[4369]: info: [1751701148775] CoreMusicLibrary::Adding element Last_100 Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:39:08 denon volumio[4369]: info: [1751701148783] CoreMusicLibrary::Adding element Webradio Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:39:08 denon sudo[4483]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:39:08 denon sudo[4483]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:08 denon volumio[4369]: info: Initializing BBC Radios Jul 05 09:39:08 denon sudo[4483]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:08 denon volumio[4369]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 09:39:08 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: Loading i18n strings for locale de Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: FusionDsp - mixtype--------------------- Hardware Jul 05 09:39:09 denon volumio[4369]: info: Volumio Calling Home Jul 05 09:39:09 denon volumio[4369]: info: Preparing to generate the ALSA configuration file Jul 05 09:39:09 denon volumio[4369]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:39:09 denon volumio[4369]: info: Reading ALSA contributions from plugins. Jul 05 09:39:09 denon volumio[4369]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 05 09:39:09 denon volumio[4369]: info: MPD Permissions set Jul 05 09:39:09 denon volumio[4369]: info: MPD Permissions set Jul 05 09:39:09 denon volumio[4369]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:39:09 denon volumio[4369]: info: CoreStateMachine::pushState Jul 05 09:39:09 denon volumio[4369]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:09 denon volumio[4369]: info: Volumio called home Jul 05 09:39:09 denon volumio[4369]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:09 denon volumio[4369]: info: Starting Shairport Sync Jul 05 09:39:09 denon volumio[4369]: info: Starting Shairport Sync Jul 05 09:39:09 denon volumio[4369]: info: Starting Shairport Sync Jul 05 09:39:09 denon sudo[4538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:39:09 denon sudo[4538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:09 denon sudo[4540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:39:09 denon sudo[4540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:09 denon sudo[4543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:39:09 denon sudo[4543]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:09 denon volumio[4369]: info: CoreCommandRouter::volumioGetState Jul 05 09:39:09 denon volumio[4369]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:09 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:39:09 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:39:09 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:09 denon volumio[4369]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:39:09 denon volumio[4369]: info: Output device has changed, restarting MPD Jul 05 09:39:10 denon volumio[4369]: info: Output device has changed, restarting Shairport Sync Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:10 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:10 denon sudo[4538]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:10 denon sudo[4540]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:10 denon sudo[4548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:39:10 denon sudo[4548]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:10 denon sudo[4548]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:10 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:39:10 denon systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 05 09:39:10 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:39:10 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:10 denon sudo[4550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:39:10 denon sudo[4550]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:10 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:10 denon sudo[4543]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:10 denon volumio[4369]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:39:10 denon systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 09:39:10 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:39:10 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:39:10 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:39:10 denon volumio[4369]: info: MPD Permissions set Jul 05 09:39:10 denon volumio[4369]: info: Shairport-Sync Started Jul 05 09:39:10 denon volumio[4369]: Error adding Membership: Error: addMembership EINVAL Jul 05 09:39:10 denon volumio[4369]: info: Shairport-Sync Started Jul 05 09:39:10 denon volumio[4369]: info: Shairport-Sync Started Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:10 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:10 denon volumio[4369]: info: Starting Shairport Sync Jul 05 09:39:10 denon sudo[4566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:39:10 denon sudo[4566]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:10 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:39:10 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:39:10 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:10 denon sudo[4557]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:39:10 denon sudo[4557]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:10 denon sudo[4557]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:10 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:10 denon sudo[4566]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:10 denon volumio[4369]: info: Shairport-Sync Started Jul 05 09:39:11 denon volumio[4369]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:39:11 denon volumio[4369]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jul 05 09:39:11 denon volumio[4369]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:39:11 denon volumio[4369]: info: camilladsp service started and running in background, instance 1 Jul 05 09:39:11 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:11 denon volumio[4369]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:39:11 denon volumio[4369]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 09:39:11 denon volumio[4369]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:39:11 denon volumio[4369]: info: FusionDsp loaded Jul 05 09:39:11 denon volumio[4369]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:39:11 denon sudo[4583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 09:39:11 denon sudo[4583]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:11 denon volumio[4369]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 09:39:11 denon volumio[4369]: info: Adding Signal Path Element [object Object] Jul 05 09:39:11 denon volumio[4369]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 09:39:11 denon sudo[4583]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:11 denon volumio[4369]: info: FusionDsp - ---- installed callbackRead Jul 05 09:39:11 denon volumio[4369]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:39:11 denon volumio[4369]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 09:39:11 denon volumio[4369]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 09:39:11 denon volumio[4369]: at onErrorNT (internal/child_process.js:465:16) Jul 05 09:39:11 denon volumio[4369]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 05 09:39:11 denon volumio[4369]: errno: -2, Jul 05 09:39:11 denon volumio[4369]: code: 'ENOENT', Jul 05 09:39:11 denon volumio[4369]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:39:11 denon volumio[4369]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:39:11 denon volumio[4369]: spawnargs: [ Jul 05 09:39:11 denon volumio[4369]: '-p', Jul 05 09:39:11 denon volumio[4369]: 9876, Jul 05 09:39:11 denon volumio[4369]: '-o', Jul 05 09:39:11 denon volumio[4369]: '/tmp/camilladsp.log', Jul 05 09:39:11 denon volumio[4369]: '-l', Jul 05 09:39:11 denon volumio[4369]: 'warn', Jul 05 09:39:11 denon volumio[4369]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 09:39:11 denon volumio[4369]: ] Jul 05 09:39:11 denon volumio[4369]: } Jul 05 09:39:11 denon volumio[4369]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:39:12 denon sudo[4594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 09:38 Jul 05 09:39:12 denon sudo[4594]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:12 denon mpd[4570]: Jul 05 09:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 09:39:12 denon systemd[1]: Started Music Player Daemon. Jul 05 09:39:12 denon sudo[4550]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:12 denon sudo[4470]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:12 denon sudo[4480]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:12 denon sudo[4594]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:12 denon volumio-remote-updater[493]: [2025-07-05 09:39:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 09:39:12 denon volumio-remote-updater[493]: [2025-07-05 09:39:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 09:39:12 denon systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 09:39:12 denon systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 09:39:12 denon systemd[1]: Started dynamicswap service. Jul 05 09:39:12 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:39:12 denon systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 09:39:12 denon systemd[1]: volumio.service: Scheduled restart job, restart counter is at 15. Jul 05 09:39:12 denon systemd[1]: Stopped Volumio Backend Module. Jul 05 09:39:12 denon systemd[1]: Started Volumio Backend Module. Jul 05 09:39:12 denon systemd[1]: Started dynamicswap service. Jul 05 09:39:12 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:39:14 denon volumio[4606]: info: ------------------------------------------- Jul 05 09:39:14 denon volumio[4606]: info: ----- Volumio3 ---- Jul 05 09:39:14 denon volumio[4606]: info: ------------------------------------------- Jul 05 09:39:14 denon volumio[4606]: info: ----- System startup ---- Jul 05 09:39:14 denon volumio[4606]: info: ------------------------------------------- Jul 05 09:39:15 denon volumio[4606]: info: MYVOLUMIO Environment detected Jul 05 09:39:15 denon volumio[4606]: info: Plugin folders cleanup Jul 05 09:39:15 denon volumio[4606]: info: Scanning into folder /volumio/app/plugins/ Jul 05 09:39:15 denon volumio[4606]: info: Scanning category audio_interface Jul 05 09:39:15 denon volumio[4606]: info: Scanning category miscellanea Jul 05 09:39:15 denon volumio[4606]: info: Scanning category music_service Jul 05 09:39:15 denon volumio[4606]: info: Scanning category plugins.json Jul 05 09:39:15 denon volumio[4606]: info: Scanning category system_controller Jul 05 09:39:15 denon volumio[4606]: info: Scanning category user_interface Jul 05 09:39:15 denon volumio[4606]: info: Scanning into folder /data/plugins/ Jul 05 09:39:15 denon volumio[4606]: info: Scanning category audio_interface Jul 05 09:39:15 denon volumio[4606]: info: Plugin folders cleanup completed Jul 05 09:39:15 denon volumio[4606]: info: ------------------------------------------- Jul 05 09:39:15 denon volumio[4606]: info: ----- Core plugins startup ---- Jul 05 09:39:15 denon volumio[4606]: info: ------------------------------------------- Jul 05 09:39:15 denon volumio[4606]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 09:39:15 denon volumio[4606]: info: Adding plugin upnp to MyMusic Plugins Jul 05 09:39:15 denon volumio[4606]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 09:39:15 denon volumio[4606]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 09:39:15 denon volumio[4606]: info: Loading plugins from folder /data/plugins/ Jul 05 09:39:15 denon volumio[4606]: info: Loading plugin "system"... Jul 05 09:39:15 denon volumio[4606]: info: Loading plugin "appearance"... Jul 05 09:39:16 denon volumio[4606]: info: Loading plugin "network"... Jul 05 09:39:17 denon volumio[4606]: info: Refreshing Cached IP Addresses Jul 05 09:39:17 denon sudo[4637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 09:39:17 denon sudo[4637]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:17 denon sudo[4639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 09:39:17 denon sudo[4639]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:17 denon sudo[4639]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:17 denon sudo[4637]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:17 denon volumio[4606]: info: Loading plugin "services"... Jul 05 09:39:17 denon volumio[4606]: info: Loading plugin "alsa_controller"... Jul 05 09:39:17 denon sudo[4654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 09:39:17 denon sudo[4654]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:17 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:39:17 denon volumio[4606]: info: Loading plugin "wizard"... Jul 05 09:39:17 denon volumio[4606]: info: Loading plugin "networkfs"... Jul 05 09:39:17 denon volumio[4606]: info: Starting Udev Watcher for removable devices Jul 05 09:39:17 denon volumio-remote-updater[493]: [2025-07-05 09:39:17] [connect] Successful connection Jul 05 09:39:17 denon volumio[4606]: info: Ignoring mount for partition: boot Jul 05 09:39:17 denon volumio[4606]: info: Ignoring mount for partition: volumio Jul 05 09:39:17 denon volumio[4606]: info: Ignoring mount for partition: volumio_data Jul 05 09:39:17 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:39:17 denon volumio[4606]: info: Loading plugin "volumio_command_line_client"... Jul 05 09:39:17 denon volumio[4606]: info: Loading plugin "upnp"... Jul 05 09:39:17 denon volumio[4606]: info: [1751701157315] Starting Upmpd Daemon Jul 05 09:39:17 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:39:17 denon volumio[4606]: info: Loading plugin "my_music"... Jul 05 09:39:17 denon volumio[4606]: info: Loading plugin "mpd"... Jul 05 09:39:17 denon volumio[4606]: info: Loading plugin "upnp_browser"... Jul 05 09:39:17 denon sudo[4654]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "alarm-clock"... Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "airplay_emulation"... Jul 05 09:39:18 denon volumio[4606]: info: Starting Shairport Sync Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "last_100"... Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "webradio"... Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "i2s_dacs"... Jul 05 09:39:18 denon volumio[4606]: info: I2S DAC not set, start Auto-detection Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "volumiodiscovery"... Jul 05 09:39:18 denon volumio[4606]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:18 denon volumio[4606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:18 denon volumio[4606]: *** WARNING *** For more information see Jul 05 09:39:18 denon node[4606]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:18 denon volumio[4606]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:18 denon volumio[4606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:18 denon volumio[4606]: *** WARNING *** For more information see Jul 05 09:39:18 denon node[4606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:18 denon node[4606]: *** WARNING *** For more information see Jul 05 09:39:18 denon node[4606]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:18 denon node[4606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:18 denon node[4606]: *** WARNING *** For more information see Jul 05 09:39:18 denon volumio[4606]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 09:39:18 denon volumio[4606]: info: Discovery: Started advertising with name: Denon Jul 05 09:39:18 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "outputs"... Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "albumart"... Jul 05 09:39:18 denon volumio[4606]: info: Plugin example_plugin is not enabled Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "inputs"... Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "updater_comm"... Jul 05 09:39:18 denon volumio[4606]: info: Plugin mpdemulation is not enabled Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "rest_api"... Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "websocket"... Jul 05 09:39:18 denon volumio[4606]: info: Starting Socket.io Server version 2.3.0 Jul 05 09:39:18 denon volumio[4606]: info: Loading plugin "fusiondsp"... Jul 05 09:39:19 denon volumio[4606]: Forking 3 albumart workers Jul 05 09:39:19 denon volumio[4606]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 09:39:19 denon volumio[4606]: info: Loading i18n strings for locale de Jul 05 09:39:19 denon volumio[4606]: Updating browse sources language Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::initPlayerControls Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:39:19 denon volumio[4606]: Express server listening on port 3000 Jul 05 09:39:19 denon volumio[4606]: [Metrics] WebUI: 5s 771.32ms Jul 05 09:39:19 denon volumio[4606]: info: CoreStateMachine::resetVolumioState Jul 05 09:39:19 denon volumio[4606]: info: CoreStateMachine::getcurrentVolume Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:39:19 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:20 denon volumio[4606]: info: Volumio Network Manager: Network status updated: 2 Jul 05 09:39:20 denon volumio[4606]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 05 09:39:20 denon volumio-remote-updater[493]: [2025-07-05 09:39:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751701157 101 Jul 05 09:39:20 denon volumio[4606]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jul 05 09:39:20 denon volumio[4606]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 05 09:39:20 denon volumio[4606]: info: Reloading queue from file Jul 05 09:39:20 denon volumio[4606]: info: CoreStateMachine::setRepeat null single undefined Jul 05 09:39:20 denon volumio[4606]: info: CoreStateMachine::pushState Jul 05 09:39:20 denon volumio[4606]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:20 denon volumio[4606]: info: CoreStateMachine::setRandom null Jul 05 09:39:20 denon volumio[4606]: info: CoreStateMachine::pushState Jul 05 09:39:20 denon volumio[4606]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:20 denon volumio[4606]: info: Setting Device type: Raspberry PI Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 09:39:20 denon volumio[4606]: Starting albumart workers Jul 05 09:39:20 denon volumio[4606]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:39:20 denon volumio[4606]: info: CoreStateMachine::pushState Jul 05 09:39:20 denon volumio[4606]: Starting albumart workers Jul 05 09:39:20 denon volumio[4606]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:20 denon volumio[4606]: info: CoreStateMachine::updateTrackBlock Jul 05 09:39:20 denon volumio[4606]: Starting albumart workers Jul 05 09:39:20 denon volumio[4606]: info: CorePlayQueue::getTrackBlock Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:39:20 denon volumio[4606]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 09:39:20 denon volumio[4606]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 09:39:20 denon volumio[4606]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::volumioGetState Jul 05 09:39:20 denon volumio[4606]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 09:39:20 denon volumio[4606]: info: Received Get System Info Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 09:39:20 denon volumio[4606]: info: Discovery: Getting this device information Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::volumioGetState Jul 05 09:39:20 denon volumio[4606]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::volumioGetState Jul 05 09:39:20 denon volumio[4606]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:20 denon volumio[4606]: info: Listing playlists Jul 05 09:39:20 denon volumio[4606]: info: Discovery: adding 1af3cffb-92c8-4da2-aa66-3855daa60f7d Jul 05 09:39:20 denon volumio[4606]: info: Discovery: Found device Denon Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::volumioGetState Jul 05 09:39:20 denon volumio[4606]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:20 denon volumio[4606]: info: Completed loading Core Plugins Jul 05 09:39:20 denon volumio[4606]: info: Preparing to generate the ALSA configuration file Jul 05 09:39:20 denon volumio[4606]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:39:20 denon volumio[4606]: info: Reading ALSA contributions from plugins. Jul 05 09:39:20 denon volumio[4606]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:39:20 denon volumio[4606]: info: CoreStateMachine::pushState Jul 05 09:39:20 denon volumio[4606]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:20 denon volumio[4606]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:39:20 denon volumio[4606]: info: Output device has changed, restarting MPD Jul 05 09:39:20 denon volumio[4606]: info: Output device has changed, restarting Shairport Sync Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:20 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:20 denon sudo[4709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:39:20 denon sudo[4709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:20 denon sudo[4709]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:20 denon sudo[4711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:39:20 denon sudo[4711]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:20 denon systemd[1]: Stopping Music Player Daemon... Jul 05 09:39:20 denon volumio[4606]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:39:20 denon volumio[4606]: info: ___________ START PLUGINS ___________ Jul 05 09:39:20 denon volumio[4606]: info: ControllerMpd::onStart: Initializing MPD Jul 05 09:39:20 denon volumio[4606]: info: Creating MPD Configuration file Jul 05 09:39:20 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:39:20 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:39:21 denon sudo[4718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:39:21 denon sudo[4718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:39:21 denon volumio[4606]: info: [1751701161011] CoreMusicLibrary::Adding element Medienserver Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:21 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:39:21 denon sudo[4718]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:21 denon sudo[4721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:39:21 denon sudo[4721]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:21 denon systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 09:39:21 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:39:21 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:39:21 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:39:21 denon volumio[4606]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:21 denon volumio[4606]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:39:21 denon volumio[4606]: info: [1751701161217] CoreMusicLibrary::Adding element Last_100 Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:39:21 denon volumio[4606]: info: [1751701161222] CoreMusicLibrary::Adding element Webradio Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:39:21 denon volumio[4606]: info: Initializing BBC Radios Jul 05 09:39:21 denon sudo[4727]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:39:21 denon volumio[4606]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:21 denon sudo[4727]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:21 denon sudo[4727]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:21 denon volumio[4606]: info: Loading i18n strings for locale de Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:21 denon volumio[4606]: info: FusionDsp - mixtype--------------------- Hardware Jul 05 09:39:21 denon volumio[4606]: info: Volumio Calling Home Jul 05 09:39:21 denon volumio[4606]: info: Preparing to generate the ALSA configuration file Jul 05 09:39:21 denon volumio[4606]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:39:21 denon volumio[4606]: info: Reading ALSA contributions from plugins. Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:39:21 denon volumio[4606]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:39:21 denon volumio[4606]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:39:21 denon volumio[4606]: TypeError: Cannot read property 'off' of undefined Jul 05 09:39:21 denon volumio[4606]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Jul 05 09:39:21 denon volumio[4606]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jul 05 09:39:21 denon volumio[4606]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Jul 05 09:39:21 denon volumio[4606]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Jul 05 09:39:21 denon volumio[4606]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Jul 05 09:39:21 denon volumio[4606]: at Socket.emit (events.js:315:20) Jul 05 09:39:21 denon volumio[4606]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 05 09:39:21 denon volumio[4606]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 05 09:39:21 denon volumio[4606]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:39:22 denon sudo[4817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 09:38 Jul 05 09:39:22 denon sudo[4817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:22 denon sudo[4817]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:23 denon mpd[4735]: Jul 05 09:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 09:39:23 denon systemd[1]: Started Music Player Daemon. Jul 05 09:39:23 denon sudo[4711]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:23 denon sudo[4721]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:23 denon volumio-remote-updater[493]: [2025-07-05 09:39:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 09:39:23 denon volumio-remote-updater[493]: [2025-07-05 09:39:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 09:39:23 denon systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 09:39:23 denon systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 09:39:23 denon systemd[1]: Started dynamicswap service. Jul 05 09:39:23 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:39:23 denon systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 09:39:23 denon systemd[1]: volumio.service: Scheduled restart job, restart counter is at 16. Jul 05 09:39:23 denon systemd[1]: Started dynamicswap service. Jul 05 09:39:23 denon systemd[1]: Stopped Volumio Backend Module. Jul 05 09:39:23 denon systemd[1]: Started Volumio Backend Module. Jul 05 09:39:23 denon systemd[1]: dynamicswap.service: Succeeded. Jul 05 09:39:25 denon volumio[4833]: info: ------------------------------------------- Jul 05 09:39:25 denon volumio[4833]: info: ----- Volumio3 ---- Jul 05 09:39:25 denon volumio[4833]: info: ------------------------------------------- Jul 05 09:39:25 denon volumio[4833]: info: ----- System startup ---- Jul 05 09:39:25 denon volumio[4833]: info: ------------------------------------------- Jul 05 09:39:26 denon volumio[4833]: info: MYVOLUMIO Environment detected Jul 05 09:39:26 denon volumio[4833]: info: Plugin folders cleanup Jul 05 09:39:26 denon volumio[4833]: info: Scanning into folder /volumio/app/plugins/ Jul 05 09:39:26 denon volumio[4833]: info: Scanning category audio_interface Jul 05 09:39:26 denon volumio[4833]: info: Scanning category miscellanea Jul 05 09:39:26 denon volumio[4833]: info: Scanning category music_service Jul 05 09:39:26 denon volumio[4833]: info: Scanning category plugins.json Jul 05 09:39:26 denon volumio[4833]: info: Scanning category system_controller Jul 05 09:39:26 denon volumio[4833]: info: Scanning category user_interface Jul 05 09:39:26 denon volumio[4833]: info: Scanning into folder /data/plugins/ Jul 05 09:39:26 denon volumio[4833]: info: Scanning category audio_interface Jul 05 09:39:26 denon volumio[4833]: info: Plugin folders cleanup completed Jul 05 09:39:26 denon volumio[4833]: info: ------------------------------------------- Jul 05 09:39:26 denon volumio[4833]: info: ----- Core plugins startup ---- Jul 05 09:39:26 denon volumio[4833]: info: ------------------------------------------- Jul 05 09:39:26 denon volumio[4833]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 09:39:26 denon volumio[4833]: info: Adding plugin upnp to MyMusic Plugins Jul 05 09:39:26 denon volumio[4833]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 09:39:26 denon volumio[4833]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 09:39:26 denon volumio[4833]: info: Loading plugins from folder /data/plugins/ Jul 05 09:39:26 denon volumio[4833]: info: Loading plugin "system"... Jul 05 09:39:26 denon volumio[4833]: info: Loading plugin "appearance"... Jul 05 09:39:27 denon volumio[4833]: info: Loading plugin "network"... Jul 05 09:39:28 denon volumio[4833]: info: Refreshing Cached IP Addresses Jul 05 09:39:28 denon sudo[4859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 09:39:28 denon sudo[4859]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:28 denon sudo[4861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 09:39:28 denon sudo[4861]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:28 denon sudo[4861]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:28 denon sudo[4859]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:28 denon volumio[4833]: info: Loading plugin "services"... Jul 05 09:39:28 denon volumio[4833]: info: Loading plugin "alsa_controller"... Jul 05 09:39:28 denon sudo[4872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 09:39:28 denon sudo[4872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:28 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:39:28 denon volumio[4833]: info: Loading plugin "wizard"... Jul 05 09:39:28 denon volumio[4833]: info: Loading plugin "networkfs"... Jul 05 09:39:28 denon volumio[4833]: info: Starting Udev Watcher for removable devices Jul 05 09:39:28 denon volumio[4833]: info: Ignoring mount for partition: boot Jul 05 09:39:28 denon volumio[4833]: info: Ignoring mount for partition: volumio Jul 05 09:39:28 denon volumio[4833]: info: Ignoring mount for partition: volumio_data Jul 05 09:39:28 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:39:28 denon volumio[4833]: info: Loading plugin "volumio_command_line_client"... Jul 05 09:39:28 denon volumio[4833]: info: Loading plugin "upnp"... Jul 05 09:39:28 denon volumio[4833]: info: [1751701168321] Starting Upmpd Daemon Jul 05 09:39:28 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:39:28 denon volumio[4833]: info: Loading plugin "my_music"... Jul 05 09:39:28 denon volumio-remote-updater[493]: [2025-07-05 09:39:28] [connect] Successful connection Jul 05 09:39:28 denon volumio[4833]: info: Loading plugin "mpd"... Jul 05 09:39:28 denon volumio[4833]: info: Loading plugin "upnp_browser"... Jul 05 09:39:28 denon sudo[4872]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "alarm-clock"... Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "airplay_emulation"... Jul 05 09:39:29 denon volumio[4833]: info: Starting Shairport Sync Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "last_100"... Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "webradio"... Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "i2s_dacs"... Jul 05 09:39:29 denon volumio[4833]: info: I2S DAC not set, start Auto-detection Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "volumiodiscovery"... Jul 05 09:39:29 denon volumio[4833]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:29 denon volumio[4833]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:29 denon volumio[4833]: *** WARNING *** For more information see Jul 05 09:39:29 denon node[4833]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:29 denon volumio[4833]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:29 denon volumio[4833]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:29 denon volumio[4833]: *** WARNING *** For more information see Jul 05 09:39:29 denon node[4833]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:29 denon node[4833]: *** WARNING *** For more information see Jul 05 09:39:29 denon node[4833]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 09:39:29 denon node[4833]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 09:39:29 denon node[4833]: *** WARNING *** For more information see Jul 05 09:39:29 denon volumio[4833]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 09:39:29 denon volumio[4833]: info: Discovery: Started advertising with name: Denon Jul 05 09:39:29 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "outputs"... Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "albumart"... Jul 05 09:39:29 denon volumio[4833]: info: Plugin example_plugin is not enabled Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "inputs"... Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "updater_comm"... Jul 05 09:39:29 denon volumio[4833]: info: Plugin mpdemulation is not enabled Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "rest_api"... Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "websocket"... Jul 05 09:39:29 denon volumio[4833]: info: Starting Socket.io Server version 2.3.0 Jul 05 09:39:29 denon volumio[4833]: info: Loading plugin "fusiondsp"... Jul 05 09:39:30 denon volumio[4833]: Forking 3 albumart workers Jul 05 09:39:30 denon volumio[4833]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 09:39:30 denon volumio[4833]: info: Loading i18n strings for locale de Jul 05 09:39:30 denon volumio[4833]: Updating browse sources language Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::initPlayerControls Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:30 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:39:30 denon volumio[4833]: Express server listening on port 3000 Jul 05 09:39:30 denon volumio[4833]: [Metrics] WebUI: 5s 855.34ms Jul 05 09:39:30 denon volumio[4833]: info: CoreStateMachine::resetVolumioState Jul 05 09:39:30 denon volumio[4833]: info: CoreStateMachine::getcurrentVolume Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:31 denon volumio[4833]: info: Volumio Network Manager: Network status updated: 2 Jul 05 09:39:31 denon volumio[4833]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 05 09:39:31 denon volumio-remote-updater[493]: [2025-07-05 09:39:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751701168 101 Jul 05 09:39:31 denon volumio[4833]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jul 05 09:39:31 denon volumio[4833]: Starting albumart workers Jul 05 09:39:31 denon volumio[4833]: Starting albumart workers Jul 05 09:39:31 denon volumio[4833]: info: Reloading queue from file Jul 05 09:39:31 denon volumio[4833]: info: CoreStateMachine::setRepeat null single undefined Jul 05 09:39:31 denon volumio[4833]: info: CoreStateMachine::pushState Jul 05 09:39:31 denon volumio[4833]: Starting albumart workers Jul 05 09:39:31 denon volumio[4833]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:31 denon volumio[4833]: info: CoreStateMachine::setRandom null Jul 05 09:39:31 denon volumio[4833]: info: CoreStateMachine::pushState Jul 05 09:39:31 denon volumio[4833]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:31 denon volumio[4833]: info: Setting Device type: Raspberry PI Jul 05 09:39:31 denon volumio[4833]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:39:31 denon volumio[4833]: info: CoreStateMachine::pushState Jul 05 09:39:31 denon volumio[4833]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:31 denon volumio[4833]: info: CoreStateMachine::updateTrackBlock Jul 05 09:39:31 denon volumio[4833]: info: CorePlayQueue::getTrackBlock Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 09:39:31 denon volumio[4833]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 09:39:31 denon volumio[4833]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 09:39:31 denon volumio[4833]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 09:39:31 denon volumio[4833]: info: Completed loading Core Plugins Jul 05 09:39:31 denon volumio[4833]: info: Preparing to generate the ALSA configuration file Jul 05 09:39:31 denon volumio[4833]: info: Discovery: adding 1af3cffb-92c8-4da2-aa66-3855daa60f7d Jul 05 09:39:31 denon volumio[4833]: info: Discovery: Found device Denon Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::volumioGetState Jul 05 09:39:31 denon volumio[4833]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:31 denon volumio[4833]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:39:31 denon volumio[4833]: info: Reading ALSA contributions from plugins. Jul 05 09:39:31 denon volumio[4833]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:39:31 denon volumio[4833]: info: Output device has changed, restarting MPD Jul 05 09:39:31 denon volumio[4833]: info: Output device has changed, restarting Shairport Sync Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:31 denon sudo[4931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:39:31 denon sudo[4931]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:31 denon sudo[4931]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:31 denon sudo[4933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:39:31 denon sudo[4933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:31 denon volumio[4833]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:39:31 denon systemd[1]: Stopping Music Player Daemon... Jul 05 09:39:31 denon volumio[4833]: info: ___________ START PLUGINS ___________ Jul 05 09:39:31 denon volumio[4833]: info: ControllerMpd::onStart: Initializing MPD Jul 05 09:39:31 denon volumio[4833]: info: Creating MPD Configuration file Jul 05 09:39:31 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:39:31 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 09:39:31 denon sudo[4940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:39:31 denon sudo[4940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:39:31 denon volumio[4833]: info: [1751701171911] CoreMusicLibrary::Adding element Medienserver Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:31 denon sudo[4940]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:31 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:39:31 denon sudo[4944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:39:31 denon sudo[4944]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:31 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:32 denon systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 09:39:32 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:39:32 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:39:32 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:39:32 denon volumio[4833]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:39:32 denon volumio[4833]: info: [1751701172210] CoreMusicLibrary::Adding element Last_100 Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:32 denon sudo[4949]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:39:32 denon sudo[4949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:32 denon sudo[4949]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 09:39:32 denon volumio[4833]: info: [1751701172236] CoreMusicLibrary::Adding element Webradio Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:39:32 denon volumio[4833]: info: Initializing BBC Radios Jul 05 09:39:32 denon volumio[4833]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: Loading i18n strings for locale de Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: FusionDsp - mixtype--------------------- Hardware Jul 05 09:39:32 denon volumio[4833]: info: Volumio Calling Home Jul 05 09:39:32 denon volumio[4833]: info: Preparing to generate the ALSA configuration file Jul 05 09:39:32 denon volumio[4833]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 09:39:32 denon volumio[4833]: info: Reading ALSA contributions from plugins. Jul 05 09:39:32 denon volumio[4833]: info: MPD Permissions set Jul 05 09:39:32 denon volumio[4833]: info: MPD Permissions set Jul 05 09:39:32 denon volumio[4833]: info: VolumeController:: Volume=0 Mute =false Jul 05 09:39:32 denon volumio[4833]: info: CoreStateMachine::pushState Jul 05 09:39:32 denon volumio[4833]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::volumioPushState Jul 05 09:39:32 denon volumio[4833]: info: Volumio called home Jul 05 09:39:32 denon volumio[4833]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:32 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: Starting Shairport Sync Jul 05 09:39:33 denon volumio[4833]: info: Starting Shairport Sync Jul 05 09:39:33 denon volumio[4833]: info: Starting Shairport Sync Jul 05 09:39:33 denon sudo[5001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:39:33 denon sudo[5001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:33 denon sudo[5003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:39:33 denon sudo[5003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:33 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:39:33 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:39:33 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::volumioGetState Jul 05 09:39:33 denon volumio[4833]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:33 denon sudo[5006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:39:33 denon sudo[5006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:33 denon volumio[4833]: verbose: New Socket.io Connection to 192.168.1.45 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 05 09:39:33 denon volumio[4833]: info: Asound.conf file unchanged, so no further update is needed Jul 05 09:39:33 denon volumio[4833]: info: Output device has changed, restarting MPD Jul 05 09:39:33 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:33 denon sudo[5001]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:33 denon sudo[5011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 09:39:33 denon sudo[5011]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:33 denon sudo[5011]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:33 denon volumio[4833]: info: Output device has changed, restarting Shairport Sync Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:33 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:39:33 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:39:33 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:33 denon sudo[5014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 09:39:33 denon sudo[5014]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:33 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:33 denon sudo[5003]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:33 denon sudo[5006]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:33 denon systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 09:39:33 denon systemd[1]: mpd.service: Succeeded. Jul 05 09:39:33 denon systemd[1]: Stopped Music Player Daemon. Jul 05 09:39:33 denon volumio[4833]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 09:39:33 denon systemd[1]: Starting Music Player Daemon... Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 09:39:33 denon volumio[4833]: info: MPD Permissions set Jul 05 09:39:33 denon volumio[4833]: info: Shairport-Sync Started Jul 05 09:39:33 denon volumio[4833]: Error adding Membership: Error: addMembership EINVAL Jul 05 09:39:33 denon volumio[4833]: info: Shairport-Sync Started Jul 05 09:39:33 denon volumio[4833]: info: Shairport-Sync Started Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::volumioGetState Jul 05 09:39:33 denon volumio[4833]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 09:39:33 denon volumio[4833]: info: Received Get System Info Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 09:39:33 denon volumio[4833]: info: Discovery: Getting this device information Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::volumioGetState Jul 05 09:39:33 denon volumio[4833]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::volumioGetState Jul 05 09:39:33 denon volumio[4833]: info: CorePlayQueue::getTrack 0 Jul 05 09:39:33 denon sudo[5022]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 09:39:33 denon volumio[4833]: info: Listing playlists Jul 05 09:39:33 denon sudo[5022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:33 denon sudo[5022]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:33 denon volumio[4833]: info: Starting Shairport Sync Jul 05 09:39:33 denon sudo[5032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 09:39:33 denon sudo[5032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:33 denon systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 09:39:33 denon systemd[1]: shairport-sync.service: Succeeded. Jul 05 09:39:33 denon systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:39:33 denon systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:39:33 denon sudo[5032]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:39:33 denon volumio[4833]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 09:39:33 denon volumio[4833]: info: Shairport-Sync Started Jul 05 09:39:33 denon volumio[4833]: info: FusionDsp - Jul 05 09:39:34 denon volumio[4833]: info: FusionDsp - Jul 05 09:39:34 denon volumio[4833]: info: FusionDsp - Jul 05 09:39:34 denon volumio[4833]: info: FusionDsp - Jul 05 09:39:35 denon volumio[4833]: info: FusionDsp - Jul 05 09:39:35 denon volumio[4833]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:39:35 denon volumio[4833]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jul 05 09:39:35 denon volumio[4833]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 09:39:35 denon volumio[4833]: info: camilladsp service started and running in background, instance 1 Jul 05 09:39:35 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 09:39:35 denon volumio[4833]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:39:35 denon volumio[4833]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 09:39:35 denon volumio[4833]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 09:39:35 denon volumio[4833]: info: FusionDsp loaded Jul 05 09:39:35 denon volumio[4833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 09:39:35 denon sudo[5049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 09:39:35 denon sudo[5049]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 09:39:35 denon mpd[5030]: Jul 05 09:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 09:39:35 denon systemd[1]: Started Music Player Daemon. Jul 05 09:39:35 denon sudo[4933]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:35 denon sudo[5014]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:35 denon sudo[4944]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:35 denon volumio[4833]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 09:39:35 denon volumio[4833]: info: Adding Signal Path Element [object Object] Jul 05 09:39:35 denon volumio[4833]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 09:39:35 denon volumio[4833]: info: FusionDsp - ---- installed callbackRead Jul 05 09:39:35 denon volumio[4833]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:39:35 denon volumio[4833]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 09:39:35 denon volumio[4833]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 09:39:35 denon volumio[4833]: at onErrorNT (internal/child_process.js:465:16) Jul 05 09:39:35 denon volumio[4833]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 05 09:39:35 denon volumio[4833]: at runNextTicks (internal/process/task_queues.js:62:3) Jul 05 09:39:35 denon volumio[4833]: at listOnTimeout (internal/timers.js:523:9) Jul 05 09:39:35 denon volumio[4833]: at processTimers (internal/timers.js:497:7) { Jul 05 09:39:35 denon volumio[4833]: errno: -2, Jul 05 09:39:35 denon volumio[4833]: code: 'ENOENT', Jul 05 09:39:35 denon volumio[4833]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:39:35 denon volumio[4833]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 09:39:35 denon volumio[4833]: spawnargs: [ Jul 05 09:39:35 denon volumio[4833]: '-p', Jul 05 09:39:35 denon volumio[4833]: 9876, Jul 05 09:39:35 denon volumio[4833]: '-o', Jul 05 09:39:35 denon volumio[4833]: '/tmp/camilladsp.log', Jul 05 09:39:35 denon volumio[4833]: '-l', Jul 05 09:39:35 denon volumio[4833]: 'warn', Jul 05 09:39:35 denon volumio[4833]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 09:39:35 denon volumio[4833]: ] Jul 05 09:39:35 denon volumio[4833]: } Jul 05 09:39:35 denon volumio[4833]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 09:39:35 denon sudo[5049]: pam_unix(sudo:session): session closed for user root Jul 05 09:39:36 denon sudo[5064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 09:38 Jul 05 09:39:36 denon sudo[5064]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"