-- Logs begin at Thu 2019-02-14 15:41:59 IST, end at Mon 2025-09-15 15:11:24 IST. -- Sep 15 15:11:10 volumio systemd-timedated[918]: Changed local time to Mon Sep 15 15:11:10 2025 Sep 15 15:11:10 volumio sudo[916]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:10 volumio volumio-time-update[540]: volumio-time-update-util: System time updated successfully. Sep 15 15:11:10 volumio systemd[1]: Starting Daily apt download activities... Sep 15 15:11:10 volumio systemd[1]: Started Volumio Time Update Utility. Sep 15 15:11:11 volumio volumio[894]: info: ------------------------------------------- Sep 15 15:11:11 volumio volumio[894]: info: ----- Volumio3 ---- Sep 15 15:11:11 volumio volumio[894]: info: ------------------------------------------- Sep 15 15:11:11 volumio volumio[894]: info: ----- System startup ---- Sep 15 15:11:11 volumio volumio[894]: info: ------------------------------------------- Sep 15 15:11:11 volumio nmbd[671]: [2025/09/15 15:11:11.356411, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 15 15:11:11 volumio nmbd[671]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 15 15:11:11 volumio systemd[1]: Started Samba NMB Daemon. Sep 15 15:11:11 volumio systemd[1]: Starting Samba Winbind Daemon... Sep 15 15:11:11 volumio volumio-remote-updater[546]: [2025-09-15 15:11:11] [connect] Successful connection Sep 15 15:11:12 volumio winbindd[947]: [2025/09/15 15:11:12.189358, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 15 15:11:12 volumio winbindd[947]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 15 15:11:12 volumio winbindd[947]: [2025/09/15 15:11:12.241256, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 15 15:11:12 volumio winbindd[947]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 15 15:11:12 volumio systemd[1]: Started Samba Winbind Daemon. Sep 15 15:11:12 volumio systemd[1]: Starting Samba SMB Daemon... Sep 15 15:11:12 volumio smbd[952]: [2025/09/15 15:11:12.919417, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 15 15:11:12 volumio systemd[1]: Started Samba SMB Daemon. Sep 15 15:11:12 volumio smbd[952]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 15 15:11:12 volumio systemd[1]: Reached target Multi-User System. Sep 15 15:11:12 volumio systemd[1]: Reached target Graphical Interface. Sep 15 15:11:12 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 15 15:11:13 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 15 15:11:13 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 15 15:11:13 volumio volumio[894]: info: MYVOLUMIO Environment detected Sep 15 15:11:13 volumio volumio[894]: info: Plugin folders cleanup Sep 15 15:11:13 volumio volumio[894]: info: Scanning into folder /volumio/app/plugins/ Sep 15 15:11:13 volumio volumio[894]: info: Scanning category audio_interface Sep 15 15:11:13 volumio volumio[894]: info: Scanning category miscellanea Sep 15 15:11:13 volumio volumio[894]: info: Scanning category music_service Sep 15 15:11:13 volumio volumio[894]: info: Scanning category plugins.json Sep 15 15:11:13 volumio volumio[894]: info: Scanning category system_controller Sep 15 15:11:13 volumio volumio[894]: info: Scanning category user_interface Sep 15 15:11:13 volumio volumio[894]: info: Scanning into folder /data/plugins/ Sep 15 15:11:13 volumio volumio[894]: info: Scanning category audio_interface Sep 15 15:11:13 volumio volumio[894]: info: Scanning category music_service Sep 15 15:11:13 volumio volumio[894]: info: Plugin folders cleanup completed Sep 15 15:11:13 volumio volumio[894]: info: ------------------------------------------- Sep 15 15:11:13 volumio volumio[894]: info: ----- Core plugins startup ---- Sep 15 15:11:13 volumio volumio[894]: info: ------------------------------------------- Sep 15 15:11:13 volumio volumio[894]: info: Loading plugins from folder /volumio/app/plugins/ Sep 15 15:11:13 volumio volumio[894]: info: Adding plugin upnp to MyMusic Plugins Sep 15 15:11:13 volumio volumio[894]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 15 15:11:13 volumio volumio[894]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 15 15:11:13 volumio volumio[894]: info: Loading plugins from folder /data/plugins/ Sep 15 15:11:13 volumio volumio[894]: info: Loading plugin "system"... Sep 15 15:11:13 volumio volumio[894]: info: Loading plugin "appearance"... Sep 15 15:11:13 volumio systemd[1]: apt-daily.service: Succeeded. Sep 15 15:11:13 volumio systemd[1]: Started Daily apt download activities. Sep 15 15:11:14 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Sep 15 15:11:15 volumio volumio[894]: info: Loading plugin "network"... Sep 15 15:11:15 volumio volumio[894]: info: Refreshing Cached IP Addresses Sep 15 15:11:15 volumio sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 15:11:15 volumio sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:15 volumio sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 15:11:15 volumio sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:15 volumio volumio[894]: info: Loading plugin "services"... Sep 15 15:11:15 volumio sudo[1021]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:15 volumio volumio[894]: info: Loading plugin "alsa_controller"... Sep 15 15:11:15 volumio sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 15:11:15 volumio sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:15 volumio sudo[1018]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:15 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 15:11:15 volumio volumio[894]: info: Loading plugin "wizard"... Sep 15 15:11:15 volumio volumio[894]: info: Loading plugin "networkfs"... Sep 15 15:11:15 volumio volumio[894]: info: Starting Udev Watcher for removable devices Sep 15 15:11:15 volumio volumio[894]: info: Ignoring mount for partition: boot Sep 15 15:11:15 volumio volumio[894]: info: Ignoring mount for partition: volumio Sep 15 15:11:15 volumio volumio[894]: info: Ignoring mount for partition: volumio_data Sep 15 15:11:15 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 15:11:15 volumio volumio[894]: info: Loading plugin "volumio_command_line_client"... Sep 15 15:11:15 volumio volumio[894]: info: Loading plugin "upnp"... Sep 15 15:11:15 volumio volumio[894]: info: [1757929275907] Starting Upmpd Daemon Sep 15 15:11:15 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 15:11:15 volumio volumio[894]: info: Loading plugin "my_music"... Sep 15 15:11:15 volumio volumio[894]: info: Loading plugin "mpd"... Sep 15 15:11:16 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 15 15:11:16 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 15 15:11:16 volumio systemd[1]: Startup finished in 13.377s (kernel) + 32.178s (userspace) = 45.555s. Sep 15 15:11:16 volumio volumio[894]: info: Loading plugin "upnp_browser"... Sep 15 15:11:16 volumio volumio[894]: info: Loading plugin "alarm-clock"... Sep 15 15:11:17 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "airplay_emulation"... Sep 15 15:11:17 volumio volumio[894]: info: Starting Shairport Sync Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "last_100"... Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "webradio"... Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "i2s_dacs"... Sep 15 15:11:17 volumio volumio[894]: info: I2S DAC not set, start Auto-detection Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "volumiodiscovery"... Sep 15 15:11:17 volumio volumio[894]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 15:11:17 volumio volumio[894]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 15:11:17 volumio volumio[894]: *** WARNING *** For more information see Sep 15 15:11:17 volumio volumio[894]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 15:11:17 volumio node[894]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 15:11:17 volumio volumio[894]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 15:11:17 volumio volumio[894]: *** WARNING *** For more information see Sep 15 15:11:17 volumio node[894]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 15:11:17 volumio node[894]: *** WARNING *** For more information see Sep 15 15:11:17 volumio node[894]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 15:11:17 volumio node[894]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 15:11:17 volumio node[894]: *** WARNING *** For more information see Sep 15 15:11:17 volumio volumio[894]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 15 15:11:17 volumio volumio[894]: info: Discovery: Started advertising with name: Volumio Sep 15 15:11:17 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "outputs"... Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "albumart"... Sep 15 15:11:17 volumio volumio[894]: info: Plugin example_plugin is not enabled Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "inputs"... Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "updater_comm"... Sep 15 15:11:17 volumio volumio[894]: info: Plugin mpdemulation is not enabled Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "rest_api"... Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "websocket"... Sep 15 15:11:17 volumio volumio[894]: info: Starting Socket.io Server version 2.3.0 Sep 15 15:11:17 volumio volumio[894]: info: Loading plugin "fusiondsp"... Sep 15 15:11:17 volumio volumio[894]: Forking 3 albumart workers Sep 15 15:11:18 volumio volumio[894]: info: Applying required configuration parameters for plugin fusiondsp Sep 15 15:11:18 volumio volumio[894]: info: Loading plugin "RoonBridge"... Sep 15 15:11:19 volumio volumio[894]: Starting albumart workers Sep 15 15:11:19 volumio volumio[894]: Starting albumart workers Sep 15 15:11:19 volumio volumio[894]: Starting albumart workers Sep 15 15:11:19 volumio sudo[1029]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:19 volumio volumio[894]: info: Applying required configuration parameters for plugin RoonBridge Sep 15 15:11:19 volumio volumio[894]: info: Loading i18n strings for locale en Sep 15 15:11:19 volumio volumio[894]: Updating browse sources language Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::initPlayerControls Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 15:11:19 volumio volumio[894]: Express server listening on port 3000 Sep 15 15:11:19 volumio volumio[894]: [Metrics] WebUI: 9s 999.83ms Sep 15 15:11:19 volumio volumio[894]: info: CoreStateMachine::resetVolumioState Sep 15 15:11:19 volumio volumio[894]: info: CoreStateMachine::getcurrentVolume Sep 15 15:11:19 volumio volumio[894]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 15:11:20 volumio volumio[894]: info: Volumio Network Manager: Network status updated: 2 Sep 15 15:11:20 volumio volumio[894]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 15 15:11:20 volumio volumio[894]: info: Reloading queue from file Sep 15 15:11:20 volumio volumio[894]: info: Setting Device type: Raspberry PI Sep 15 15:11:20 volumio volumio[894]: info: VolumeController:: Volume=undefined Mute =false Sep 15 15:11:20 volumio volumio[894]: info: CoreStateMachine::pushState Sep 15 15:11:20 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Sep 15 15:11:20 volumio volumio[894]: info: CoreStateMachine::updateTrackBlock Sep 15 15:11:20 volumio volumio[894]: info: CorePlayQueue::getTrackBlock Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 15:11:20 volumio volumio[894]: info: CoreStateMachine::setRepeat null single undefined Sep 15 15:11:20 volumio volumio[894]: info: CoreStateMachine::pushState Sep 15 15:11:20 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Sep 15 15:11:20 volumio volumio[894]: info: CoreStateMachine::setRandom null Sep 15 15:11:20 volumio volumio[894]: info: CoreStateMachine::pushState Sep 15 15:11:20 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Sep 15 15:11:20 volumio volumio[894]: info: Completed loading Core Plugins Sep 15 15:11:20 volumio volumio[894]: info: Preparing to generate the ALSA configuration file Sep 15 15:11:20 volumio volumio[894]: info: Discovery: adding ac64c9f3-12b1-486a-b6da-1ab8be05cfc7 Sep 15 15:11:20 volumio volumio[894]: info: Discovery: Found device Volumio Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Sep 15 15:11:20 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Sep 15 15:11:20 volumio volumio[894]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 15:11:20 volumio volumio[894]: info: Reading ALSA contributions from plugins. Sep 15 15:11:20 volumio volumio[894]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 15 15:11:20 volumio volumio[894]: info: Asound.conf file unchanged, so no further update is needed Sep 15 15:11:20 volumio volumio[894]: info: Output device has changed, restarting MPD Sep 15 15:11:20 volumio volumio[894]: info: Output device has changed, restarting Shairport Sync Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 15:11:20 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 15:11:20 volumio volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 15:11:20 volumio sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 15:11:20 volumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:20 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:20 volumio volumio[894]: info: ___________ START PLUGINS ___________ Sep 15 15:11:20 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:20 volumio volumio[894]: info: ControllerMpd::onStart: Initializing MPD Sep 15 15:11:20 volumio volumio[894]: info: Creating MPD Configuration file Sep 15 15:11:20 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 15:11:20 volumio volumio[894]: info: [1757929280485] CoreMusicLibrary::Adding element Media Servers Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 15:11:20 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 15:11:20 volumio sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 15:11:20 volumio sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:20 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:20 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 15:11:20 volumio volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 15:11:20 volumio volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 15:11:20 volumio volumio[894]: info: [1757929280635] CoreMusicLibrary::Adding element Last_100 Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 15:11:20 volumio volumio[894]: info: [1757929280639] CoreMusicLibrary::Adding element Webradio Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 15:11:20 volumio volumio[894]: info: Initializing BBC Radios Sep 15 15:11:20 volumio volumio[894]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 15:11:20 volumio volumio[894]: info: Loading i18n strings for locale en Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 15 15:11:20 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:20 volumio volumio[894]: info: FusionDsp - mixtype--------------------- undefined Sep 15 15:11:20 volumio volumio[894]: info: Volumio Calling Home Sep 15 15:11:20 volumio sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Sep 15 15:11:20 volumio sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:20 volumio volumio[894]: info: Preparing to generate the ALSA configuration file Sep 15 15:11:21 volumio systemd[1]: Started RoonBridge. Sep 15 15:11:21 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Sep 15 15:11:21 volumio systemd[1]: mpd.service: Succeeded. Sep 15 15:11:21 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 15:11:21 volumio systemd[1]: Starting Music Player Daemon... Sep 15 15:11:21 volumio sudo[1172]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:21 volumio volumio[894]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 15 15:11:21 volumio volumio[894]: info: Reading ALSA contributions from plugins. Sep 15 15:11:21 volumio volumio[894]: info: MPD Permissions set Sep 15 15:11:21 volumio volumio[894]: info: MPD Permissions set Sep 15 15:11:21 volumio volumio[894]: info: VolumeController:: Volume=undefined Mute =false Sep 15 15:11:21 volumio volumio[894]: info: CoreStateMachine::pushState Sep 15 15:11:21 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::volumioPushState Sep 15 15:11:21 volumio sudo[1181]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 15:11:21 volumio sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:21 volumio sudo[1181]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:21 volumio volumio[894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: Starting Shairport Sync Sep 15 15:11:21 volumio volumio[894]: info: Starting Shairport Sync Sep 15 15:11:21 volumio volumio[894]: info: Starting Shairport Sync Sep 15 15:11:21 volumio sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 15:11:21 volumio sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:21 volumio sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 15:11:21 volumio sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 15:11:21 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 15:11:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::volumioGetState Sep 15 15:11:21 volumio volumio[894]: info: CorePlayQueue::getTrack 0 Sep 15 15:11:21 volumio sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 15:11:21 volumio sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:21 volumio volumio[894]: info: Volumio called home Sep 15 15:11:21 volumio volumio[894]: info: Asound.conf file unchanged, so no further update is needed Sep 15 15:11:21 volumio volumio[894]: info: Output device has changed, restarting MPD Sep 15 15:11:21 volumio volumio[894]: info: Output device has changed, restarting Shairport Sync Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 15:11:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 15:11:21 volumio sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 15:11:21 volumio sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:21 volumio sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 15:11:21 volumio sudo[1211]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:21 volumio sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:21 volumio sudo[1201]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 15:11:21 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 15 15:11:21 volumio systemd[1]: mpd.service: Succeeded. Sep 15 15:11:21 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 15:11:21 volumio volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 15:11:21 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 15:11:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 15:11:21 volumio volumio[894]: info: MPD Permissions set Sep 15 15:11:21 volumio volumio[894]: info: Shairport-Sync Started Sep 15 15:11:21 volumio volumio[894]: Error adding Membership: Error: addMembership EINVAL Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 15:11:21 volumio sudo[1206]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:21 volumio sudo[1203]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:21 volumio systemd[1]: Starting Music Player Daemon... Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:21 volumio volumio[894]: info: Shairport-Sync Started Sep 15 15:11:21 volumio volumio[894]: info: Shairport-Sync Started Sep 15 15:11:21 volumio volumio[894]: info: Starting Shairport Sync Sep 15 15:11:22 volumio sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 15:11:22 volumio sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 15:11:22 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 15:11:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 15:11:22 volumio sudo[1224]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 15:11:22 volumio sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:22 volumio sudo[1224]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 15:11:22 volumio sudo[1231]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:22 volumio volumio[894]: info: Shairport-Sync Started Sep 15 15:11:22 volumio volumio[894]: info: camilladsp service started and running in background, instance 1 Sep 15 15:11:22 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 15:11:22 volumio volumio[894]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 15:11:22 volumio volumio[894]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 15 15:11:22 volumio volumio[894]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 15 15:11:22 volumio volumio[894]: info: FusionDsp loaded Sep 15 15:11:22 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 15:11:22 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 15 15:11:22 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:22 volumio volumio[894]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 15 15:11:22 volumio volumio[894]: info: Adding Signal Path Element [object Object] Sep 15 15:11:22 volumio volumio[894]: info: Adding fusiondspeq DSP Signal Path Element Sep 15 15:11:22 volumio volumio[894]: info: FusionDsp - ---- installed callbackRead Sep 15 15:11:22 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:22 volumio volumio[894]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 15:11:22 volumio volumio[894]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 15 15:11:22 volumio volumio[894]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Sep 15 15:11:22 volumio volumio[894]: at onErrorNT (internal/child_process.js:465:16) Sep 15 15:11:22 volumio volumio[894]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Sep 15 15:11:22 volumio volumio[894]: errno: -2, Sep 15 15:11:22 volumio volumio[894]: code: 'ENOENT', Sep 15 15:11:22 volumio volumio[894]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 15:11:22 volumio volumio[894]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 15 15:11:22 volumio volumio[894]: spawnargs: [ Sep 15 15:11:22 volumio volumio[894]: '-p', Sep 15 15:11:22 volumio volumio[894]: 9876, Sep 15 15:11:22 volumio volumio[894]: '-o', Sep 15 15:11:22 volumio volumio[894]: '/tmp/camilladsp.log', Sep 15 15:11:22 volumio volumio[894]: '-l', Sep 15 15:11:22 volumio volumio[894]: 'warn', Sep 15 15:11:22 volumio volumio[894]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 15 15:11:22 volumio volumio[894]: ] Sep 15 15:11:22 volumio volumio[894]: } Sep 15 15:11:22 volumio volumio[894]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 15:11:23 volumio RoonBridge[1176]: 00:00:00.016 Warn: get lock file path: /tmp/.rnbgem0- Sep 15 15:11:24 volumio sudo[1260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 15:10 Sep 15 15:11:24 volumio sudo[1260]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 15:11:24 volumio mpd[1235]: Sep 15 15:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 15:11:24 volumio systemd[1]: Started Music Player Daemon. Sep 15 15:11:24 volumio sudo[1213]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:24 volumio sudo[1143]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:24 volumio sudo[1152]: pam_unix(sudo:session): session closed for user root Sep 15 15:11:24 volumio RoonBridge[1176]: 00:00:01.016 Trace: [childprocess] using unix child process PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"