-- Logs begin at Fri 2025-04-18 13:36:34 CST, end at Sat 2025-04-19 12:27:21 CST. -- Apr 19 12:26:12 volumio sudo[11585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 12:26:12 volumio sudo[11585]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:12 volumio sudo[11587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 12:26:12 volumio sudo[11587]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:12 volumio sudo[11587]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:12 volumio sudo[11585]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:12 volumio volumio[11003]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:12 volumio volumio[11003]: info: CorePlayQueue::getTrack 3 Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 12:26:12 volumio volumio[11003]: info: Received Get System Info Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:26:12 volumio volumio[11003]: info: Discovery: Getting this device information Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:12 volumio volumio[11003]: info: CorePlayQueue::getTrack 3 Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:12 volumio volumio[11003]: info: CorePlayQueue::getTrack 3 Apr 19 12:26:12 volumio volumio[11003]: info: Listing playlists Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 19 12:26:12 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 19 12:26:14 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 19 12:26:14 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 12:26:14 volumio volumio[11003]: info: Received Get System Info Apr 19 12:26:14 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:26:14 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:26:14 volumio volumio[11003]: info: Discovery: Getting this device information Apr 19 12:26:14 volumio volumio[11003]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:14 volumio volumio[11003]: info: CorePlayQueue::getTrack 3 Apr 19 12:26:14 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:14 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 19 12:26:16 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 12:26:16 volumio volumio[11003]: info: Received Get System Info Apr 19 12:26:16 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:26:16 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:26:16 volumio volumio[11003]: info: Discovery: Getting this device information Apr 19 12:26:16 volumio volumio[11003]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:16 volumio volumio[11003]: info: CorePlayQueue::getTrack 3 Apr 19 12:26:16 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:17 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 12:26:17 volumio volumio[11003]: info: Received Get System Info Apr 19 12:26:17 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:26:17 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:26:17 volumio volumio[11003]: info: Discovery: Getting this device information Apr 19 12:26:17 volumio volumio[11003]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:17 volumio volumio[11003]: info: CorePlayQueue::getTrack 3 Apr 19 12:26:17 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:22 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 19 12:26:22 volumio volumio[11003]: info: Enabling plugin fusiondsp Apr 19 12:26:22 volumio volumio[11003]: info: Loading plugin "fusiondsp"... Apr 19 12:26:22 volumio volumio[11003]: info: Preparing to generate the ALSA configuration file Apr 19 12:26:23 volumio volumio[11003]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:26:23 volumio volumio[11003]: info: Output device has changed, restarting MPD Apr 19 12:26:23 volumio sudo[11610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:23 volumio sudo[11610]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:23 volumio volumio[11003]: info: PLUGIN START: fusiondsp Apr 19 12:26:23 volumio sudo[11610]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:23 volumio volumio[11003]: info: Loading i18n strings for locale zh Apr 19 12:26:23 volumio sudo[11612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:23 volumio sudo[11612]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:23 volumio volumio[11003]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: FusionDsp - mixtype--------------------- None Apr 19 12:26:23 volumio volumio[11003]: info: Preparing to generate the ALSA configuration file Apr 19 12:26:23 volumio volumio[11003]: info: Done. Apr 19 12:26:23 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 12:26:23 volumio volumio[11003]: info: MPD Permissions set Apr 19 12:26:23 volumio volumio[11003]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:26:23 volumio volumio[11003]: info: Output device has changed, restarting MPD Apr 19 12:26:23 volumio sudo[11616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:23 volumio sudo[11616]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:23 volumio sudo[11616]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio sudo[11618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:23 volumio sudo[11618]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: MPD Permissions set Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:23 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:23 volumio volumio[11003]: info: FusionDsp - Apr 19 12:26:23 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:23 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:23 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:23 volumio volumio[11003]: info: FusionDsp - undefined Apr 19 12:26:23 volumio sudo[11633]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:26:23 volumio sudo[11633]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:23 volumio sudo[11633]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:24 volumio volumio[11003]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:26:24 volumio volumio[11003]: info: FusionDsp - Apr 19 12:26:24 volumio volumio[11003]: info: FusionDsp - undefined Apr 19 12:26:25 volumio mpd[11635]: Apr 19 12:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 12:26:25 volumio systemd[1]: Started Music Player Daemon. Apr 19 12:26:25 volumio sudo[11618]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:25 volumio sudo[11612]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:25 volumio volumio[11003]: info: camilladsp service started and running in background, instance 1 Apr 19 12:26:25 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:25 volumio volumio[11003]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:26:25 volumio volumio[11003]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 19 12:26:25 volumio volumio[11003]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:26:25 volumio volumio[11003]: info: FusionDsp loaded Apr 19 12:26:25 volumio volumio[11003]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:25 volumio sudo[11648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 19 12:26:25 volumio sudo[11648]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:25 volumio volumio[11003]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 19 12:26:25 volumio volumio[11003]: info: Adding Signal Path Element [object Object] Apr 19 12:26:25 volumio volumio[11003]: info: Adding fusiondspeq DSP Signal Path Element Apr 19 12:26:25 volumio volumio[11003]: info: FusionDsp - ---- installed callbackRead Apr 19 12:26:25 volumio sudo[11648]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:25 volumio volumio[11003]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:26:25 volumio volumio[11003]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 19 12:26:25 volumio volumio[11003]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 19 12:26:25 volumio volumio[11003]: at onErrorNT (internal/child_process.js:472:16) Apr 19 12:26:25 volumio volumio[11003]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Apr 19 12:26:25 volumio volumio[11003]: errno: -2, Apr 19 12:26:25 volumio volumio[11003]: code: 'ENOENT', Apr 19 12:26:25 volumio volumio[11003]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:26:25 volumio volumio[11003]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:26:25 volumio volumio[11003]: spawnargs: [ Apr 19 12:26:25 volumio volumio[11003]: '-p', Apr 19 12:26:25 volumio volumio[11003]: 9876, Apr 19 12:26:25 volumio volumio[11003]: '-o', Apr 19 12:26:25 volumio volumio[11003]: '/tmp/camilladsp.log', Apr 19 12:26:25 volumio volumio[11003]: '-l', Apr 19 12:26:25 volumio volumio[11003]: 'warn', Apr 19 12:26:25 volumio volumio[11003]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 19 12:26:25 volumio volumio[11003]: ] Apr 19 12:26:25 volumio volumio[11003]: } Apr 19 12:26:25 volumio volumio[11003]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:26:25 volumio sudo[11659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 12:25 Apr 19 12:26:25 volumio sudo[11659]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:25 volumio sudo[11659]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:27 volumio volumio-remote-updater[617]: [2025-04-19 12:26:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 19 12:26:27 volumio volumio-remote-updater[617]: [2025-04-19 12:26:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 19 12:26:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 19 12:26:27 volumio sudo[5325]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:27 volumio sudo[5660]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 19 12:26:27 volumio systemd[1]: Started dynamicswap service. Apr 19 12:26:27 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:26:27 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 19 12:26:27 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Apr 19 12:26:27 volumio systemd[1]: Stopped Volumio Backend Module. Apr 19 12:26:27 volumio systemd[1]: Started Volumio Backend Module. Apr 19 12:26:27 volumio systemd[1]: Started dynamicswap service. Apr 19 12:26:27 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:26:28 volumio volumio[11672]: info: ------------------------------------------- Apr 19 12:26:28 volumio volumio[11672]: info: ----- Volumio3 ---- Apr 19 12:26:28 volumio volumio[11672]: info: ------------------------------------------- Apr 19 12:26:28 volumio volumio[11672]: info: ----- System startup ---- Apr 19 12:26:28 volumio volumio[11672]: info: ------------------------------------------- Apr 19 12:26:29 volumio volumio[11672]: info: MYVOLUMIO Environment detected Apr 19 12:26:29 volumio volumio[11672]: info: Plugin folders cleanup Apr 19 12:26:29 volumio volumio[11672]: info: Scanning into folder /volumio/app/plugins/ Apr 19 12:26:29 volumio volumio[11672]: info: Scanning category audio_interface Apr 19 12:26:29 volumio volumio[11672]: info: Scanning category miscellanea Apr 19 12:26:29 volumio volumio[11672]: info: Scanning category music_service Apr 19 12:26:29 volumio volumio[11672]: info: Scanning category plugins.json Apr 19 12:26:29 volumio volumio[11672]: info: Scanning category system_controller Apr 19 12:26:29 volumio volumio[11672]: info: Scanning category user_interface Apr 19 12:26:29 volumio volumio[11672]: info: Scanning into folder /data/plugins/ Apr 19 12:26:29 volumio volumio[11672]: info: Scanning category audio_interface Apr 19 12:26:29 volumio volumio[11672]: info: Scanning category music_service Apr 19 12:26:29 volumio volumio[11672]: info: Scanning category user_interface Apr 19 12:26:29 volumio volumio[11672]: info: Plugin folders cleanup completed Apr 19 12:26:29 volumio volumio[11672]: info: ------------------------------------------- Apr 19 12:26:29 volumio volumio[11672]: info: ----- Core plugins startup ---- Apr 19 12:26:29 volumio volumio[11672]: info: ------------------------------------------- Apr 19 12:26:29 volumio volumio[11672]: info: Loading plugins from folder /volumio/app/plugins/ Apr 19 12:26:29 volumio volumio[11672]: info: Adding plugin upnp to MyMusic Plugins Apr 19 12:26:29 volumio volumio[11672]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 19 12:26:29 volumio volumio[11672]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 19 12:26:29 volumio volumio[11672]: info: Loading plugins from folder /data/plugins/ Apr 19 12:26:29 volumio volumio[11672]: info: Loading plugin "system"... Apr 19 12:26:29 volumio volumio[11672]: info: Loading plugin "appearance"... Apr 19 12:26:30 volumio volumio[11672]: info: Loading plugin "network"... Apr 19 12:26:30 volumio volumio[11672]: info: Refreshing Cached IP Addresses Apr 19 12:26:30 volumio sudo[11699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 12:26:30 volumio sudo[11699]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:30 volumio sudo[11699]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:30 volumio sudo[11701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 12:26:30 volumio sudo[11701]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:30 volumio sudo[11701]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:30 volumio volumio[11672]: info: Loading plugin "services"... Apr 19 12:26:30 volumio volumio[11672]: info: Loading plugin "alsa_controller"... Apr 19 12:26:30 volumio sudo[11714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 19 12:26:30 volumio sudo[11714]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:30 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:30 volumio volumio[11672]: info: Loading plugin "wizard"... Apr 19 12:26:30 volumio volumio[11672]: info: Loading plugin "networkfs"... Apr 19 12:26:30 volumio volumio[11672]: info: Cannot mount NAS CQ at system boot, trial number 1 ,retrying in 5 seconds Apr 19 12:26:30 volumio volumio[11672]: info: Starting Udev Watcher for removable devices Apr 19 12:26:30 volumio volumio[11672]: info: Ignoring mount for partition: boot Apr 19 12:26:30 volumio volumio[11672]: info: Ignoring mount for partition: volumio Apr 19 12:26:30 volumio volumio[11672]: info: Ignoring mount for partition: volumio_data Apr 19 12:26:30 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:26:30 volumio volumio[11672]: info: Loading plugin "volumio_command_line_client"... Apr 19 12:26:30 volumio volumio[11672]: info: Plugin upnp is not enabled Apr 19 12:26:30 volumio volumio[11672]: info: Loading plugin "my_music"... Apr 19 12:26:30 volumio volumio[11672]: info: Loading plugin "mpd"... Apr 19 12:26:30 volumio sudo[11714]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:31 volumio volumio[11672]: info: Plugin upnp_browser is not enabled Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "alarm-clock"... Apr 19 12:26:31 volumio volumio[11672]: info: Plugin airplay_emulation is not enabled Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "last_100"... Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "webradio"... Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "i2s_dacs"... Apr 19 12:26:31 volumio volumio[11672]: info: I2S DAC not set, start Auto-detection Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "volumiodiscovery"... Apr 19 12:26:31 volumio volumio[11672]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:31 volumio volumio[11672]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:31 volumio volumio[11672]: *** WARNING *** For more information see Apr 19 12:26:31 volumio node[11672]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:31 volumio volumio[11672]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:31 volumio volumio[11672]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:31 volumio volumio[11672]: *** WARNING *** For more information see Apr 19 12:26:31 volumio node[11672]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:31 volumio node[11672]: *** WARNING *** For more information see Apr 19 12:26:31 volumio node[11672]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:31 volumio node[11672]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:31 volumio node[11672]: *** WARNING *** For more information see Apr 19 12:26:31 volumio volumio[11672]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 19 12:26:31 volumio volumio[11672]: info: Discovery: Started advertising with name: Volumio Apr 19 12:26:31 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "outputs"... Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "albumart"... Apr 19 12:26:31 volumio volumio[11672]: info: Plugin example_plugin is not enabled Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "inputs"... Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "updater_comm"... Apr 19 12:26:31 volumio volumio[11672]: info: Plugin mpdemulation is not enabled Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "rest_api"... Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "websocket"... Apr 19 12:26:31 volumio volumio[11672]: Forking 1 albumart workers Apr 19 12:26:31 volumio volumio[11672]: info: Starting Socket.io Server version 2.3.0 Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "fusiondsp"... Apr 19 12:26:31 volumio volumio[11672]: info: Applying required configuration parameters for plugin fusiondsp Apr 19 12:26:31 volumio volumio[11672]: info: Loading plugin "volusonic"... Apr 19 12:26:31 volumio volumio[11672]: Starting albumart workers Apr 19 12:26:32 volumio volumio-remote-updater[617]: [2025-04-19 12:26:32] [connect] Successful connection Apr 19 12:26:32 volumio volumio[11672]: info: Applying required configuration parameters for plugin volusonic Apr 19 12:26:32 volumio volumio[11672]: info: Loading plugin "lastfm"... Apr 19 12:26:32 volumio volumio[11672]: info: Loading i18n strings for locale zh Apr 19 12:26:32 volumio volumio[11672]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:32 volumio volumio[11672]: Updating browse sources language Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::initPlayerControls Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:26:32 volumio volumio[11672]: Express server listening on port 3000 Apr 19 12:26:32 volumio volumio[11672]: [Metrics] WebUI: 4s 643.00ms Apr 19 12:26:32 volumio volumio[11672]: info: Setting Device type: x86 Apr 19 12:26:32 volumio volumio[11672]: info: CoreStateMachine::resetVolumioState Apr 19 12:26:32 volumio volumio[11672]: info: CoreStateMachine::getcurrentVolume Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 12:26:32 volumio volumio[11672]: info: CoreStateMachine::pushState Apr 19 12:26:32 volumio volumio[11672]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:32 volumio volumio[11672]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 19 12:26:32 volumio volumio[11672]: info: Completed loading Core Plugins Apr 19 12:26:32 volumio volumio[11672]: info: Preparing to generate the ALSA configuration file Apr 19 12:26:32 volumio volumio[11672]: info: Volumio Network Manager: Network status updated: 2 Apr 19 12:26:32 volumio volumio[11672]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 19 12:26:32 volumio volumio[11672]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:26:32 volumio volumio[11672]: info: Reading ALSA contributions from plugins. Apr 19 12:26:32 volumio volumio[11672]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 19 12:26:32 volumio volumio[11672]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 19 12:26:32 volumio volumio-remote-updater[617]: [2025-04-19 12:26:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745036792 101 Apr 19 12:26:32 volumio volumio[11672]: 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 Apr 19 12:26:32 volumio volumio[11672]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 19 12:26:32 volumio volumio[11672]: info: Reloading queue from file Apr 19 12:26:32 volumio volumio[11672]: info: CoreStateMachine::setRepeat null single undefined Apr 19 12:26:32 volumio volumio[11672]: info: CoreStateMachine::pushState Apr 19 12:26:32 volumio volumio[11672]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:32 volumio volumio[11672]: info: CoreStateMachine::setRandom null Apr 19 12:26:32 volumio volumio[11672]: info: CoreStateMachine::pushState Apr 19 12:26:32 volumio volumio[11672]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:32 volumio volumio[11672]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:33 volumio volumio[11672]: info: Discovery: adding ff0c7a9d-0254-430b-958c-60aff2e24e3f Apr 19 12:26:33 volumio volumio[11672]: info: Discovery: Found device Volumio Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:33 volumio volumio[11672]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:33 volumio volumio[11672]: info: Asound.conf file written Apr 19 12:26:33 volumio sudo[11754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 19 12:26:33 volumio sudo[11754]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:33 volumio sudo[11754]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:33 volumio volumio[11672]: info: Output device has changed, restarting MPD Apr 19 12:26:33 volumio sudo[11759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:33 volumio sudo[11759]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:33 volumio volumio[11672]: info: ___________ START PLUGINS ___________ Apr 19 12:26:33 volumio sudo[11759]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:33 volumio volumio[11672]: info: ControllerMpd::onStart: Initializing MPD Apr 19 12:26:33 volumio volumio[11672]: info: Creating MPD Configuration file Apr 19 12:26:33 volumio sudo[11761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:33 volumio sudo[11761]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:33 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 12:26:33 volumio sudo[11765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:33 volumio sudo[11765]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:33 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:33 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:33 volumio sudo[11765]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:33 volumio volumio[11672]: info: [1745036793219] CoreMusicLibrary::Adding element Last_100 Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:33 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:33 volumio sudo[11769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:33 volumio sudo[11769]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:33 volumio volumio[11672]: info: [1745036793241] CoreMusicLibrary::Adding element Webradio Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:33 volumio volumio[11672]: info: Initializing BBC Radios Apr 19 12:26:33 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 19 12:26:33 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:33 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:33 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: Loading i18n strings for locale zh Apr 19 12:26:33 volumio volumio[11672]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: FusionDsp - mixtype--------------------- None Apr 19 12:26:33 volumio sudo[11772]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:26:33 volumio sudo[11772]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:33 volumio volumio[11672]: info: Loading i18n strings for locale zh Apr 19 12:26:33 volumio sudo[11772]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:33 volumio volumio[11672]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:33 volumio volumio[11672]: Updating browse sources language Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:33 volumio volumio[11672]: info: [1745036793347] CoreMusicLibrary::Adding element Volusonic Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:33 volumio volumio[11672]: Cannot find translation for source Volusonic Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:33 volumio volumio[11672]: info: [1745036793350] CoreMusicLibrary::Adding element LastFM Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:33 volumio volumio[11672]: Cannot find translation for source Volusonic Apr 19 12:26:33 volumio volumio[11672]: Cannot find translation for source LastFM Apr 19 12:26:33 volumio volumio[11672]: info: [LastFM] scrobbler initiated! Apr 19 12:26:33 volumio volumio[11672]: info: [LastFM] extended logging: false Apr 19 12:26:33 volumio volumio[11672]: info: [LastFM] try scrobble stream/radio plays: true Apr 19 12:26:33 volumio volumio[11672]: info: [LastFM] Left init routine Apr 19 12:26:33 volumio volumio[11672]: info: [LastFM] Socket already connected: false Apr 19 12:26:33 volumio volumio[11672]: info: Volumio Calling Home Apr 19 12:26:33 volumio volumio[11672]: info: Preparing to generate the ALSA configuration file Apr 19 12:26:33 volumio volumio[11672]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:26:33 volumio volumio[11672]: info: Reading ALSA contributions from plugins. Apr 19 12:26:33 volumio volumio[11672]: info: MPD Permissions set Apr 19 12:26:33 volumio volumio[11672]: info: MPD Permissions set Apr 19 12:26:33 volumio volumio[11672]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:26:33 volumio volumio[11672]: info: Output device has changed, restarting MPD Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio sudo[11803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:33 volumio sudo[11803]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:33 volumio sudo[11801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:33 volumio sudo[11801]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:33 volumio sudo[11801]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:33 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 19 12:26:33 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:33 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:33 volumio volumio[11672]: info: MPD Permissions set Apr 19 12:26:33 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:33 volumio sudo[11811]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:26:33 volumio sudo[11811]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:33 volumio sudo[11811]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:33 volumio volumio[11672]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:33 volumio volumio[11672]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:33 volumio volumio[11672]: info: [LastFM] finished init: Authenticated successfully! Apr 19 12:26:34 volumio volumio[11672]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 19 12:26:34 volumio volumio[11672]: info: Volumio called home Apr 19 12:26:34 volumio mpd[11814]: Apr 19 12:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 12:26:34 volumio systemd[1]: Started Music Player Daemon. Apr 19 12:26:34 volumio sudo[11761]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:34 volumio sudo[11803]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:34 volumio sudo[11769]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:34 volumio volumio[11672]: info: Completed starting Core Plugins Apr 19 12:26:34 volumio volumio[11672]: info: ------------------------------------------- Apr 19 12:26:34 volumio volumio[11672]: info: ----- MyVolumio plugins startup ---- Apr 19 12:26:34 volumio volumio[11672]: info: ------------------------------------------- Apr 19 12:26:34 volumio volumio[11672]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 19 12:26:34 volumio volumio[11672]: info: MPD running with PID11814 Apr 19 12:26:34 volumio volumio[11672]: ,establishing connection Apr 19 12:26:34 volumio volumio[11672]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:26:34 volumio volumio[11672]: assert.ok(self.idling) Apr 19 12:26:34 volumio volumio[11672]: error: The expression evaluated to a falsy value: Apr 19 12:26:34 volumio volumio[11672]: assert.ok(self.idling) Apr 19 12:26:34 volumio volumio[11672]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:26:34 volumio volumio[11672]: assert.ok(self.idling) Apr 19 12:26:34 volumio volumio[11672]: error: The expression evaluated to a falsy value: Apr 19 12:26:34 volumio volumio[11672]: assert.ok(self.idling) Apr 19 12:26:34 volumio volumio[11672]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:26:34 volumio volumio[11672]: assert.ok(self.idling) Apr 19 12:26:34 volumio volumio[11672]: error: The expression evaluated to a falsy value: Apr 19 12:26:34 volumio volumio[11672]: assert.ok(self.idling) Apr 19 12:26:34 volumio volumio[11672]: error: updateQueue error: null Apr 19 12:26:35 volumio volumio[11672]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:26:35 volumio volumio[11672]: 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 Apr 19 12:26:35 volumio volumio[11672]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:26:35 volumio volumio[11672]: info: camilladsp service started and running in background, instance 1 Apr 19 12:26:35 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:35 volumio volumio[11672]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:26:35 volumio volumio[11672]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 19 12:26:35 volumio volumio[11672]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:26:35 volumio volumio[11672]: info: FusionDsp loaded Apr 19 12:26:35 volumio volumio[11672]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:35 volumio sudo[11830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 19 12:26:35 volumio sudo[11830]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:35 volumio volumio[11672]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 19 12:26:35 volumio volumio[11672]: info: Adding Signal Path Element [object Object] Apr 19 12:26:35 volumio volumio[11672]: info: Adding fusiondspeq DSP Signal Path Element Apr 19 12:26:35 volumio volumio[11672]: info: FusionDsp - ---- installed callbackRead Apr 19 12:26:35 volumio volumio[11672]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:26:35 volumio sudo[11830]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:35 volumio volumio[11672]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 19 12:26:35 volumio volumio[11672]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 19 12:26:35 volumio volumio[11672]: at onErrorNT (internal/child_process.js:472:16) Apr 19 12:26:35 volumio volumio[11672]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Apr 19 12:26:35 volumio volumio[11672]: errno: -2, Apr 19 12:26:35 volumio volumio[11672]: code: 'ENOENT', Apr 19 12:26:35 volumio volumio[11672]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:26:35 volumio volumio[11672]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:26:35 volumio volumio[11672]: spawnargs: [ Apr 19 12:26:35 volumio volumio[11672]: '-p', Apr 19 12:26:35 volumio volumio[11672]: 9876, Apr 19 12:26:35 volumio volumio[11672]: '-o', Apr 19 12:26:35 volumio volumio[11672]: '/tmp/camilladsp.log', Apr 19 12:26:35 volumio volumio[11672]: '-l', Apr 19 12:26:35 volumio volumio[11672]: 'warn', Apr 19 12:26:35 volumio volumio[11672]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 19 12:26:35 volumio volumio[11672]: ] Apr 19 12:26:35 volumio volumio[11672]: } Apr 19 12:26:35 volumio volumio[11672]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:26:35 volumio sudo[11843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 12:25 Apr 19 12:26:35 volumio sudo[11843]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:35 volumio sudo[11843]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:35 volumio volumio-remote-updater[617]: [2025-04-19 12:26:35] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 19 12:26:35 volumio volumio-remote-updater[617]: [2025-04-19 12:26:35] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 19 12:26:35 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 19 12:26:35 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 19 12:26:35 volumio systemd[1]: Started dynamicswap service. Apr 19 12:26:35 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:26:36 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 19 12:26:36 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Apr 19 12:26:36 volumio systemd[1]: Stopped Volumio Backend Module. Apr 19 12:26:36 volumio systemd[1]: Started Volumio Backend Module. Apr 19 12:26:36 volumio systemd[1]: Started dynamicswap service. Apr 19 12:26:36 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:26:37 volumio volumio[11854]: info: ------------------------------------------- Apr 19 12:26:37 volumio volumio[11854]: info: ----- Volumio3 ---- Apr 19 12:26:37 volumio volumio[11854]: info: ------------------------------------------- Apr 19 12:26:37 volumio volumio[11854]: info: ----- System startup ---- Apr 19 12:26:37 volumio volumio[11854]: info: ------------------------------------------- Apr 19 12:26:37 volumio volumio[11854]: info: MYVOLUMIO Environment detected Apr 19 12:26:37 volumio volumio[11854]: info: Plugin folders cleanup Apr 19 12:26:37 volumio volumio[11854]: info: Scanning into folder /volumio/app/plugins/ Apr 19 12:26:37 volumio volumio[11854]: info: Scanning category audio_interface Apr 19 12:26:37 volumio volumio[11854]: info: Scanning category miscellanea Apr 19 12:26:37 volumio volumio[11854]: info: Scanning category music_service Apr 19 12:26:37 volumio volumio[11854]: info: Scanning category plugins.json Apr 19 12:26:37 volumio volumio[11854]: info: Scanning category system_controller Apr 19 12:26:37 volumio volumio[11854]: info: Scanning category user_interface Apr 19 12:26:37 volumio volumio[11854]: info: Scanning into folder /data/plugins/ Apr 19 12:26:37 volumio volumio[11854]: info: Scanning category audio_interface Apr 19 12:26:37 volumio volumio[11854]: info: Scanning category music_service Apr 19 12:26:37 volumio volumio[11854]: info: Scanning category user_interface Apr 19 12:26:37 volumio volumio[11854]: info: Plugin folders cleanup completed Apr 19 12:26:37 volumio volumio[11854]: info: ------------------------------------------- Apr 19 12:26:37 volumio volumio[11854]: info: ----- Core plugins startup ---- Apr 19 12:26:37 volumio volumio[11854]: info: ------------------------------------------- Apr 19 12:26:37 volumio volumio[11854]: info: Loading plugins from folder /volumio/app/plugins/ Apr 19 12:26:37 volumio volumio[11854]: info: Adding plugin upnp to MyMusic Plugins Apr 19 12:26:37 volumio volumio[11854]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 19 12:26:37 volumio volumio[11854]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 19 12:26:37 volumio volumio[11854]: info: Loading plugins from folder /data/plugins/ Apr 19 12:26:37 volumio volumio[11854]: info: Loading plugin "system"... Apr 19 12:26:37 volumio volumio[11854]: info: Loading plugin "appearance"... Apr 19 12:26:38 volumio volumio[11854]: info: Loading plugin "network"... Apr 19 12:26:38 volumio volumio[11854]: info: Refreshing Cached IP Addresses Apr 19 12:26:38 volumio sudo[11881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 12:26:38 volumio sudo[11881]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:38 volumio sudo[11881]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:38 volumio sudo[11883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 12:26:38 volumio sudo[11883]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:38 volumio sudo[11883]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:38 volumio volumio[11854]: info: Loading plugin "services"... Apr 19 12:26:38 volumio volumio[11854]: info: Loading plugin "alsa_controller"... Apr 19 12:26:38 volumio sudo[11895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 19 12:26:38 volumio sudo[11895]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:38 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:38 volumio volumio[11854]: info: Loading plugin "wizard"... Apr 19 12:26:38 volumio volumio[11854]: info: Loading plugin "networkfs"... Apr 19 12:26:38 volumio volumio[11854]: info: Cannot mount NAS CQ at system boot, trial number 1 ,retrying in 5 seconds Apr 19 12:26:38 volumio volumio[11854]: info: Starting Udev Watcher for removable devices Apr 19 12:26:38 volumio volumio[11854]: info: Ignoring mount for partition: boot Apr 19 12:26:38 volumio volumio[11854]: info: Ignoring mount for partition: volumio Apr 19 12:26:38 volumio volumio[11854]: info: Ignoring mount for partition: volumio_data Apr 19 12:26:38 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:26:38 volumio volumio[11854]: info: Loading plugin "volumio_command_line_client"... Apr 19 12:26:38 volumio volumio[11854]: info: Plugin upnp is not enabled Apr 19 12:26:38 volumio volumio[11854]: info: Loading plugin "my_music"... Apr 19 12:26:38 volumio volumio[11854]: info: Loading plugin "mpd"... Apr 19 12:26:38 volumio sudo[11895]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:38 volumio volumio[11854]: info: Plugin upnp_browser is not enabled Apr 19 12:26:38 volumio volumio[11854]: info: Loading plugin "alarm-clock"... Apr 19 12:26:39 volumio volumio[11854]: info: Plugin airplay_emulation is not enabled Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "last_100"... Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "webradio"... Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "i2s_dacs"... Apr 19 12:26:39 volumio volumio[11854]: info: I2S DAC not set, start Auto-detection Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "volumiodiscovery"... Apr 19 12:26:39 volumio volumio[11854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:39 volumio volumio[11854]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:39 volumio volumio[11854]: *** WARNING *** For more information see Apr 19 12:26:39 volumio node[11854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:39 volumio volumio[11854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:39 volumio volumio[11854]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:39 volumio volumio[11854]: *** WARNING *** For more information see Apr 19 12:26:39 volumio node[11854]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:39 volumio node[11854]: *** WARNING *** For more information see Apr 19 12:26:39 volumio node[11854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:39 volumio node[11854]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:39 volumio node[11854]: *** WARNING *** For more information see Apr 19 12:26:39 volumio volumio[11854]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 19 12:26:39 volumio volumio[11854]: info: Discovery: Started advertising with name: Volumio Apr 19 12:26:39 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "outputs"... Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "albumart"... Apr 19 12:26:39 volumio volumio[11854]: info: Plugin example_plugin is not enabled Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "inputs"... Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "updater_comm"... Apr 19 12:26:39 volumio volumio[11854]: info: Plugin mpdemulation is not enabled Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "rest_api"... Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "websocket"... Apr 19 12:26:39 volumio volumio[11854]: info: Starting Socket.io Server version 2.3.0 Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "fusiondsp"... Apr 19 12:26:39 volumio volumio[11854]: Forking 1 albumart workers Apr 19 12:26:39 volumio volumio[11854]: info: Applying required configuration parameters for plugin fusiondsp Apr 19 12:26:39 volumio volumio[11854]: info: Loading plugin "volusonic"... Apr 19 12:26:39 volumio volumio[11854]: Starting albumart workers Apr 19 12:26:40 volumio volumio[11854]: info: Applying required configuration parameters for plugin volusonic Apr 19 12:26:40 volumio volumio[11854]: info: Loading plugin "lastfm"... Apr 19 12:26:40 volumio volumio[11854]: info: Loading i18n strings for locale zh Apr 19 12:26:40 volumio volumio[11854]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:40 volumio volumio[11854]: Updating browse sources language Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::initPlayerControls Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: Express server listening on port 3000 Apr 19 12:26:40 volumio volumio[11854]: [Metrics] WebUI: 3s 697.53ms Apr 19 12:26:40 volumio volumio[11854]: info: Setting Device type: x86 Apr 19 12:26:40 volumio volumio[11854]: info: CoreStateMachine::resetVolumioState Apr 19 12:26:40 volumio volumio[11854]: info: CoreStateMachine::getcurrentVolume Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 12:26:40 volumio volumio[11854]: info: CoreStateMachine::pushState Apr 19 12:26:40 volumio volumio[11854]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 19 12:26:40 volumio volumio[11854]: info: Completed loading Core Plugins Apr 19 12:26:40 volumio volumio[11854]: info: Preparing to generate the ALSA configuration file Apr 19 12:26:40 volumio volumio[11854]: info: Volumio Network Manager: Network status updated: 2 Apr 19 12:26:40 volumio volumio[11854]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 19 12:26:40 volumio volumio[11854]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:26:40 volumio volumio[11854]: info: Reading ALSA contributions from plugins. Apr 19 12:26:40 volumio volumio[11854]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 19 12:26:40 volumio volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 19 12:26:40 volumio volumio[11854]: info: Reloading queue from file Apr 19 12:26:40 volumio volumio[11854]: info: CoreStateMachine::setRepeat null single undefined Apr 19 12:26:40 volumio volumio[11854]: info: CoreStateMachine::pushState Apr 19 12:26:40 volumio volumio[11854]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:40 volumio volumio[11854]: info: CoreStateMachine::setRandom null Apr 19 12:26:40 volumio volumio[11854]: info: CoreStateMachine::pushState Apr 19 12:26:40 volumio volumio[11854]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:40 volumio volumio[11854]: info: Discovery: adding ff0c7a9d-0254-430b-958c-60aff2e24e3f Apr 19 12:26:40 volumio volumio[11854]: info: Discovery: Found device Volumio Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:40 volumio volumio[11854]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:40 volumio volumio[11854]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:26:40 volumio volumio[11854]: info: Output device has changed, restarting MPD Apr 19 12:26:40 volumio sudo[11936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:40 volumio sudo[11936]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:40 volumio volumio[11854]: info: ___________ START PLUGINS ___________ Apr 19 12:26:40 volumio sudo[11936]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:40 volumio volumio[11854]: info: ControllerMpd::onStart: Initializing MPD Apr 19 12:26:40 volumio volumio[11854]: info: Creating MPD Configuration file Apr 19 12:26:40 volumio sudo[11938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:40 volumio sudo[11938]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:40 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 12:26:40 volumio sudo[11942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:40 volumio sudo[11942]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:40 volumio sudo[11942]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:40 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:40 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:40 volumio volumio[11854]: info: [1745036800842] CoreMusicLibrary::Adding element Last_100 Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:40 volumio volumio[11854]: info: [1745036800846] CoreMusicLibrary::Adding element Webradio Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:40 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:40 volumio volumio[11854]: info: Initializing BBC Radios Apr 19 12:26:40 volumio sudo[11946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:40 volumio sudo[11946]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:40 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 19 12:26:40 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:40 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:40 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: info: Loading i18n strings for locale zh Apr 19 12:26:40 volumio volumio-remote-updater[617]: [2025-04-19 12:26:40] [connect] Successful connection Apr 19 12:26:40 volumio volumio[11854]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:40 volumio volumio[11854]: info: FusionDsp - mixtype--------------------- None Apr 19 12:26:40 volumio volumio[11854]: info: Loading i18n strings for locale zh Apr 19 12:26:40 volumio volumio[11854]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:40 volumio volumio[11854]: Updating browse sources language Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:40 volumio volumio[11854]: info: [1745036800966] CoreMusicLibrary::Adding element Volusonic Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:40 volumio volumio[11854]: Cannot find translation for source Volusonic Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:40 volumio volumio[11854]: info: [1745036800970] CoreMusicLibrary::Adding element LastFM Apr 19 12:26:40 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:40 volumio volumio[11854]: Cannot find translation for source Volusonic Apr 19 12:26:40 volumio volumio[11854]: Cannot find translation for source LastFM Apr 19 12:26:40 volumio volumio[11854]: info: [LastFM] scrobbler initiated! Apr 19 12:26:40 volumio volumio[11854]: info: [LastFM] extended logging: false Apr 19 12:26:40 volumio volumio[11854]: info: [LastFM] try scrobble stream/radio plays: true Apr 19 12:26:40 volumio volumio[11854]: info: [LastFM] Left init routine Apr 19 12:26:40 volumio sudo[11953]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:26:40 volumio sudo[11953]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:40 volumio volumio[11854]: info: [LastFM] Socket already connected: false Apr 19 12:26:40 volumio volumio[11854]: info: Volumio Calling Home Apr 19 12:26:40 volumio sudo[11953]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:41 volumio volumio[11854]: info: Preparing to generate the ALSA configuration file Apr 19 12:26:41 volumio volumio[11854]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:26:41 volumio volumio[11854]: info: Reading ALSA contributions from plugins. Apr 19 12:26:41 volumio volumio-remote-updater[617]: [2025-04-19 12:26:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745036800 101 Apr 19 12:26:41 volumio volumio[11854]: 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 Apr 19 12:26:41 volumio volumio[11854]: info: MPD Permissions set Apr 19 12:26:41 volumio volumio[11854]: info: MPD Permissions set Apr 19 12:26:41 volumio volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:41 volumio volumio[11854]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:41 volumio volumio[11854]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:26:41 volumio volumio[11854]: info: Output device has changed, restarting MPD Apr 19 12:26:41 volumio sudo[11977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:41 volumio sudo[11977]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:41 volumio sudo[11977]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:41 volumio volumio[11854]: info: MPD Permissions set Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio sudo[11980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio sudo[11980]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:41 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 19 12:26:41 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:41 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:41 volumio volumio[11854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 19 12:26:41 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:41 volumio volumio[11854]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 12:26:41 volumio sudo[11987]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:26:41 volumio sudo[11987]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:41 volumio volumio[11854]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 19 12:26:41 volumio volumio[11854]: info: Received Get System Version Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:41 volumio volumio[11854]: info: Received Get System Info Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:26:41 volumio sudo[11987]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:41 volumio volumio[11854]: info: Discovery: Getting this device information Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:41 volumio volumio[11854]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:41 volumio volumio[11854]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 12:26:41 volumio volumio[11854]: info: Received Get System Info Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:26:41 volumio volumio[11854]: info: Discovery: Getting this device information Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:41 volumio volumio[11854]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:41 volumio volumio[11854]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:41 volumio volumio[11854]: info: Listing playlists Apr 19 12:26:41 volumio volumio[11854]: info: [LastFM] finished init: Authenticated successfully! Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:26:41 volumio volumio[11854]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:26:41 volumio volumio[11854]: info: FusionDsp - Apr 19 12:26:41 volumio volumio[11854]: info: FusionDsp - Apr 19 12:26:41 volumio volumio[11854]: info: FusionDsp - undefined Apr 19 12:26:42 volumio volumio[11854]: info: FusionDsp - undefined Apr 19 12:26:42 volumio mpd[11990]: Apr 19 12:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 12:26:42 volumio systemd[1]: Started Music Player Daemon. Apr 19 12:26:42 volumio sudo[11980]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:42 volumio sudo[11938]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:42 volumio sudo[11946]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:42 volumio volumio[11854]: info: Completed starting Core Plugins Apr 19 12:26:42 volumio volumio[11854]: info: ------------------------------------------- Apr 19 12:26:42 volumio volumio[11854]: info: ----- MyVolumio plugins startup ---- Apr 19 12:26:42 volumio volumio[11854]: info: ------------------------------------------- Apr 19 12:26:42 volumio volumio[11854]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 19 12:26:42 volumio volumio[11854]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:26:42 volumio volumio[11854]: assert.ok(self.idling) Apr 19 12:26:42 volumio volumio[11854]: error: The expression evaluated to a falsy value: Apr 19 12:26:42 volumio volumio[11854]: assert.ok(self.idling) Apr 19 12:26:42 volumio volumio[11854]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:26:42 volumio volumio[11854]: assert.ok(self.idling) Apr 19 12:26:42 volumio volumio[11854]: error: The expression evaluated to a falsy value: Apr 19 12:26:42 volumio volumio[11854]: assert.ok(self.idling) Apr 19 12:26:42 volumio volumio[11854]: error: updateQueue error: null Apr 19 12:26:42 volumio volumio[11854]: info: MPD running with PID11990 Apr 19 12:26:42 volumio volumio[11854]: ,establishing connection Apr 19 12:26:42 volumio volumio[11854]: error: updateQueue error: null Apr 19 12:26:42 volumio volumio[11854]: info: Volumio called home Apr 19 12:26:42 volumio volumio[11854]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:26:42 volumio volumio[11854]: 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 Apr 19 12:26:42 volumio volumio[11854]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:26:42 volumio volumio[11854]: info: camilladsp service started and running in background, instance 1 Apr 19 12:26:42 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:42 volumio volumio[11854]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:26:42 volumio volumio[11854]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 19 12:26:42 volumio volumio[11854]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:26:43 volumio volumio[11854]: info: FusionDsp loaded Apr 19 12:26:43 volumio volumio[11854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:43 volumio sudo[12006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 19 12:26:43 volumio sudo[12006]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:43 volumio volumio[11854]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 19 12:26:43 volumio volumio[11854]: info: Adding Signal Path Element [object Object] Apr 19 12:26:43 volumio volumio[11854]: info: Adding fusiondspeq DSP Signal Path Element Apr 19 12:26:43 volumio volumio[11854]: info: FusionDsp - ---- installed callbackRead Apr 19 12:26:43 volumio volumio[11854]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:26:43 volumio sudo[12006]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:43 volumio volumio[11854]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 19 12:26:43 volumio volumio[11854]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 19 12:26:43 volumio volumio[11854]: at onErrorNT (internal/child_process.js:472:16) Apr 19 12:26:43 volumio volumio[11854]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Apr 19 12:26:43 volumio volumio[11854]: errno: -2, Apr 19 12:26:43 volumio volumio[11854]: code: 'ENOENT', Apr 19 12:26:43 volumio volumio[11854]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:26:43 volumio volumio[11854]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:26:43 volumio volumio[11854]: spawnargs: [ Apr 19 12:26:43 volumio volumio[11854]: '-p', Apr 19 12:26:43 volumio volumio[11854]: 9876, Apr 19 12:26:43 volumio volumio[11854]: '-o', Apr 19 12:26:43 volumio volumio[11854]: '/tmp/camilladsp.log', Apr 19 12:26:43 volumio volumio[11854]: '-l', Apr 19 12:26:43 volumio volumio[11854]: 'warn', Apr 19 12:26:43 volumio volumio[11854]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 19 12:26:43 volumio volumio[11854]: ] Apr 19 12:26:43 volumio volumio[11854]: } Apr 19 12:26:43 volumio volumio[11854]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:26:43 volumio sudo[12017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 12:25 Apr 19 12:26:43 volumio sudo[12017]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:43 volumio sudo[12017]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:43 volumio volumio-remote-updater[617]: [2025-04-19 12:26:43] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 19 12:26:43 volumio volumio-remote-updater[617]: [2025-04-19 12:26:43] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 19 12:26:43 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 19 12:26:43 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 19 12:26:43 volumio systemd[1]: Started dynamicswap service. Apr 19 12:26:43 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:26:43 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 19 12:26:43 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Apr 19 12:26:43 volumio systemd[1]: Started dynamicswap service. Apr 19 12:26:43 volumio systemd[1]: Stopped Volumio Backend Module. Apr 19 12:26:43 volumio systemd[1]: Started Volumio Backend Module. Apr 19 12:26:43 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:26:44 volumio volumio[12028]: info: ------------------------------------------- Apr 19 12:26:44 volumio volumio[12028]: info: ----- Volumio3 ---- Apr 19 12:26:44 volumio volumio[12028]: info: ------------------------------------------- Apr 19 12:26:44 volumio volumio[12028]: info: ----- System startup ---- Apr 19 12:26:44 volumio volumio[12028]: info: ------------------------------------------- Apr 19 12:26:44 volumio volumio[12028]: info: MYVOLUMIO Environment detected Apr 19 12:26:44 volumio volumio[12028]: info: Plugin folders cleanup Apr 19 12:26:44 volumio volumio[12028]: info: Scanning into folder /volumio/app/plugins/ Apr 19 12:26:44 volumio volumio[12028]: info: Scanning category audio_interface Apr 19 12:26:44 volumio volumio[12028]: info: Scanning category miscellanea Apr 19 12:26:44 volumio volumio[12028]: info: Scanning category music_service Apr 19 12:26:44 volumio volumio[12028]: info: Scanning category plugins.json Apr 19 12:26:44 volumio volumio[12028]: info: Scanning category system_controller Apr 19 12:26:44 volumio volumio[12028]: info: Scanning category user_interface Apr 19 12:26:44 volumio volumio[12028]: info: Scanning into folder /data/plugins/ Apr 19 12:26:44 volumio volumio[12028]: info: Scanning category audio_interface Apr 19 12:26:44 volumio volumio[12028]: info: Scanning category music_service Apr 19 12:26:44 volumio volumio[12028]: info: Scanning category user_interface Apr 19 12:26:44 volumio volumio[12028]: info: Plugin folders cleanup completed Apr 19 12:26:44 volumio volumio[12028]: info: ------------------------------------------- Apr 19 12:26:44 volumio volumio[12028]: info: ----- Core plugins startup ---- Apr 19 12:26:44 volumio volumio[12028]: info: ------------------------------------------- Apr 19 12:26:44 volumio volumio[12028]: info: Loading plugins from folder /volumio/app/plugins/ Apr 19 12:26:44 volumio volumio[12028]: info: Adding plugin upnp to MyMusic Plugins Apr 19 12:26:44 volumio volumio[12028]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 19 12:26:44 volumio volumio[12028]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 19 12:26:44 volumio volumio[12028]: info: Loading plugins from folder /data/plugins/ Apr 19 12:26:44 volumio volumio[12028]: info: Loading plugin "system"... Apr 19 12:26:44 volumio volumio[12028]: info: Loading plugin "appearance"... Apr 19 12:26:45 volumio volumio[12028]: info: Loading plugin "network"... Apr 19 12:26:45 volumio volumio[12028]: info: Refreshing Cached IP Addresses Apr 19 12:26:45 volumio sudo[12054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 12:26:45 volumio sudo[12054]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:45 volumio sudo[12054]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:45 volumio sudo[12056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 12:26:45 volumio sudo[12056]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:45 volumio volumio[12028]: info: Loading plugin "services"... Apr 19 12:26:45 volumio volumio[12028]: info: Loading plugin "alsa_controller"... Apr 19 12:26:45 volumio sudo[12056]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:45 volumio sudo[12064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 19 12:26:45 volumio sudo[12064]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:45 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:45 volumio volumio[12028]: info: Loading plugin "wizard"... Apr 19 12:26:45 volumio volumio[12028]: info: Loading plugin "networkfs"... Apr 19 12:26:45 volumio volumio[12028]: info: Cannot mount NAS CQ at system boot, trial number 1 ,retrying in 5 seconds Apr 19 12:26:45 volumio volumio[12028]: info: Starting Udev Watcher for removable devices Apr 19 12:26:45 volumio volumio[12028]: info: Ignoring mount for partition: boot Apr 19 12:26:45 volumio volumio[12028]: info: Ignoring mount for partition: volumio Apr 19 12:26:45 volumio volumio[12028]: info: Ignoring mount for partition: volumio_data Apr 19 12:26:45 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:26:45 volumio volumio[12028]: info: Loading plugin "volumio_command_line_client"... Apr 19 12:26:45 volumio volumio[12028]: info: Plugin upnp is not enabled Apr 19 12:26:45 volumio volumio[12028]: info: Loading plugin "my_music"... Apr 19 12:26:45 volumio volumio[12028]: info: Loading plugin "mpd"... Apr 19 12:26:46 volumio sudo[12064]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:46 volumio volumio[12028]: info: Plugin upnp_browser is not enabled Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "alarm-clock"... Apr 19 12:26:46 volumio volumio[12028]: info: Plugin airplay_emulation is not enabled Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "last_100"... Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "webradio"... Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "i2s_dacs"... Apr 19 12:26:46 volumio volumio[12028]: info: I2S DAC not set, start Auto-detection Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "volumiodiscovery"... Apr 19 12:26:46 volumio volumio[12028]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:46 volumio volumio[12028]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:46 volumio volumio[12028]: *** WARNING *** For more information see Apr 19 12:26:46 volumio node[12028]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:46 volumio volumio[12028]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:46 volumio volumio[12028]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:46 volumio volumio[12028]: *** WARNING *** For more information see Apr 19 12:26:46 volumio node[12028]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:46 volumio node[12028]: *** WARNING *** For more information see Apr 19 12:26:46 volumio node[12028]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:46 volumio node[12028]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:46 volumio node[12028]: *** WARNING *** For more information see Apr 19 12:26:46 volumio volumio[12028]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 19 12:26:46 volumio volumio[12028]: info: Discovery: Started advertising with name: Volumio Apr 19 12:26:46 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "outputs"... Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "albumart"... Apr 19 12:26:46 volumio volumio[12028]: info: Plugin example_plugin is not enabled Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "inputs"... Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "updater_comm"... Apr 19 12:26:46 volumio volumio[12028]: info: Plugin mpdemulation is not enabled Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "rest_api"... Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "websocket"... Apr 19 12:26:46 volumio volumio[12028]: info: Starting Socket.io Server version 2.3.0 Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "fusiondsp"... Apr 19 12:26:46 volumio volumio[12028]: Forking 1 albumart workers Apr 19 12:26:46 volumio volumio[12028]: info: Applying required configuration parameters for plugin fusiondsp Apr 19 12:26:46 volumio volumio[12028]: info: Loading plugin "volusonic"... Apr 19 12:26:47 volumio volumio[12028]: Starting albumart workers Apr 19 12:26:47 volumio volumio[12028]: info: Applying required configuration parameters for plugin volusonic Apr 19 12:26:47 volumio volumio[12028]: info: Loading plugin "lastfm"... Apr 19 12:26:47 volumio volumio[12028]: info: Loading i18n strings for locale zh Apr 19 12:26:47 volumio volumio[12028]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:47 volumio volumio[12028]: Updating browse sources language Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::initPlayerControls Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:26:47 volumio volumio[12028]: Express server listening on port 3000 Apr 19 12:26:47 volumio volumio[12028]: [Metrics] WebUI: 3s 745.99ms Apr 19 12:26:47 volumio volumio[12028]: info: Setting Device type: x86 Apr 19 12:26:47 volumio volumio[12028]: info: CoreStateMachine::resetVolumioState Apr 19 12:26:47 volumio volumio[12028]: info: CoreStateMachine::getcurrentVolume Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 12:26:47 volumio volumio[12028]: info: CoreStateMachine::pushState Apr 19 12:26:47 volumio volumio[12028]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:47 volumio volumio[12028]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 19 12:26:47 volumio volumio[12028]: info: Completed loading Core Plugins Apr 19 12:26:47 volumio volumio[12028]: info: Preparing to generate the ALSA configuration file Apr 19 12:26:47 volumio volumio[12028]: info: Volumio Network Manager: Network status updated: 2 Apr 19 12:26:47 volumio volumio[12028]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 19 12:26:47 volumio volumio[12028]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:26:47 volumio volumio[12028]: info: Reading ALSA contributions from plugins. Apr 19 12:26:47 volumio volumio[12028]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 19 12:26:47 volumio volumio[12028]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 19 12:26:47 volumio volumio[12028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 19 12:26:47 volumio volumio[12028]: info: Reloading queue from file Apr 19 12:26:47 volumio volumio[12028]: info: CoreStateMachine::setRepeat null single undefined Apr 19 12:26:47 volumio volumio[12028]: info: CoreStateMachine::pushState Apr 19 12:26:47 volumio volumio[12028]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:47 volumio volumio[12028]: info: CoreStateMachine::setRandom null Apr 19 12:26:47 volumio volumio[12028]: info: CoreStateMachine::pushState Apr 19 12:26:47 volumio volumio[12028]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:47 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:48 volumio volumio[12028]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 12:26:48 volumio volumio[12028]: info: Received Get System Info Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:26:48 volumio volumio[12028]: info: Discovery: Getting this device information Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:48 volumio volumio[12028]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:48 volumio volumio[12028]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:48 volumio volumio[12028]: info: Listing playlists Apr 19 12:26:48 volumio volumio[12028]: info: Discovery: adding ff0c7a9d-0254-430b-958c-60aff2e24e3f Apr 19 12:26:48 volumio volumio[12028]: info: Discovery: Found device Volumio Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:48 volumio volumio[12028]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:48 volumio volumio[12028]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:26:48 volumio volumio[12028]: info: Output device has changed, restarting MPD Apr 19 12:26:48 volumio sudo[12109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:48 volumio sudo[12109]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:48 volumio sudo[12109]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:48 volumio volumio[12028]: info: ___________ START PLUGINS ___________ Apr 19 12:26:48 volumio volumio[12028]: info: ControllerMpd::onStart: Initializing MPD Apr 19 12:26:48 volumio volumio[12028]: info: Creating MPD Configuration file Apr 19 12:26:48 volumio sudo[12112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:48 volumio sudo[12112]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:48 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:48 volumio volumio[12028]: info: [1745036808147] CoreMusicLibrary::Adding element Last_100 Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:48 volumio sudo[12115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:48 volumio sudo[12115]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:48 volumio volumio[12028]: info: [1745036808154] CoreMusicLibrary::Adding element Webradio Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:48 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:48 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:48 volumio volumio[12028]: info: Initializing BBC Radios Apr 19 12:26:48 volumio sudo[12115]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:48 volumio sudo[12117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:48 volumio sudo[12117]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:48 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:48 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 19 12:26:48 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:48 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:48 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: Loading i18n strings for locale zh Apr 19 12:26:48 volumio volumio[12028]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: FusionDsp - mixtype--------------------- None Apr 19 12:26:48 volumio sudo[12124]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:26:48 volumio sudo[12124]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:48 volumio volumio[12028]: info: Loading i18n strings for locale zh Apr 19 12:26:48 volumio sudo[12124]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:48 volumio volumio[12028]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:48 volumio volumio[12028]: Updating browse sources language Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:48 volumio volumio[12028]: info: [1745036808289] CoreMusicLibrary::Adding element Volusonic Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:48 volumio volumio[12028]: Cannot find translation for source Volusonic Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:48 volumio volumio[12028]: info: [1745036808293] CoreMusicLibrary::Adding element LastFM Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:48 volumio volumio[12028]: Cannot find translation for source Volusonic Apr 19 12:26:48 volumio volumio[12028]: Cannot find translation for source LastFM Apr 19 12:26:48 volumio volumio[12028]: info: [LastFM] scrobbler initiated! Apr 19 12:26:48 volumio volumio[12028]: info: [LastFM] extended logging: false Apr 19 12:26:48 volumio volumio[12028]: info: [LastFM] try scrobble stream/radio plays: true Apr 19 12:26:48 volumio volumio[12028]: info: [LastFM] Left init routine Apr 19 12:26:48 volumio volumio[12028]: info: [LastFM] Socket already connected: false Apr 19 12:26:48 volumio volumio[12028]: info: Volumio Calling Home Apr 19 12:26:48 volumio volumio[12028]: info: Preparing to generate the ALSA configuration file Apr 19 12:26:48 volumio volumio-remote-updater[617]: [2025-04-19 12:26:48] [connect] Successful connection Apr 19 12:26:48 volumio volumio[12028]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:26:48 volumio volumio[12028]: info: Reading ALSA contributions from plugins. Apr 19 12:26:48 volumio volumio-remote-updater[617]: [2025-04-19 12:26:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745036808 101 Apr 19 12:26:48 volumio volumio[12028]: 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 Apr 19 12:26:48 volumio volumio[12028]: info: MPD Permissions set Apr 19 12:26:48 volumio volumio[12028]: info: MPD Permissions set Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:26:48 volumio volumio[12028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:48 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:48 volumio volumio[12028]: info: FusionDsp - Apr 19 12:26:48 volumio volumio[12028]: info: FusionDsp - Apr 19 12:26:48 volumio volumio[12028]: info: FusionDsp - Apr 19 12:26:49 volumio volumio[12028]: info: FusionDsp - undefined Apr 19 12:26:49 volumio mpd[12133]: Apr 19 12:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 12:26:49 volumio systemd[1]: Started Music Player Daemon. Apr 19 12:26:49 volumio sudo[12112]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:49 volumio sudo[12117]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:49 volumio volumio[12028]: info: FusionDsp - undefined Apr 19 12:26:49 volumio volumio[12028]: info: FusionDsp - undefined Apr 19 12:26:49 volumio volumio[12028]: info: [LastFM] finished init: Authenticated successfully! Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::volumioGetState Apr 19 12:26:49 volumio volumio[12028]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:49 volumio volumio[12028]: info: Volumio called home Apr 19 12:26:49 volumio volumio[12028]: info: Completed starting Core Plugins Apr 19 12:26:49 volumio volumio[12028]: info: ------------------------------------------- Apr 19 12:26:49 volumio volumio[12028]: info: ----- MyVolumio plugins startup ---- Apr 19 12:26:49 volumio volumio[12028]: info: ------------------------------------------- Apr 19 12:26:49 volumio volumio[12028]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 19 12:26:49 volumio volumio[12028]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:26:49 volumio volumio[12028]: info: Output device has changed, restarting MPD Apr 19 12:26:49 volumio sudo[12154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:49 volumio sudo[12154]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:49 volumio sudo[12154]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:49 volumio sudo[12157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:49 volumio sudo[12157]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:49 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 12:26:49 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:49 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:49 volumio volumio[12028]: error: MPD error: Error: write EPIPE Apr 19 12:26:49 volumio volumio[12028]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 19 12:26:49 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:49 volumio sudo[12159]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:26:49 volumio sudo[12159]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:49 volumio sudo[12159]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:49 volumio volumio[12028]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:26:49 volumio volumio[12028]: assert.ok(self.idling) Apr 19 12:26:49 volumio volumio[12028]: error: The expression evaluated to a falsy value: Apr 19 12:26:49 volumio volumio[12028]: assert.ok(self.idling) Apr 19 12:26:49 volumio volumio[12028]: error: MPD error: Error: write EPIPE Apr 19 12:26:49 volumio volumio[12028]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 19 12:26:49 volumio volumio[12028]: info: MPD Permissions set Apr 19 12:26:49 volumio volumio[12028]: info: MPD running with PID12133 Apr 19 12:26:49 volumio volumio[12028]: ,establishing connection Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:49 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:49 volumio volumio[12028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 19 12:26:50 volumio volumio[12028]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:26:50 volumio volumio[12028]: 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 Apr 19 12:26:50 volumio volumio[12028]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:26:50 volumio volumio[12028]: info: camilladsp service started and running in background, instance 1 Apr 19 12:26:50 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:50 volumio volumio[12028]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:26:50 volumio volumio[12028]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 19 12:26:50 volumio volumio[12028]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:26:50 volumio volumio[12028]: info: FusionDsp loaded Apr 19 12:26:50 volumio volumio[12028]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:50 volumio sudo[12179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 19 12:26:50 volumio sudo[12179]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:50 volumio volumio[12028]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 19 12:26:50 volumio volumio[12028]: info: Adding Signal Path Element [object Object] Apr 19 12:26:50 volumio volumio[12028]: info: Adding fusiondspeq DSP Signal Path Element Apr 19 12:26:50 volumio volumio[12028]: info: FusionDsp - ---- installed callbackRead Apr 19 12:26:50 volumio volumio[12028]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:26:50 volumio volumio[12028]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 19 12:26:50 volumio volumio[12028]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 19 12:26:50 volumio volumio[12028]: at onErrorNT (internal/child_process.js:472:16) Apr 19 12:26:50 volumio volumio[12028]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Apr 19 12:26:50 volumio volumio[12028]: errno: -2, Apr 19 12:26:50 volumio volumio[12028]: code: 'ENOENT', Apr 19 12:26:50 volumio volumio[12028]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:26:50 volumio volumio[12028]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:26:50 volumio volumio[12028]: spawnargs: [ Apr 19 12:26:50 volumio volumio[12028]: '-p', Apr 19 12:26:50 volumio volumio[12028]: 9876, Apr 19 12:26:50 volumio volumio[12028]: '-o', Apr 19 12:26:50 volumio volumio[12028]: '/tmp/camilladsp.log', Apr 19 12:26:50 volumio volumio[12028]: '-l', Apr 19 12:26:50 volumio volumio[12028]: 'warn', Apr 19 12:26:50 volumio volumio[12028]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 19 12:26:50 volumio volumio[12028]: ] Apr 19 12:26:50 volumio volumio[12028]: } Apr 19 12:26:50 volumio volumio[12028]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:26:50 volumio sudo[12179]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:50 volumio mpd[12161]: Apr 19 12:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 12:26:50 volumio systemd[1]: Started Music Player Daemon. Apr 19 12:26:50 volumio sudo[12157]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:50 volumio sudo[12192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 12:25 Apr 19 12:26:50 volumio sudo[12192]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:50 volumio sudo[12192]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:50 volumio volumio-remote-updater[617]: [2025-04-19 12:26:50] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 19 12:26:50 volumio volumio-remote-updater[617]: [2025-04-19 12:26:50] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 19 12:26:50 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 19 12:26:50 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 19 12:26:50 volumio systemd[1]: Started dynamicswap service. Apr 19 12:26:50 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:26:51 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 19 12:26:51 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Apr 19 12:26:51 volumio systemd[1]: Started dynamicswap service. Apr 19 12:26:51 volumio systemd[1]: Stopped Volumio Backend Module. Apr 19 12:26:51 volumio systemd[1]: Started Volumio Backend Module. Apr 19 12:26:51 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:26:51 volumio volumio[12203]: info: ------------------------------------------- Apr 19 12:26:51 volumio volumio[12203]: info: ----- Volumio3 ---- Apr 19 12:26:51 volumio volumio[12203]: info: ------------------------------------------- Apr 19 12:26:51 volumio volumio[12203]: info: ----- System startup ---- Apr 19 12:26:51 volumio volumio[12203]: info: ------------------------------------------- Apr 19 12:26:52 volumio volumio[12203]: info: MYVOLUMIO Environment detected Apr 19 12:26:52 volumio volumio[12203]: info: Plugin folders cleanup Apr 19 12:26:52 volumio volumio[12203]: info: Scanning into folder /volumio/app/plugins/ Apr 19 12:26:52 volumio volumio[12203]: info: Scanning category audio_interface Apr 19 12:26:52 volumio volumio[12203]: info: Scanning category miscellanea Apr 19 12:26:52 volumio volumio[12203]: info: Scanning category music_service Apr 19 12:26:52 volumio volumio[12203]: info: Scanning category plugins.json Apr 19 12:26:52 volumio volumio[12203]: info: Scanning category system_controller Apr 19 12:26:52 volumio volumio[12203]: info: Scanning category user_interface Apr 19 12:26:52 volumio volumio[12203]: info: Scanning into folder /data/plugins/ Apr 19 12:26:52 volumio volumio[12203]: info: Scanning category audio_interface Apr 19 12:26:52 volumio volumio[12203]: info: Scanning category music_service Apr 19 12:26:52 volumio volumio[12203]: info: Scanning category user_interface Apr 19 12:26:52 volumio volumio[12203]: info: Plugin folders cleanup completed Apr 19 12:26:52 volumio volumio[12203]: info: ------------------------------------------- Apr 19 12:26:52 volumio volumio[12203]: info: ----- Core plugins startup ---- Apr 19 12:26:52 volumio volumio[12203]: info: ------------------------------------------- Apr 19 12:26:52 volumio volumio[12203]: info: Loading plugins from folder /volumio/app/plugins/ Apr 19 12:26:52 volumio volumio[12203]: info: Adding plugin upnp to MyMusic Plugins Apr 19 12:26:52 volumio volumio[12203]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 19 12:26:52 volumio volumio[12203]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 19 12:26:52 volumio volumio[12203]: info: Loading plugins from folder /data/plugins/ Apr 19 12:26:52 volumio volumio[12203]: info: Loading plugin "system"... Apr 19 12:26:52 volumio volumio[12203]: info: Loading plugin "appearance"... Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "network"... Apr 19 12:26:53 volumio volumio[12203]: info: Refreshing Cached IP Addresses Apr 19 12:26:53 volumio sudo[12229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 12:26:53 volumio sudo[12229]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:53 volumio sudo[12229]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:53 volumio sudo[12231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 12:26:53 volumio sudo[12231]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:53 volumio sudo[12231]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "services"... Apr 19 12:26:53 volumio sudo[12244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "alsa_controller"... Apr 19 12:26:53 volumio sudo[12244]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:53 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "wizard"... Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "networkfs"... Apr 19 12:26:53 volumio volumio[12203]: info: Cannot mount NAS CQ at system boot, trial number 1 ,retrying in 5 seconds Apr 19 12:26:53 volumio volumio[12203]: info: Starting Udev Watcher for removable devices Apr 19 12:26:53 volumio volumio[12203]: info: Ignoring mount for partition: boot Apr 19 12:26:53 volumio volumio[12203]: info: Ignoring mount for partition: volumio Apr 19 12:26:53 volumio volumio[12203]: info: Ignoring mount for partition: volumio_data Apr 19 12:26:53 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "volumio_command_line_client"... Apr 19 12:26:53 volumio volumio[12203]: info: Plugin upnp is not enabled Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "my_music"... Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "mpd"... Apr 19 12:26:53 volumio volumio[12203]: info: Plugin upnp_browser is not enabled Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "alarm-clock"... Apr 19 12:26:53 volumio volumio[12203]: info: Plugin airplay_emulation is not enabled Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "last_100"... Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "webradio"... Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "i2s_dacs"... Apr 19 12:26:53 volumio volumio[12203]: info: I2S DAC not set, start Auto-detection Apr 19 12:26:53 volumio volumio[12203]: info: Loading plugin "volumiodiscovery"... Apr 19 12:26:53 volumio volumio[12203]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:53 volumio volumio[12203]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:53 volumio volumio[12203]: *** WARNING *** For more information see Apr 19 12:26:53 volumio node[12203]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:53 volumio volumio[12203]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:53 volumio volumio[12203]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:53 volumio volumio[12203]: *** WARNING *** For more information see Apr 19 12:26:53 volumio node[12203]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:53 volumio node[12203]: *** WARNING *** For more information see Apr 19 12:26:53 volumio node[12203]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:26:53 volumio node[12203]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:26:53 volumio node[12203]: *** WARNING *** For more information see Apr 19 12:26:53 volumio volumio[12203]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 19 12:26:53 volumio volumio[12203]: info: Discovery: Started advertising with name: Volumio Apr 19 12:26:54 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:26:54 volumio volumio[12203]: info: Loading plugin "outputs"... Apr 19 12:26:54 volumio volumio[12203]: info: Loading plugin "albumart"... Apr 19 12:26:54 volumio volumio[12203]: info: Plugin example_plugin is not enabled Apr 19 12:26:54 volumio volumio[12203]: info: Loading plugin "inputs"... Apr 19 12:26:54 volumio volumio[12203]: info: Loading plugin "updater_comm"... Apr 19 12:26:54 volumio volumio[12203]: Forking 1 albumart workers Apr 19 12:26:54 volumio volumio[12203]: info: Plugin mpdemulation is not enabled Apr 19 12:26:54 volumio volumio[12203]: info: Loading plugin "rest_api"... Apr 19 12:26:54 volumio volumio[12203]: info: Loading plugin "websocket"... Apr 19 12:26:54 volumio volumio[12203]: info: Starting Socket.io Server version 2.3.0 Apr 19 12:26:54 volumio volumio[12203]: info: Loading plugin "fusiondsp"... Apr 19 12:26:54 volumio volumio[12203]: info: Applying required configuration parameters for plugin fusiondsp Apr 19 12:26:54 volumio volumio[12203]: info: Loading plugin "volusonic"... Apr 19 12:26:54 volumio volumio[12203]: Starting albumart workers Apr 19 12:26:55 volumio volumio[12203]: info: Applying required configuration parameters for plugin volusonic Apr 19 12:26:55 volumio volumio[12203]: info: Loading plugin "lastfm"... Apr 19 12:26:55 volumio volumio[12203]: info: Loading i18n strings for locale zh Apr 19 12:26:55 volumio volumio[12203]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:55 volumio volumio[12203]: Updating browse sources language Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::initPlayerControls Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: Express server listening on port 3000 Apr 19 12:26:55 volumio volumio[12203]: [Metrics] WebUI: 3s 962.82ms Apr 19 12:26:55 volumio volumio[12203]: info: Setting Device type: x86 Apr 19 12:26:55 volumio volumio[12203]: info: CoreStateMachine::resetVolumioState Apr 19 12:26:55 volumio volumio[12203]: info: CoreStateMachine::getcurrentVolume Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 12:26:55 volumio volumio[12203]: info: CoreStateMachine::pushState Apr 19 12:26:55 volumio volumio[12203]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 19 12:26:55 volumio volumio[12203]: info: Completed loading Core Plugins Apr 19 12:26:55 volumio volumio[12203]: info: Preparing to generate the ALSA configuration file Apr 19 12:26:55 volumio volumio[12203]: info: Volumio Network Manager: Network status updated: 2 Apr 19 12:26:55 volumio volumio[12203]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 19 12:26:55 volumio volumio[12203]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:26:55 volumio volumio[12203]: info: Reading ALSA contributions from plugins. Apr 19 12:26:55 volumio volumio[12203]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 19 12:26:55 volumio volumio[12203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 19 12:26:55 volumio volumio[12203]: info: Reloading queue from file Apr 19 12:26:55 volumio volumio[12203]: info: CoreStateMachine::setRepeat null single undefined Apr 19 12:26:55 volumio volumio[12203]: info: CoreStateMachine::pushState Apr 19 12:26:55 volumio volumio[12203]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:55 volumio volumio[12203]: info: CoreStateMachine::setRandom null Apr 19 12:26:55 volumio volumio[12203]: info: CoreStateMachine::pushState Apr 19 12:26:55 volumio volumio[12203]: info: CorePlayQueue::getTrack 0 Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::volumioPushState Apr 19 12:26:55 volumio volumio[12203]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:26:55 volumio volumio[12203]: info: Output device has changed, restarting MPD Apr 19 12:26:55 volumio sudo[12285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:55 volumio sudo[12285]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:55 volumio volumio[12203]: info: ___________ START PLUGINS ___________ Apr 19 12:26:55 volumio sudo[12285]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:55 volumio volumio-remote-updater[617]: [2025-04-19 12:26:55] [connect] Successful connection Apr 19 12:26:55 volumio volumio[12203]: info: ControllerMpd::onStart: Initializing MPD Apr 19 12:26:55 volumio volumio[12203]: info: Creating MPD Configuration file Apr 19 12:26:55 volumio sudo[12287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:55 volumio sudo[12287]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:55 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 12:26:55 volumio sudo[12293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:55 volumio sudo[12293]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:55 volumio sudo[12293]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:55 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:55 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:55 volumio volumio[12203]: info: [1745036815854] CoreMusicLibrary::Adding element Last_100 Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:55 volumio volumio[12203]: info: [1745036815858] CoreMusicLibrary::Adding element Webradio Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:55 volumio sudo[12297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:55 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:55 volumio sudo[12297]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:55 volumio volumio[12203]: info: Initializing BBC Radios Apr 19 12:26:55 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 19 12:26:55 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:55 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:55 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: info: Loading i18n strings for locale zh Apr 19 12:26:55 volumio volumio[12203]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:55 volumio volumio[12203]: info: FusionDsp - mixtype--------------------- None Apr 19 12:26:55 volumio volumio[12203]: info: Loading i18n strings for locale zh Apr 19 12:26:55 volumio sudo[12302]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:26:55 volumio volumio[12203]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:26:55 volumio volumio[12203]: Updating browse sources language Apr 19 12:26:55 volumio sudo[12302]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:55 volumio volumio[12203]: info: [1745036815984] CoreMusicLibrary::Adding element Volusonic Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:55 volumio volumio[12203]: Cannot find translation for source Volusonic Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:26:55 volumio volumio[12203]: info: [1745036815986] CoreMusicLibrary::Adding element LastFM Apr 19 12:26:55 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:26:55 volumio volumio[12203]: Cannot find translation for source Volusonic Apr 19 12:26:55 volumio volumio[12203]: Cannot find translation for source LastFM Apr 19 12:26:55 volumio volumio[12203]: info: [LastFM] scrobbler initiated! Apr 19 12:26:55 volumio volumio[12203]: info: [LastFM] extended logging: false Apr 19 12:26:55 volumio volumio[12203]: info: [LastFM] try scrobble stream/radio plays: true Apr 19 12:26:55 volumio sudo[12302]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:55 volumio volumio[12203]: info: [LastFM] Left init routine Apr 19 12:26:55 volumio volumio[12203]: info: [LastFM] Socket already connected: false Apr 19 12:26:55 volumio volumio[12203]: info: Volumio Calling Home Apr 19 12:26:56 volumio volumio[12203]: info: Preparing to generate the ALSA configuration file Apr 19 12:26:56 volumio volumio[12203]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:26:56 volumio volumio[12203]: info: Reading ALSA contributions from plugins. Apr 19 12:26:56 volumio volumio-remote-updater[617]: [2025-04-19 12:26:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745036815 101 Apr 19 12:26:56 volumio volumio[12203]: 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 Apr 19 12:26:56 volumio volumio[12203]: info: MPD Permissions set Apr 19 12:26:56 volumio volumio[12203]: info: MPD Permissions set Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:26:56 volumio volumio[12203]: info: Output device has changed, restarting MPD Apr 19 12:26:56 volumio sudo[12329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:26:56 volumio sudo[12329]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:56 volumio sudo[12329]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:56 volumio volumio[12203]: info: MPD Permissions set Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:26:56 volumio sudo[12332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:26:56 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:56 volumio sudo[12332]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:56 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 19 12:26:56 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:26:56 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:26:56 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:26:56 volumio sudo[12340]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:26:56 volumio sudo[12340]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:56 volumio sudo[12340]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:57 volumio mpd[12342]: Apr 19 12:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 12:26:57 volumio systemd[1]: Started Music Player Daemon. Apr 19 12:26:57 volumio sudo[12297]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:57 volumio sudo[12287]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:57 volumio sudo[12332]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:57 volumio volumio[12203]: info: Completed starting Core Plugins Apr 19 12:26:57 volumio volumio[12203]: info: ------------------------------------------- Apr 19 12:26:57 volumio volumio[12203]: info: ----- MyVolumio plugins startup ---- Apr 19 12:26:57 volumio volumio[12203]: info: ------------------------------------------- Apr 19 12:26:57 volumio volumio[12203]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 19 12:26:57 volumio volumio[12203]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:26:57 volumio volumio[12203]: assert.ok(self.idling) Apr 19 12:26:57 volumio volumio[12203]: error: The expression evaluated to a falsy value: Apr 19 12:26:57 volumio volumio[12203]: assert.ok(self.idling) Apr 19 12:26:57 volumio volumio[12203]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:26:57 volumio volumio[12203]: assert.ok(self.idling) Apr 19 12:26:57 volumio volumio[12203]: error: The expression evaluated to a falsy value: Apr 19 12:26:57 volumio volumio[12203]: assert.ok(self.idling) Apr 19 12:26:57 volumio volumio[12203]: info: MPD running with PID12342 Apr 19 12:26:57 volumio volumio[12203]: ,establishing connection Apr 19 12:26:57 volumio volumio[12203]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:26:57 volumio volumio[12203]: assert.ok(self.idling) Apr 19 12:26:57 volumio volumio[12203]: error: The expression evaluated to a falsy value: Apr 19 12:26:57 volumio volumio[12203]: assert.ok(self.idling) Apr 19 12:26:57 volumio volumio[12203]: error: updateQueue error: null Apr 19 12:26:57 volumio volumio[12203]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:26:57 volumio volumio[12203]: 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 Apr 19 12:26:57 volumio volumio[12203]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:26:58 volumio volumio[12203]: info: camilladsp service started and running in background, instance 1 Apr 19 12:26:58 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:26:58 volumio volumio[12203]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:26:58 volumio volumio[12203]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 19 12:26:58 volumio volumio[12203]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:26:58 volumio volumio[12203]: info: FusionDsp loaded Apr 19 12:26:58 volumio volumio[12203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:26:58 volumio sudo[12357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 19 12:26:58 volumio sudo[12357]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:58 volumio volumio[12203]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 19 12:26:58 volumio volumio[12203]: info: Adding Signal Path Element [object Object] Apr 19 12:26:58 volumio volumio[12203]: info: Adding fusiondspeq DSP Signal Path Element Apr 19 12:26:58 volumio volumio[12203]: info: FusionDsp - ---- installed callbackRead Apr 19 12:26:58 volumio volumio[12203]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:26:58 volumio sudo[12357]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:58 volumio volumio[12203]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 19 12:26:58 volumio volumio[12203]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 19 12:26:58 volumio volumio[12203]: at onErrorNT (internal/child_process.js:472:16) Apr 19 12:26:58 volumio volumio[12203]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Apr 19 12:26:58 volumio volumio[12203]: errno: -2, Apr 19 12:26:58 volumio volumio[12203]: code: 'ENOENT', Apr 19 12:26:58 volumio volumio[12203]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:26:58 volumio volumio[12203]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:26:58 volumio volumio[12203]: spawnargs: [ Apr 19 12:26:58 volumio volumio[12203]: '-p', Apr 19 12:26:58 volumio volumio[12203]: 9876, Apr 19 12:26:58 volumio volumio[12203]: '-o', Apr 19 12:26:58 volumio volumio[12203]: '/tmp/camilladsp.log', Apr 19 12:26:58 volumio volumio[12203]: '-l', Apr 19 12:26:58 volumio volumio[12203]: 'warn', Apr 19 12:26:58 volumio volumio[12203]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 19 12:26:58 volumio volumio[12203]: ] Apr 19 12:26:58 volumio volumio[12203]: } Apr 19 12:26:58 volumio volumio[12203]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:26:58 volumio sudo[12368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 12:25 Apr 19 12:26:58 volumio sudo[12368]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:26:58 volumio sudo[12244]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:58 volumio sudo[12368]: pam_unix(sudo:session): session closed for user root Apr 19 12:26:58 volumio volumio-remote-updater[617]: [2025-04-19 12:26:58] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 19 12:26:58 volumio volumio-remote-updater[617]: [2025-04-19 12:26:58] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 19 12:26:58 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 19 12:26:58 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 19 12:26:58 volumio systemd[1]: Started dynamicswap service. Apr 19 12:26:58 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:26:58 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 19 12:26:58 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Apr 19 12:26:58 volumio systemd[1]: Started dynamicswap service. Apr 19 12:26:58 volumio systemd[1]: Stopped Volumio Backend Module. Apr 19 12:26:58 volumio systemd[1]: Started Volumio Backend Module. Apr 19 12:26:58 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:27:00 volumio volumio[12379]: info: ------------------------------------------- Apr 19 12:27:00 volumio volumio[12379]: info: ----- Volumio3 ---- Apr 19 12:27:00 volumio volumio[12379]: info: ------------------------------------------- Apr 19 12:27:00 volumio volumio[12379]: info: ----- System startup ---- Apr 19 12:27:00 volumio volumio[12379]: info: ------------------------------------------- Apr 19 12:27:00 volumio volumio[12379]: info: MYVOLUMIO Environment detected Apr 19 12:27:00 volumio volumio[12379]: info: Plugin folders cleanup Apr 19 12:27:00 volumio volumio[12379]: info: Scanning into folder /volumio/app/plugins/ Apr 19 12:27:00 volumio volumio[12379]: info: Scanning category audio_interface Apr 19 12:27:00 volumio volumio[12379]: info: Scanning category miscellanea Apr 19 12:27:00 volumio volumio[12379]: info: Scanning category music_service Apr 19 12:27:00 volumio volumio[12379]: info: Scanning category plugins.json Apr 19 12:27:00 volumio volumio[12379]: info: Scanning category system_controller Apr 19 12:27:00 volumio volumio[12379]: info: Scanning category user_interface Apr 19 12:27:00 volumio volumio[12379]: info: Scanning into folder /data/plugins/ Apr 19 12:27:00 volumio volumio[12379]: info: Scanning category audio_interface Apr 19 12:27:00 volumio volumio[12379]: info: Scanning category music_service Apr 19 12:27:00 volumio volumio[12379]: info: Scanning category user_interface Apr 19 12:27:00 volumio volumio[12379]: info: Plugin folders cleanup completed Apr 19 12:27:00 volumio volumio[12379]: info: ------------------------------------------- Apr 19 12:27:00 volumio volumio[12379]: info: ----- Core plugins startup ---- Apr 19 12:27:00 volumio volumio[12379]: info: ------------------------------------------- Apr 19 12:27:00 volumio volumio[12379]: info: Loading plugins from folder /volumio/app/plugins/ Apr 19 12:27:00 volumio volumio[12379]: info: Adding plugin upnp to MyMusic Plugins Apr 19 12:27:00 volumio volumio[12379]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 19 12:27:00 volumio volumio[12379]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 19 12:27:00 volumio volumio[12379]: info: Loading plugins from folder /data/plugins/ Apr 19 12:27:00 volumio volumio[12379]: info: Loading plugin "system"... Apr 19 12:27:00 volumio volumio[12379]: info: Loading plugin "appearance"... Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "network"... Apr 19 12:27:01 volumio volumio[12379]: info: Refreshing Cached IP Addresses Apr 19 12:27:01 volumio sudo[12405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 12:27:01 volumio sudo[12405]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:01 volumio sudo[12405]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:01 volumio sudo[12407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 12:27:01 volumio sudo[12407]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:01 volumio sudo[12407]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "services"... Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "alsa_controller"... Apr 19 12:27:01 volumio sudo[12416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 19 12:27:01 volumio sudo[12416]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:01 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "wizard"... Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "networkfs"... Apr 19 12:27:01 volumio volumio[12379]: info: Cannot mount NAS CQ at system boot, trial number 1 ,retrying in 5 seconds Apr 19 12:27:01 volumio volumio[12379]: info: Starting Udev Watcher for removable devices Apr 19 12:27:01 volumio volumio[12379]: info: Ignoring mount for partition: boot Apr 19 12:27:01 volumio volumio[12379]: info: Ignoring mount for partition: volumio Apr 19 12:27:01 volumio volumio[12379]: info: Ignoring mount for partition: volumio_data Apr 19 12:27:01 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "volumio_command_line_client"... Apr 19 12:27:01 volumio volumio[12379]: info: Plugin upnp is not enabled Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "my_music"... Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "mpd"... Apr 19 12:27:01 volumio sudo[12416]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:01 volumio volumio[12379]: info: Plugin upnp_browser is not enabled Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "alarm-clock"... Apr 19 12:27:01 volumio volumio[12379]: info: Plugin airplay_emulation is not enabled Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "last_100"... Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "webradio"... Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "i2s_dacs"... Apr 19 12:27:01 volumio volumio[12379]: info: I2S DAC not set, start Auto-detection Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "volumiodiscovery"... Apr 19 12:27:01 volumio volumio[12379]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:01 volumio volumio[12379]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:01 volumio volumio[12379]: *** WARNING *** For more information see Apr 19 12:27:01 volumio node[12379]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:01 volumio volumio[12379]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:01 volumio volumio[12379]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:01 volumio volumio[12379]: *** WARNING *** For more information see Apr 19 12:27:01 volumio node[12379]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:01 volumio node[12379]: *** WARNING *** For more information see Apr 19 12:27:01 volumio node[12379]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:01 volumio node[12379]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:01 volumio node[12379]: *** WARNING *** For more information see Apr 19 12:27:01 volumio volumio[12379]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 19 12:27:01 volumio volumio[12379]: info: Discovery: Started advertising with name: Volumio Apr 19 12:27:01 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "outputs"... Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "albumart"... Apr 19 12:27:01 volumio volumio[12379]: info: Plugin example_plugin is not enabled Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "inputs"... Apr 19 12:27:01 volumio volumio[12379]: info: Loading plugin "updater_comm"... Apr 19 12:27:02 volumio volumio[12379]: info: Plugin mpdemulation is not enabled Apr 19 12:27:02 volumio volumio[12379]: info: Loading plugin "rest_api"... Apr 19 12:27:02 volumio volumio[12379]: info: Loading plugin "websocket"... Apr 19 12:27:02 volumio volumio[12379]: info: Starting Socket.io Server version 2.3.0 Apr 19 12:27:02 volumio volumio[12379]: info: Loading plugin "fusiondsp"... Apr 19 12:27:02 volumio volumio[12379]: Forking 1 albumart workers Apr 19 12:27:02 volumio volumio[12379]: info: Applying required configuration parameters for plugin fusiondsp Apr 19 12:27:02 volumio volumio[12379]: info: Loading plugin "volusonic"... Apr 19 12:27:02 volumio volumio[12379]: Starting albumart workers Apr 19 12:27:02 volumio volumio[12379]: info: Applying required configuration parameters for plugin volusonic Apr 19 12:27:02 volumio volumio[12379]: info: Loading plugin "lastfm"... Apr 19 12:27:03 volumio volumio[12379]: info: Loading i18n strings for locale zh Apr 19 12:27:03 volumio volumio[12379]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:27:03 volumio volumio[12379]: Updating browse sources language Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::initPlayerControls Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: Express server listening on port 3000 Apr 19 12:27:03 volumio volumio[12379]: [Metrics] WebUI: 3s 838.36ms Apr 19 12:27:03 volumio volumio[12379]: info: Setting Device type: x86 Apr 19 12:27:03 volumio volumio[12379]: info: CoreStateMachine::resetVolumioState Apr 19 12:27:03 volumio volumio[12379]: info: CoreStateMachine::getcurrentVolume Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 12:27:03 volumio volumio[12379]: info: CoreStateMachine::pushState Apr 19 12:27:03 volumio volumio[12379]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::volumioPushState Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 19 12:27:03 volumio volumio[12379]: info: Completed loading Core Plugins Apr 19 12:27:03 volumio volumio[12379]: info: Preparing to generate the ALSA configuration file Apr 19 12:27:03 volumio volumio[12379]: info: Volumio Network Manager: Network status updated: 2 Apr 19 12:27:03 volumio volumio[12379]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 19 12:27:03 volumio volumio[12379]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:27:03 volumio volumio[12379]: info: Reading ALSA contributions from plugins. Apr 19 12:27:03 volumio volumio[12379]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 19 12:27:03 volumio volumio[12379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 19 12:27:03 volumio volumio-remote-updater[617]: [2025-04-19 12:27:03] [connect] Successful connection Apr 19 12:27:03 volumio volumio[12379]: info: Reloading queue from file Apr 19 12:27:03 volumio volumio[12379]: info: CoreStateMachine::setRepeat null single undefined Apr 19 12:27:03 volumio volumio[12379]: info: CoreStateMachine::pushState Apr 19 12:27:03 volumio volumio[12379]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::volumioPushState Apr 19 12:27:03 volumio volumio[12379]: info: CoreStateMachine::setRandom null Apr 19 12:27:03 volumio volumio[12379]: info: CoreStateMachine::pushState Apr 19 12:27:03 volumio volumio[12379]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::volumioPushState Apr 19 12:27:03 volumio volumio-remote-updater[617]: [2025-04-19 12:27:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745036823 101 Apr 19 12:27:03 volumio volumio[12379]: 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 Apr 19 12:27:03 volumio volumio[12379]: info: Discovery: adding ff0c7a9d-0254-430b-958c-60aff2e24e3f Apr 19 12:27:03 volumio volumio[12379]: info: Discovery: Found device Volumio Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:03 volumio volumio[12379]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:03 volumio volumio[12379]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:27:03 volumio volumio[12379]: info: Output device has changed, restarting MPD Apr 19 12:27:03 volumio sudo[12460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:27:03 volumio sudo[12460]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:03 volumio sudo[12460]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:03 volumio volumio[12379]: info: ___________ START PLUGINS ___________ Apr 19 12:27:03 volumio volumio[12379]: info: ControllerMpd::onStart: Initializing MPD Apr 19 12:27:03 volumio volumio[12379]: info: Creating MPD Configuration file Apr 19 12:27:03 volumio sudo[12462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:27:03 volumio sudo[12462]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:03 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 12:27:03 volumio sudo[12466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:27:03 volumio sudo[12466]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:03 volumio sudo[12466]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:03 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:27:03 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:27:03 volumio volumio[12379]: info: [1745036823685] CoreMusicLibrary::Adding element Last_100 Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:03 volumio sudo[12468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:27:03 volumio sudo[12468]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:03 volumio volumio[12379]: info: [1745036823689] CoreMusicLibrary::Adding element Webradio Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:03 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:27:03 volumio volumio[12379]: info: Initializing BBC Radios Apr 19 12:27:03 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 19 12:27:03 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:27:03 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:27:03 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: info: Loading i18n strings for locale zh Apr 19 12:27:03 volumio volumio[12379]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:03 volumio volumio[12379]: info: FusionDsp - mixtype--------------------- None Apr 19 12:27:03 volumio sudo[12473]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:27:03 volumio sudo[12473]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:03 volumio volumio[12379]: info: Loading i18n strings for locale zh Apr 19 12:27:03 volumio sudo[12473]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:03 volumio volumio[12379]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:27:03 volumio volumio[12379]: Updating browse sources language Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:03 volumio volumio[12379]: info: [1745036823808] CoreMusicLibrary::Adding element Volusonic Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:03 volumio volumio[12379]: Cannot find translation for source Volusonic Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:03 volumio volumio[12379]: info: [1745036823811] CoreMusicLibrary::Adding element LastFM Apr 19 12:27:03 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:03 volumio volumio[12379]: Cannot find translation for source Volusonic Apr 19 12:27:03 volumio volumio[12379]: Cannot find translation for source LastFM Apr 19 12:27:03 volumio volumio[12379]: info: [LastFM] scrobbler initiated! Apr 19 12:27:03 volumio volumio[12379]: info: [LastFM] extended logging: false Apr 19 12:27:03 volumio volumio[12379]: info: [LastFM] try scrobble stream/radio plays: true Apr 19 12:27:03 volumio volumio[12379]: info: [LastFM] Left init routine Apr 19 12:27:03 volumio volumio[12379]: info: [LastFM] Socket already connected: false Apr 19 12:27:03 volumio volumio[12379]: info: Volumio Calling Home Apr 19 12:27:03 volumio volumio[12379]: info: Preparing to generate the ALSA configuration file Apr 19 12:27:03 volumio volumio[12379]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:27:03 volumio volumio[12379]: info: Reading ALSA contributions from plugins. Apr 19 12:27:04 volumio volumio[12379]: info: MPD Permissions set Apr 19 12:27:04 volumio volumio[12379]: info: MPD Permissions set Apr 19 12:27:04 volumio volumio[12379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:04 volumio volumio[12379]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:04 volumio volumio[12379]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:27:04 volumio volumio[12379]: info: Output device has changed, restarting MPD Apr 19 12:27:04 volumio sudo[12501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:27:04 volumio sudo[12501]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:04 volumio sudo[12501]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:04 volumio sudo[12504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:27:04 volumio sudo[12504]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:04 volumio volumio[12379]: info: MPD Permissions set Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 19 12:27:04 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:27:04 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:27:04 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:27:04 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:04 volumio sudo[12507]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:27:04 volumio sudo[12507]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:04 volumio sudo[12507]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:04 volumio volumio[12379]: info: Volumio called home Apr 19 12:27:04 volumio volumio[12379]: info: [LastFM] finished init: Authenticated successfully! Apr 19 12:27:04 volumio volumio[12379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 19 12:27:05 volumio mpd[12514]: Apr 19 12:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 12:27:05 volumio systemd[1]: Started Music Player Daemon. Apr 19 12:27:05 volumio sudo[12504]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:05 volumio sudo[12462]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:05 volumio sudo[12468]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:05 volumio volumio[12379]: info: Completed starting Core Plugins Apr 19 12:27:05 volumio volumio[12379]: info: ------------------------------------------- Apr 19 12:27:05 volumio volumio[12379]: info: ----- MyVolumio plugins startup ---- Apr 19 12:27:05 volumio volumio[12379]: info: ------------------------------------------- Apr 19 12:27:05 volumio volumio[12379]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 19 12:27:05 volumio volumio[12379]: info: MPD running with PID12514 Apr 19 12:27:05 volumio volumio[12379]: ,establishing connection Apr 19 12:27:05 volumio volumio[12379]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:27:05 volumio volumio[12379]: assert.ok(self.idling) Apr 19 12:27:05 volumio volumio[12379]: error: The expression evaluated to a falsy value: Apr 19 12:27:05 volumio volumio[12379]: assert.ok(self.idling) Apr 19 12:27:05 volumio volumio[12379]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:27:05 volumio volumio[12379]: assert.ok(self.idling) Apr 19 12:27:05 volumio volumio[12379]: error: The expression evaluated to a falsy value: Apr 19 12:27:05 volumio volumio[12379]: assert.ok(self.idling) Apr 19 12:27:05 volumio volumio[12379]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:27:05 volumio volumio[12379]: assert.ok(self.idling) Apr 19 12:27:05 volumio volumio[12379]: error: The expression evaluated to a falsy value: Apr 19 12:27:05 volumio volumio[12379]: assert.ok(self.idling) Apr 19 12:27:05 volumio volumio[12379]: error: updateQueue error: null Apr 19 12:27:05 volumio volumio[12379]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:05 volumio volumio[12379]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:05 volumio volumio[12379]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:05 volumio volumio[12379]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 12:27:05 volumio volumio[12379]: info: Received Get System Info Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:27:05 volumio volumio[12379]: info: Discovery: Getting this device information Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:05 volumio volumio[12379]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:05 volumio volumio[12379]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:05 volumio volumio[12379]: info: Listing playlists Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:05 volumio volumio[12379]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:05 volumio volumio[12379]: info: FusionDsp - Apr 19 12:27:05 volumio volumio[12379]: info: FusionDsp - Apr 19 12:27:06 volumio volumio[12379]: info: FusionDsp - Apr 19 12:27:06 volumio volumio[12379]: info: FusionDsp - Apr 19 12:27:06 volumio volumio[12379]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:27:06 volumio volumio[12379]: 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 Apr 19 12:27:06 volumio volumio[12379]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:27:06 volumio volumio[12379]: info: camilladsp service started and running in background, instance 1 Apr 19 12:27:06 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:06 volumio volumio[12379]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:27:06 volumio volumio[12379]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 19 12:27:06 volumio volumio[12379]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:27:06 volumio volumio[12379]: info: FusionDsp loaded Apr 19 12:27:06 volumio volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:27:06 volumio sudo[12567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 19 12:27:06 volumio sudo[12567]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:06 volumio volumio[12379]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 19 12:27:06 volumio volumio[12379]: info: Adding Signal Path Element [object Object] Apr 19 12:27:06 volumio volumio[12379]: info: Adding fusiondspeq DSP Signal Path Element Apr 19 12:27:06 volumio volumio[12379]: info: FusionDsp - ---- installed callbackRead Apr 19 12:27:06 volumio volumio[12379]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:27:06 volumio sudo[12567]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:06 volumio volumio[12379]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 19 12:27:06 volumio volumio[12379]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 19 12:27:06 volumio volumio[12379]: at onErrorNT (internal/child_process.js:472:16) Apr 19 12:27:06 volumio volumio[12379]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 19 12:27:06 volumio volumio[12379]: at runNextTicks (internal/process/task_queues.js:64:3) Apr 19 12:27:06 volumio volumio[12379]: at listOnTimeout (internal/timers.js:526:9) Apr 19 12:27:06 volumio volumio[12379]: at processTimers (internal/timers.js:500:7) { Apr 19 12:27:06 volumio volumio[12379]: errno: -2, Apr 19 12:27:06 volumio volumio[12379]: code: 'ENOENT', Apr 19 12:27:06 volumio volumio[12379]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:27:06 volumio volumio[12379]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:27:06 volumio volumio[12379]: spawnargs: [ Apr 19 12:27:06 volumio volumio[12379]: '-p', Apr 19 12:27:06 volumio volumio[12379]: 9876, Apr 19 12:27:06 volumio volumio[12379]: '-o', Apr 19 12:27:06 volumio volumio[12379]: '/tmp/camilladsp.log', Apr 19 12:27:06 volumio volumio[12379]: '-l', Apr 19 12:27:06 volumio volumio[12379]: 'warn', Apr 19 12:27:06 volumio volumio[12379]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 19 12:27:06 volumio volumio[12379]: ] Apr 19 12:27:06 volumio volumio[12379]: } Apr 19 12:27:06 volumio volumio[12379]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:27:06 volumio sudo[12578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 12:26 Apr 19 12:27:06 volumio sudo[12578]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:06 volumio sudo[12578]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:07 volumio volumio-remote-updater[617]: [2025-04-19 12:27:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 19 12:27:07 volumio volumio-remote-updater[617]: [2025-04-19 12:27:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 19 12:27:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 19 12:27:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 19 12:27:07 volumio systemd[1]: Started dynamicswap service. Apr 19 12:27:07 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:27:07 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 19 12:27:07 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Apr 19 12:27:07 volumio systemd[1]: Started dynamicswap service. Apr 19 12:27:07 volumio systemd[1]: Stopped Volumio Backend Module. Apr 19 12:27:07 volumio systemd[1]: Started Volumio Backend Module. Apr 19 12:27:07 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:27:08 volumio volumio[12589]: info: ------------------------------------------- Apr 19 12:27:08 volumio volumio[12589]: info: ----- Volumio3 ---- Apr 19 12:27:08 volumio volumio[12589]: info: ------------------------------------------- Apr 19 12:27:08 volumio volumio[12589]: info: ----- System startup ---- Apr 19 12:27:08 volumio volumio[12589]: info: ------------------------------------------- Apr 19 12:27:09 volumio volumio[12589]: info: MYVOLUMIO Environment detected Apr 19 12:27:09 volumio volumio[12589]: info: Plugin folders cleanup Apr 19 12:27:09 volumio volumio[12589]: info: Scanning into folder /volumio/app/plugins/ Apr 19 12:27:09 volumio volumio[12589]: info: Scanning category audio_interface Apr 19 12:27:09 volumio volumio[12589]: info: Scanning category miscellanea Apr 19 12:27:09 volumio volumio[12589]: info: Scanning category music_service Apr 19 12:27:09 volumio volumio[12589]: info: Scanning category plugins.json Apr 19 12:27:09 volumio volumio[12589]: info: Scanning category system_controller Apr 19 12:27:09 volumio volumio[12589]: info: Scanning category user_interface Apr 19 12:27:09 volumio volumio[12589]: info: Scanning into folder /data/plugins/ Apr 19 12:27:09 volumio volumio[12589]: info: Scanning category audio_interface Apr 19 12:27:09 volumio volumio[12589]: info: Scanning category music_service Apr 19 12:27:09 volumio volumio[12589]: info: Scanning category user_interface Apr 19 12:27:09 volumio volumio[12589]: info: Plugin folders cleanup completed Apr 19 12:27:09 volumio volumio[12589]: info: ------------------------------------------- Apr 19 12:27:09 volumio volumio[12589]: info: ----- Core plugins startup ---- Apr 19 12:27:09 volumio volumio[12589]: info: ------------------------------------------- Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugins from folder /volumio/app/plugins/ Apr 19 12:27:09 volumio volumio[12589]: info: Adding plugin upnp to MyMusic Plugins Apr 19 12:27:09 volumio volumio[12589]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 19 12:27:09 volumio volumio[12589]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugins from folder /data/plugins/ Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugin "system"... Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugin "appearance"... Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugin "network"... Apr 19 12:27:09 volumio volumio[12589]: info: Refreshing Cached IP Addresses Apr 19 12:27:09 volumio sudo[12615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 12:27:09 volumio sudo[12615]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:09 volumio sudo[12615]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:09 volumio sudo[12618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 12:27:09 volumio sudo[12618]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:09 volumio sudo[12618]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugin "services"... Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugin "alsa_controller"... Apr 19 12:27:09 volumio sudo[12630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 19 12:27:09 volumio sudo[12630]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:09 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugin "wizard"... Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugin "networkfs"... Apr 19 12:27:09 volumio volumio[12589]: info: Cannot mount NAS CQ at system boot, trial number 1 ,retrying in 5 seconds Apr 19 12:27:09 volumio volumio[12589]: info: Starting Udev Watcher for removable devices Apr 19 12:27:09 volumio volumio[12589]: info: Ignoring mount for partition: boot Apr 19 12:27:09 volumio volumio[12589]: info: Ignoring mount for partition: volumio Apr 19 12:27:09 volumio volumio[12589]: info: Ignoring mount for partition: volumio_data Apr 19 12:27:09 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugin "volumio_command_line_client"... Apr 19 12:27:09 volumio volumio[12589]: info: Plugin upnp is not enabled Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugin "my_music"... Apr 19 12:27:09 volumio volumio[12589]: info: Loading plugin "mpd"... Apr 19 12:27:10 volumio sudo[12630]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:10 volumio volumio[12589]: info: Plugin upnp_browser is not enabled Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "alarm-clock"... Apr 19 12:27:10 volumio volumio[12589]: info: Plugin airplay_emulation is not enabled Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "last_100"... Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "webradio"... Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "i2s_dacs"... Apr 19 12:27:10 volumio volumio[12589]: info: I2S DAC not set, start Auto-detection Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "volumiodiscovery"... Apr 19 12:27:10 volumio volumio[12589]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:10 volumio volumio[12589]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:10 volumio volumio[12589]: *** WARNING *** For more information see Apr 19 12:27:10 volumio volumio[12589]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:10 volumio node[12589]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:10 volumio volumio[12589]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:10 volumio volumio[12589]: *** WARNING *** For more information see Apr 19 12:27:10 volumio node[12589]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:10 volumio node[12589]: *** WARNING *** For more information see Apr 19 12:27:10 volumio node[12589]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:10 volumio node[12589]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:10 volumio node[12589]: *** WARNING *** For more information see Apr 19 12:27:10 volumio volumio[12589]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 19 12:27:10 volumio volumio[12589]: info: Discovery: Started advertising with name: Volumio Apr 19 12:27:10 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "outputs"... Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "albumart"... Apr 19 12:27:10 volumio volumio[12589]: info: Plugin example_plugin is not enabled Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "inputs"... Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "updater_comm"... Apr 19 12:27:10 volumio volumio[12589]: info: Plugin mpdemulation is not enabled Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "rest_api"... Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "websocket"... Apr 19 12:27:10 volumio volumio[12589]: info: Starting Socket.io Server version 2.3.0 Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "fusiondsp"... Apr 19 12:27:10 volumio volumio[12589]: Forking 1 albumart workers Apr 19 12:27:10 volumio volumio[12589]: info: Applying required configuration parameters for plugin fusiondsp Apr 19 12:27:10 volumio volumio[12589]: info: Loading plugin "volusonic"... Apr 19 12:27:10 volumio volumio[12589]: Starting albumart workers Apr 19 12:27:11 volumio volumio[12589]: info: Applying required configuration parameters for plugin volusonic Apr 19 12:27:11 volumio volumio[12589]: info: Loading plugin "lastfm"... Apr 19 12:27:11 volumio volumio[12589]: info: Loading i18n strings for locale zh Apr 19 12:27:11 volumio volumio[12589]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:27:11 volumio volumio[12589]: Updating browse sources language Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::initPlayerControls Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:27:11 volumio volumio[12589]: Express server listening on port 3000 Apr 19 12:27:11 volumio volumio[12589]: [Metrics] WebUI: 3s 788.85ms Apr 19 12:27:11 volumio volumio[12589]: info: Setting Device type: x86 Apr 19 12:27:11 volumio volumio[12589]: info: CoreStateMachine::resetVolumioState Apr 19 12:27:11 volumio volumio[12589]: info: CoreStateMachine::getcurrentVolume Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 12:27:11 volumio volumio[12589]: info: CoreStateMachine::pushState Apr 19 12:27:11 volumio volumio[12589]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::volumioPushState Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:11 volumio volumio[12589]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 19 12:27:11 volumio volumio[12589]: info: Completed loading Core Plugins Apr 19 12:27:11 volumio volumio[12589]: info: Preparing to generate the ALSA configuration file Apr 19 12:27:11 volumio volumio[12589]: info: Volumio Network Manager: Network status updated: 2 Apr 19 12:27:11 volumio volumio[12589]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 19 12:27:11 volumio volumio[12589]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:27:11 volumio volumio[12589]: info: Reading ALSA contributions from plugins. Apr 19 12:27:11 volumio volumio[12589]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 19 12:27:11 volumio volumio[12589]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 19 12:27:11 volumio volumio[12589]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 19 12:27:11 volumio volumio[12589]: info: Reloading queue from file Apr 19 12:27:11 volumio volumio[12589]: info: CoreStateMachine::setRepeat null single undefined Apr 19 12:27:11 volumio volumio[12589]: info: CoreStateMachine::pushState Apr 19 12:27:11 volumio volumio[12589]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::volumioPushState Apr 19 12:27:11 volumio volumio[12589]: info: CoreStateMachine::setRandom null Apr 19 12:27:11 volumio volumio[12589]: info: CoreStateMachine::pushState Apr 19 12:27:11 volumio volumio[12589]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:11 volumio volumio[12589]: info: CoreCommandRouter::volumioPushState Apr 19 12:27:11 volumio volumio[12589]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:27:11 volumio volumio[12589]: info: Output device has changed, restarting MPD Apr 19 12:27:11 volumio sudo[12670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:27:11 volumio sudo[12670]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:11 volumio volumio[12589]: info: ___________ START PLUGINS ___________ Apr 19 12:27:11 volumio volumio[12589]: info: ControllerMpd::onStart: Initializing MPD Apr 19 12:27:11 volumio volumio[12589]: info: Creating MPD Configuration file Apr 19 12:27:11 volumio sudo[12670]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:12 volumio sudo[12672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:27:12 volumio sudo[12672]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:12 volumio volumio[12589]: info: [1745036832025] CoreMusicLibrary::Adding element Last_100 Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:12 volumio volumio[12589]: info: [1745036832028] CoreMusicLibrary::Adding element Webradio Apr 19 12:27:12 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:27:12 volumio sudo[12678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:27:12 volumio sudo[12675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:27:12 volumio volumio[12589]: info: Initializing BBC Radios Apr 19 12:27:12 volumio sudo[12675]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:12 volumio sudo[12678]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:12 volumio sudo[12675]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:12 volumio volumio-remote-updater[617]: [2025-04-19 12:27:12] [connect] Successful connection Apr 19 12:27:12 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:27:12 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:27:12 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:27:12 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 19 12:27:12 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:27:12 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:27:12 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: Loading i18n strings for locale zh Apr 19 12:27:12 volumio volumio[12589]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: FusionDsp - mixtype--------------------- None Apr 19 12:27:12 volumio volumio[12589]: info: Loading i18n strings for locale zh Apr 19 12:27:12 volumio volumio[12589]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:27:12 volumio volumio[12589]: Updating browse sources language Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:12 volumio sudo[12685]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:12 volumio volumio[12589]: info: [1745036832168] CoreMusicLibrary::Adding element Volusonic Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:12 volumio volumio[12589]: Cannot find translation for source Volusonic Apr 19 12:27:12 volumio sudo[12685]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:12 volumio volumio[12589]: info: [1745036832171] CoreMusicLibrary::Adding element LastFM Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:12 volumio volumio[12589]: Cannot find translation for source Volusonic Apr 19 12:27:12 volumio volumio[12589]: Cannot find translation for source LastFM Apr 19 12:27:12 volumio volumio[12589]: info: [LastFM] scrobbler initiated! Apr 19 12:27:12 volumio volumio[12589]: info: [LastFM] extended logging: false Apr 19 12:27:12 volumio volumio[12589]: info: [LastFM] try scrobble stream/radio plays: true Apr 19 12:27:12 volumio sudo[12685]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:12 volumio volumio[12589]: info: [LastFM] Left init routine Apr 19 12:27:12 volumio volumio[12589]: info: [LastFM] Socket already connected: false Apr 19 12:27:12 volumio volumio[12589]: info: Volumio Calling Home Apr 19 12:27:12 volumio volumio[12589]: info: Preparing to generate the ALSA configuration file Apr 19 12:27:12 volumio volumio[12589]: info: Discovery: adding ff0c7a9d-0254-430b-958c-60aff2e24e3f Apr 19 12:27:12 volumio volumio[12589]: info: Discovery: Found device Volumio Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:12 volumio volumio[12589]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:12 volumio volumio[12589]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:27:12 volumio volumio[12589]: info: Reading ALSA contributions from plugins. Apr 19 12:27:12 volumio volumio-remote-updater[617]: [2025-04-19 12:27:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745036832 101 Apr 19 12:27:12 volumio volumio[12589]: 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 Apr 19 12:27:12 volumio volumio[12589]: info: MPD Permissions set Apr 19 12:27:12 volumio volumio[12589]: info: MPD Permissions set Apr 19 12:27:12 volumio volumio[12589]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:12 volumio volumio[12589]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:12 volumio volumio[12589]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:27:12 volumio volumio[12589]: info: Output device has changed, restarting MPD Apr 19 12:27:12 volumio sudo[12711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:27:12 volumio sudo[12711]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:12 volumio sudo[12711]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:12 volumio volumio[12589]: info: MPD Permissions set Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio sudo[12714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:27:12 volumio sudo[12714]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:12 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 19 12:27:12 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:27:12 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:27:12 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:12 volumio volumio[12589]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 19 12:27:12 volumio sudo[12720]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:27:12 volumio sudo[12720]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:12 volumio sudo[12720]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:12 volumio volumio[12589]: info: [LastFM] finished init: Authenticated successfully! Apr 19 12:27:12 volumio volumio[12589]: info: Volumio called home Apr 19 12:27:12 volumio volumio[12589]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:12 volumio volumio[12589]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:12 volumio volumio[12589]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 12:27:12 volumio volumio[12589]: info: Received Get System Info Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:27:12 volumio volumio[12589]: info: Discovery: Getting this device information Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:12 volumio volumio[12589]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:27:12 volumio volumio[12589]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:12 volumio volumio[12589]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:12 volumio volumio[12589]: info: Listing playlists Apr 19 12:27:13 volumio volumio[12589]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:13 volumio volumio[12589]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:13 volumio volumio[12589]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:13 volumio volumio[12589]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:13 volumio volumio[12589]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:13 volumio volumio[12589]: info: FusionDsp - Apr 19 12:27:13 volumio volumio[12589]: info: FusionDsp - Apr 19 12:27:13 volumio volumio[12589]: info: FusionDsp - Apr 19 12:27:13 volumio volumio[12589]: info: FusionDsp - Apr 19 12:27:13 volumio mpd[12724]: Apr 19 12:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 12:27:13 volumio systemd[1]: Started Music Player Daemon. Apr 19 12:27:13 volumio sudo[12714]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:13 volumio sudo[12678]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:13 volumio sudo[12672]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:13 volumio volumio[12589]: info: FusionDsp - Apr 19 12:27:13 volumio volumio[12589]: info: FusionDsp - undefined Apr 19 12:27:13 volumio volumio[12589]: info: FusionDsp - undefined Apr 19 12:27:13 volumio volumio[12589]: info: FusionDsp - undefined Apr 19 12:27:13 volumio volumio[12589]: info: FusionDsp - undefined Apr 19 12:27:13 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 12:27:14 volumio volumio[12589]: info: Completed starting Core Plugins Apr 19 12:27:14 volumio volumio[12589]: info: ------------------------------------------- Apr 19 12:27:14 volumio volumio[12589]: info: ----- MyVolumio plugins startup ---- Apr 19 12:27:14 volumio volumio[12589]: info: ------------------------------------------- Apr 19 12:27:14 volumio volumio[12589]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 19 12:27:14 volumio volumio[12589]: info: FusionDsp - undefined Apr 19 12:27:14 volumio volumio[12589]: info: MPD running with PID12724 Apr 19 12:27:14 volumio volumio[12589]: ,establishing connection Apr 19 12:27:14 volumio volumio[12589]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:27:14 volumio volumio[12589]: assert.ok(self.idling) Apr 19 12:27:14 volumio volumio[12589]: error: The expression evaluated to a falsy value: Apr 19 12:27:14 volumio volumio[12589]: assert.ok(self.idling) Apr 19 12:27:14 volumio volumio[12589]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:27:14 volumio volumio[12589]: assert.ok(self.idling) Apr 19 12:27:14 volumio volumio[12589]: error: The expression evaluated to a falsy value: Apr 19 12:27:14 volumio volumio[12589]: assert.ok(self.idling) Apr 19 12:27:14 volumio volumio[12589]: error: MPD error: The expression evaluated to a falsy value: Apr 19 12:27:14 volumio volumio[12589]: assert.ok(self.idling) Apr 19 12:27:14 volumio volumio[12589]: error: The expression evaluated to a falsy value: Apr 19 12:27:14 volumio volumio[12589]: assert.ok(self.idling) Apr 19 12:27:14 volumio volumio[12589]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:27:14 volumio volumio[12589]: 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 Apr 19 12:27:14 volumio volumio[12589]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 19 12:27:14 volumio volumio[12589]: info: camilladsp service started and running in background, instance 1 Apr 19 12:27:14 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:14 volumio volumio[12589]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:27:14 volumio volumio[12589]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 19 12:27:14 volumio volumio[12589]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 19 12:27:14 volumio volumio[12589]: info: FusionDsp loaded Apr 19 12:27:14 volumio volumio[12589]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:27:14 volumio sudo[12741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 19 12:27:14 volumio volumio[12589]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 19 12:27:14 volumio sudo[12741]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:14 volumio volumio[12589]: info: Adding Signal Path Element [object Object] Apr 19 12:27:14 volumio volumio[12589]: info: Adding fusiondspeq DSP Signal Path Element Apr 19 12:27:14 volumio volumio[12589]: info: FusionDsp - ---- installed callbackRead Apr 19 12:27:14 volumio volumio[12589]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:27:14 volumio volumio[12589]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 19 12:27:14 volumio volumio[12589]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 19 12:27:14 volumio volumio[12589]: at onErrorNT (internal/child_process.js:472:16) Apr 19 12:27:14 volumio volumio[12589]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 19 12:27:14 volumio volumio[12589]: at runNextTicks (internal/process/task_queues.js:64:3) Apr 19 12:27:14 volumio volumio[12589]: at listOnTimeout (internal/timers.js:526:9) Apr 19 12:27:14 volumio volumio[12589]: at processTimers (internal/timers.js:500:7) { Apr 19 12:27:14 volumio volumio[12589]: errno: -2, Apr 19 12:27:14 volumio volumio[12589]: code: 'ENOENT', Apr 19 12:27:14 volumio volumio[12589]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:27:14 volumio volumio[12589]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 19 12:27:14 volumio volumio[12589]: spawnargs: [ Apr 19 12:27:14 volumio volumio[12589]: '-p', Apr 19 12:27:14 volumio volumio[12589]: 9876, Apr 19 12:27:14 volumio volumio[12589]: '-o', Apr 19 12:27:14 volumio volumio[12589]: '/tmp/camilladsp.log', Apr 19 12:27:14 volumio volumio[12589]: '-l', Apr 19 12:27:14 volumio volumio[12589]: 'warn', Apr 19 12:27:14 volumio volumio[12589]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 19 12:27:14 volumio volumio[12589]: ] Apr 19 12:27:14 volumio volumio[12589]: } Apr 19 12:27:14 volumio volumio[12589]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:27:14 volumio sudo[12741]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:14 volumio sudo[12752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 12:26 Apr 19 12:27:14 volumio sudo[12752]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:14 volumio sudo[12752]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:14 volumio volumio-remote-updater[617]: [2025-04-19 12:27:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 19 12:27:14 volumio volumio-remote-updater[617]: [2025-04-19 12:27:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 19 12:27:14 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 19 12:27:14 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 19 12:27:14 volumio systemd[1]: Started dynamicswap service. Apr 19 12:27:14 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:27:15 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 19 12:27:15 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Apr 19 12:27:15 volumio systemd[1]: Stopped Volumio Backend Module. Apr 19 12:27:15 volumio systemd[1]: Started Volumio Backend Module. Apr 19 12:27:15 volumio systemd[1]: Started dynamicswap service. Apr 19 12:27:15 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 19 12:27:15 volumio volumio[12762]: info: ------------------------------------------- Apr 19 12:27:15 volumio volumio[12762]: info: ----- Volumio3 ---- Apr 19 12:27:15 volumio volumio[12762]: info: ------------------------------------------- Apr 19 12:27:15 volumio volumio[12762]: info: ----- System startup ---- Apr 19 12:27:15 volumio volumio[12762]: info: ------------------------------------------- Apr 19 12:27:16 volumio volumio[12762]: info: MYVOLUMIO Environment detected Apr 19 12:27:16 volumio volumio[12762]: info: Plugin folders cleanup Apr 19 12:27:16 volumio volumio[12762]: info: Scanning into folder /volumio/app/plugins/ Apr 19 12:27:16 volumio volumio[12762]: info: Scanning category audio_interface Apr 19 12:27:16 volumio volumio[12762]: info: Scanning category miscellanea Apr 19 12:27:16 volumio volumio[12762]: info: Scanning category music_service Apr 19 12:27:16 volumio volumio[12762]: info: Scanning category plugins.json Apr 19 12:27:16 volumio volumio[12762]: info: Scanning category system_controller Apr 19 12:27:16 volumio volumio[12762]: info: Scanning category user_interface Apr 19 12:27:16 volumio volumio[12762]: info: Scanning into folder /data/plugins/ Apr 19 12:27:16 volumio volumio[12762]: info: Scanning category audio_interface Apr 19 12:27:16 volumio volumio[12762]: info: Scanning category music_service Apr 19 12:27:16 volumio volumio[12762]: info: Scanning category user_interface Apr 19 12:27:16 volumio volumio[12762]: info: Plugin folders cleanup completed Apr 19 12:27:16 volumio volumio[12762]: info: ------------------------------------------- Apr 19 12:27:16 volumio volumio[12762]: info: ----- Core plugins startup ---- Apr 19 12:27:16 volumio volumio[12762]: info: ------------------------------------------- Apr 19 12:27:16 volumio volumio[12762]: info: Loading plugins from folder /volumio/app/plugins/ Apr 19 12:27:16 volumio volumio[12762]: info: Adding plugin upnp to MyMusic Plugins Apr 19 12:27:16 volumio volumio[12762]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 19 12:27:16 volumio volumio[12762]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 19 12:27:16 volumio volumio[12762]: info: Loading plugins from folder /data/plugins/ Apr 19 12:27:16 volumio volumio[12762]: info: Loading plugin "system"... Apr 19 12:27:16 volumio volumio[12762]: info: Loading plugin "appearance"... Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "network"... Apr 19 12:27:17 volumio volumio[12762]: info: Refreshing Cached IP Addresses Apr 19 12:27:17 volumio sudo[12792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 12:27:17 volumio sudo[12792]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:17 volumio sudo[12792]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:17 volumio sudo[12794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 12:27:17 volumio sudo[12794]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:17 volumio sudo[12794]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "services"... Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "alsa_controller"... Apr 19 12:27:17 volumio sudo[12803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 19 12:27:17 volumio sudo[12803]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:17 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "wizard"... Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "networkfs"... Apr 19 12:27:17 volumio volumio[12762]: info: Cannot mount NAS CQ at system boot, trial number 1 ,retrying in 5 seconds Apr 19 12:27:17 volumio volumio[12762]: info: Starting Udev Watcher for removable devices Apr 19 12:27:17 volumio volumio[12762]: info: Ignoring mount for partition: boot Apr 19 12:27:17 volumio volumio[12762]: info: Ignoring mount for partition: volumio Apr 19 12:27:17 volumio volumio[12762]: info: Ignoring mount for partition: volumio_data Apr 19 12:27:17 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "volumio_command_line_client"... Apr 19 12:27:17 volumio volumio[12762]: info: Plugin upnp is not enabled Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "my_music"... Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "mpd"... Apr 19 12:27:17 volumio sudo[12803]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:17 volumio volumio[12762]: info: Plugin upnp_browser is not enabled Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "alarm-clock"... Apr 19 12:27:17 volumio volumio[12762]: info: Plugin airplay_emulation is not enabled Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "last_100"... Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "webradio"... Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "i2s_dacs"... Apr 19 12:27:17 volumio volumio[12762]: info: I2S DAC not set, start Auto-detection Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "volumiodiscovery"... Apr 19 12:27:17 volumio volumio[12762]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:17 volumio volumio[12762]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:17 volumio volumio[12762]: *** WARNING *** For more information see Apr 19 12:27:17 volumio node[12762]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:17 volumio volumio[12762]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:17 volumio volumio[12762]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:17 volumio volumio[12762]: *** WARNING *** For more information see Apr 19 12:27:17 volumio node[12762]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:17 volumio node[12762]: *** WARNING *** For more information see Apr 19 12:27:17 volumio node[12762]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 12:27:17 volumio node[12762]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 12:27:17 volumio node[12762]: *** WARNING *** For more information see Apr 19 12:27:17 volumio volumio[12762]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 19 12:27:17 volumio volumio[12762]: info: Discovery: Started advertising with name: Volumio Apr 19 12:27:17 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "outputs"... Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "albumart"... Apr 19 12:27:17 volumio volumio[12762]: info: Plugin example_plugin is not enabled Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "inputs"... Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "updater_comm"... Apr 19 12:27:17 volumio volumio[12762]: info: Plugin mpdemulation is not enabled Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "rest_api"... Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "websocket"... Apr 19 12:27:17 volumio volumio[12762]: info: Starting Socket.io Server version 2.3.0 Apr 19 12:27:17 volumio volumio[12762]: info: Loading plugin "fusiondsp"... Apr 19 12:27:17 volumio volumio[12762]: Forking 1 albumart workers Apr 19 12:27:18 volumio volumio[12762]: info: Applying required configuration parameters for plugin fusiondsp Apr 19 12:27:18 volumio volumio[12762]: info: Loading plugin "volusonic"... Apr 19 12:27:18 volumio volumio[12762]: Starting albumart workers Apr 19 12:27:18 volumio volumio[12762]: info: Applying required configuration parameters for plugin volusonic Apr 19 12:27:18 volumio volumio[12762]: info: Loading plugin "lastfm"... Apr 19 12:27:19 volumio volumio[12762]: info: Loading i18n strings for locale zh Apr 19 12:27:19 volumio volumio[12762]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:27:19 volumio volumio[12762]: Updating browse sources language Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::initPlayerControls Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 12:27:19 volumio volumio[12762]: Express server listening on port 3000 Apr 19 12:27:19 volumio volumio[12762]: [Metrics] WebUI: 3s 738.95ms Apr 19 12:27:19 volumio volumio[12762]: info: Setting Device type: x86 Apr 19 12:27:19 volumio volumio[12762]: info: CoreStateMachine::resetVolumioState Apr 19 12:27:19 volumio volumio[12762]: info: CoreStateMachine::getcurrentVolume Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 12:27:19 volumio volumio[12762]: info: CoreStateMachine::pushState Apr 19 12:27:19 volumio volumio[12762]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioPushState Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:19 volumio volumio[12762]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 19 12:27:19 volumio volumio[12762]: info: Completed loading Core Plugins Apr 19 12:27:19 volumio volumio[12762]: info: Preparing to generate the ALSA configuration file Apr 19 12:27:19 volumio volumio[12762]: info: Volumio Network Manager: Network status updated: 2 Apr 19 12:27:19 volumio volumio[12762]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 19 12:27:19 volumio volumio[12762]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:27:19 volumio volumio[12762]: info: Reading ALSA contributions from plugins. Apr 19 12:27:19 volumio volumio[12762]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 19 12:27:19 volumio volumio[12762]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 19 12:27:19 volumio volumio[12762]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 19 12:27:19 volumio volumio[12762]: info: Reloading queue from file Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 12:27:19 volumio volumio[12762]: info: CoreStateMachine::setRepeat null single undefined Apr 19 12:27:19 volumio volumio[12762]: info: CoreStateMachine::pushState Apr 19 12:27:19 volumio volumio[12762]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioPushState Apr 19 12:27:19 volumio volumio[12762]: info: CoreStateMachine::setRandom null Apr 19 12:27:19 volumio volumio[12762]: info: CoreStateMachine::pushState Apr 19 12:27:19 volumio volumio[12762]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioPushState Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:19 volumio volumio[12762]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:19 volumio volumio[12762]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 12:27:19 volumio volumio[12762]: info: Received Get System Info Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:27:19 volumio volumio[12762]: info: Discovery: Getting this device information Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:19 volumio volumio[12762]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:19 volumio volumio[12762]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:19 volumio volumio[12762]: info: Listing playlists Apr 19 12:27:19 volumio volumio-remote-updater[617]: [2025-04-19 12:27:19] [connect] Successful connection Apr 19 12:27:19 volumio volumio-remote-updater[617]: [2025-04-19 12:27:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745036839 101 Apr 19 12:27:19 volumio volumio[12762]: 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: 4 Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:19 volumio volumio[12762]: info: Discovery: adding ff0c7a9d-0254-430b-958c-60aff2e24e3f Apr 19 12:27:19 volumio volumio[12762]: info: Discovery: Found device Volumio Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioGetState Apr 19 12:27:19 volumio volumio[12762]: info: CorePlayQueue::getTrack 0 Apr 19 12:27:19 volumio volumio[12762]: info: Asound.conf file unchanged, so no further update is needed Apr 19 12:27:19 volumio volumio[12762]: info: Output device has changed, restarting MPD Apr 19 12:27:19 volumio sudo[12847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:27:19 volumio sudo[12847]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:19 volumio volumio[12762]: info: ___________ START PLUGINS ___________ Apr 19 12:27:19 volumio sudo[12847]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:19 volumio volumio[12762]: info: ControllerMpd::onStart: Initializing MPD Apr 19 12:27:19 volumio volumio[12762]: info: Creating MPD Configuration file Apr 19 12:27:19 volumio sudo[12849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:27:19 volumio sudo[12849]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:19 volumio systemd[1]: Stopping Music Player Daemon... Apr 19 12:27:19 volumio sudo[12853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 12:27:19 volumio sudo[12853]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:19 volumio sudo[12853]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:19 volumio volumio[12762]: info: [1745036839909] CoreMusicLibrary::Adding element Last_100 Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:19 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:27:19 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:19 volumio volumio[12762]: info: [1745036839914] CoreMusicLibrary::Adding element Webradio Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:27:19 volumio volumio[12762]: info: Initializing BBC Radios Apr 19 12:27:19 volumio sudo[12856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 12:27:19 volumio sudo[12856]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:19 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:27:19 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 19 12:27:19 volumio systemd[1]: mpd.service: Succeeded. Apr 19 12:27:19 volumio systemd[1]: Stopped Music Player Daemon. Apr 19 12:27:19 volumio systemd[1]: Starting Music Player Daemon... Apr 19 12:27:19 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: Loading i18n strings for locale zh Apr 19 12:27:20 volumio volumio[12762]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: FusionDsp - mixtype--------------------- None Apr 19 12:27:20 volumio volumio[12762]: info: Loading i18n strings for locale zh Apr 19 12:27:20 volumio volumio[12762]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Apr 19 12:27:20 volumio volumio[12762]: Updating browse sources language Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:20 volumio volumio[12762]: info: [1745036840038] CoreMusicLibrary::Adding element Volusonic Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:20 volumio volumio[12762]: Cannot find translation for source Volusonic Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 12:27:20 volumio volumio[12762]: info: [1745036840040] CoreMusicLibrary::Adding element LastFM Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:27:20 volumio volumio[12762]: Cannot find translation for source Volusonic Apr 19 12:27:20 volumio volumio[12762]: Cannot find translation for source LastFM Apr 19 12:27:20 volumio volumio[12762]: info: [LastFM] scrobbler initiated! Apr 19 12:27:20 volumio volumio[12762]: info: [LastFM] extended logging: false Apr 19 12:27:20 volumio volumio[12762]: info: [LastFM] try scrobble stream/radio plays: true Apr 19 12:27:20 volumio volumio[12762]: info: [LastFM] Left init routine Apr 19 12:27:20 volumio volumio[12762]: info: [LastFM] Socket already connected: false Apr 19 12:27:20 volumio volumio[12762]: info: Volumio Calling Home Apr 19 12:27:20 volumio sudo[12861]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 12:27:20 volumio sudo[12861]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 12:27:20 volumio sudo[12861]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:20 volumio volumio[12762]: info: Preparing to generate the ALSA configuration file Apr 19 12:27:20 volumio volumio[12762]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 19 12:27:20 volumio volumio[12762]: info: Reading ALSA contributions from plugins. Apr 19 12:27:20 volumio volumio[12762]: info: MPD Permissions set Apr 19 12:27:20 volumio volumio[12762]: info: MPD Permissions set Apr 19 12:27:20 volumio volumio[12762]: info: FusionDsp - Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 12:27:20 volumio volumio[12762]: info: FusionDsp - undefined Apr 19 12:27:20 volumio volumio[12762]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 19 12:27:20 volumio volumio[12762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 12:27:20 volumio volumio[12762]: info: FusionDsp - Apr 19 12:27:20 volumio volumio[12762]: info: FusionDsp - Apr 19 12:27:20 volumio mpd[12873]: Apr 19 12:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 12:27:20 volumio systemd[1]: Started Music Player Daemon. Apr 19 12:27:20 volumio sudo[12849]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:20 volumio sudo[12856]: pam_unix(sudo:session): session closed for user root Apr 19 12:27:20 volumio volumio[12762]: info: FusionDsp - Apr 19 12:27:21 volumio volumio[12762]: info: FusionDsp - Apr 19 12:27:21 volumio volumio[12762]: info: FusionDsp - Apr 19 12:27:21 volumio volumio[12762]: info: FusionDsp - undefined Apr 19 12:27:21 volumio volumio[12762]: info: FusionDsp - undefined Apr 19 12:27:21 volumio volumio[12762]: info: FusionDsp - undefined Apr 19 12:27:21 volumio volumio[12762]: info: FusionDsp - undefined Apr 19 12:27:21 volumio volumio[12762]: info: FusionDsp - undefined Apr 19 12:27:21 volumio volumio[12762]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:27:21 volumio volumio[12762]: TypeError: Cannot read property 'off' of undefined Apr 19 12:27:21 volumio volumio[12762]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Apr 19 12:27:21 volumio volumio[12762]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 19 12:27:21 volumio volumio[12762]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Apr 19 12:27:21 volumio volumio[12762]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Apr 19 12:27:21 volumio volumio[12762]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Apr 19 12:27:21 volumio volumio[12762]: at Socket.emit (events.js:400:28) Apr 19 12:27:21 volumio volumio[12762]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 19 12:27:21 volumio volumio[12762]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 19 12:27:21 volumio volumio[12762]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:27:21 volumio sudo[12898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 12:26 Apr 19 12:27:21 volumio sudo[12898]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"