-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Fri 2025-08-29 19:58:11 +07. -- Aug 29 19:57:00 volumio volumio[21617]: info: FusionDsp - Aug 29 19:57:00 volumio volumio[21617]: info: FusionDsp - Aug 29 19:57:00 volumio volumio[21617]: info: FusionDsp - Aug 29 19:57:00 volumio volumio[21617]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 19:57:00 volumio volumio[21617]: 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 Aug 29 19:57:00 volumio volumio[21617]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 19:57:00 volumio volumio[21617]: info: camilladsp service started and running in background, instance 1 Aug 29 19:57:00 volumio volumio[21617]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:00 volumio volumio[21617]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:57:00 volumio volumio[21617]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 19:57:00 volumio volumio[21617]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:57:00 volumio volumio[21617]: info: FusionDsp loaded Aug 29 19:57:00 volumio volumio[21617]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:57:00 volumio sudo[21839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 19:57:00 volumio sudo[21839]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:00 volumio volumio[21617]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 19:57:00 volumio volumio[21617]: info: Adding Signal Path Element [object Object] Aug 29 19:57:00 volumio volumio[21617]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 19:57:00 volumio volumio[21617]: info: FusionDsp - ---- installed callbackRead Aug 29 19:57:00 volumio volumio[21617]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:57:00 volumio sudo[21839]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:01 volumio volumio[21617]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 19:57:01 volumio volumio[21617]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 19:57:01 volumio volumio[21617]: at onErrorNT (internal/child_process.js:465:16) Aug 29 19:57:01 volumio volumio[21617]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 29 19:57:01 volumio volumio[21617]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 29 19:57:01 volumio volumio[21617]: at listOnTimeout (internal/timers.js:523:9) Aug 29 19:57:01 volumio volumio[21617]: at processTimers (internal/timers.js:497:7) { Aug 29 19:57:01 volumio volumio[21617]: errno: -2, Aug 29 19:57:01 volumio volumio[21617]: code: 'ENOENT', Aug 29 19:57:01 volumio volumio[21617]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:57:01 volumio volumio[21617]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:57:01 volumio volumio[21617]: spawnargs: [ Aug 29 19:57:01 volumio volumio[21617]: '-p', Aug 29 19:57:01 volumio volumio[21617]: 9876, Aug 29 19:57:01 volumio volumio[21617]: '-o', Aug 29 19:57:01 volumio volumio[21617]: '/tmp/camilladsp.log', Aug 29 19:57:01 volumio volumio[21617]: '-l', Aug 29 19:57:01 volumio volumio[21617]: 'warn', Aug 29 19:57:01 volumio volumio[21617]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 19:57:01 volumio volumio[21617]: ] Aug 29 19:57:01 volumio volumio[21617]: } Aug 29 19:57:01 volumio volumio[21617]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:57:01 volumio mpd[21823]: Aug 29 19:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 19:57:01 volumio systemd[1]: Started Music Player Daemon. Aug 29 19:57:01 volumio sudo[21737]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:01 volumio sudo[21807]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:01 volumio sudo[21723]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:01 volumio sudo[21852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-29 19:56 Aug 29 19:57:01 volumio sudo[21852]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:01 volumio sudo[21852]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:02 volumio volumio-remote-updater[663]: [2025-08-29 19:57:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 19:57:02 volumio volumio-remote-updater[663]: [2025-08-29 19:57:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 19:57:02 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 19:57:02 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 19:57:02 volumio systemd[1]: Started dynamicswap service. Aug 29 19:57:02 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 19:57:02 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 19:57:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 82. Aug 29 19:57:02 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 19:57:02 volumio systemd[1]: Started Volumio Backend Module. Aug 29 19:57:02 volumio systemd[1]: Started dynamicswap service. Aug 29 19:57:02 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 19:57:04 volumio volumio[21862]: info: ------------------------------------------- Aug 29 19:57:04 volumio volumio[21862]: info: ----- Volumio3 ---- Aug 29 19:57:04 volumio volumio[21862]: info: ------------------------------------------- Aug 29 19:57:04 volumio volumio[21862]: info: ----- System startup ---- Aug 29 19:57:04 volumio volumio[21862]: info: ------------------------------------------- Aug 29 19:57:05 volumio volumio[21862]: info: MYVOLUMIO Environment detected Aug 29 19:57:05 volumio volumio[21862]: info: Plugin folders cleanup Aug 29 19:57:05 volumio volumio[21862]: info: Scanning into folder /volumio/app/plugins/ Aug 29 19:57:05 volumio volumio[21862]: info: Scanning category audio_interface Aug 29 19:57:05 volumio volumio[21862]: info: Scanning category miscellanea Aug 29 19:57:05 volumio volumio[21862]: info: Scanning category music_service Aug 29 19:57:05 volumio volumio[21862]: info: Scanning category plugins.json Aug 29 19:57:05 volumio volumio[21862]: info: Scanning category system_controller Aug 29 19:57:05 volumio volumio[21862]: info: Scanning category user_interface Aug 29 19:57:05 volumio volumio[21862]: info: Scanning into folder /data/plugins/ Aug 29 19:57:05 volumio volumio[21862]: info: Scanning category audio_interface Aug 29 19:57:05 volumio volumio[21862]: info: Plugin folders cleanup completed Aug 29 19:57:05 volumio volumio[21862]: info: ------------------------------------------- Aug 29 19:57:05 volumio volumio[21862]: info: ----- Core plugins startup ---- Aug 29 19:57:05 volumio volumio[21862]: info: ------------------------------------------- Aug 29 19:57:05 volumio volumio[21862]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 19:57:05 volumio volumio[21862]: info: Adding plugin upnp to MyMusic Plugins Aug 29 19:57:05 volumio volumio[21862]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 19:57:05 volumio volumio[21862]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 19:57:05 volumio volumio[21862]: info: Loading plugins from folder /data/plugins/ Aug 29 19:57:05 volumio volumio[21862]: info: Loading plugin "system"... Aug 29 19:57:05 volumio volumio[21862]: info: Loading plugin "appearance"... Aug 29 19:57:06 volumio wpa_supplicant[908]: wlan0: Failed to initiate sched scan Aug 29 19:57:06 volumio volumio[21862]: info: Loading plugin "network"... Aug 29 19:57:06 volumio volumio[21862]: info: Refreshing Cached IP Addresses Aug 29 19:57:06 volumio sudo[21927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 19:57:06 volumio sudo[21927]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:06 volumio sudo[21927]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:06 volumio sudo[21929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 19:57:06 volumio sudo[21929]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:06 volumio sudo[21929]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:06 volumio volumio[21862]: info: Loading plugin "services"... Aug 29 19:57:06 volumio volumio[21862]: info: Loading plugin "alsa_controller"... Aug 29 19:57:06 volumio sudo[21938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 19:57:06 volumio sudo[21938]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:06 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:06 volumio volumio[21862]: info: Loading plugin "wizard"... Aug 29 19:57:06 volumio volumio[21862]: info: Loading plugin "networkfs"... Aug 29 19:57:06 volumio volumio[21862]: info: Starting Udev Watcher for removable devices Aug 29 19:57:07 volumio volumio[21862]: info: Ignoring mount for partition: boot Aug 29 19:57:07 volumio volumio[21862]: info: Ignoring mount for partition: volumio Aug 29 19:57:07 volumio volumio[21862]: info: Ignoring mount for partition: volumio_data Aug 29 19:57:07 volumio volumio-remote-updater[663]: [2025-08-29 19:57:07] [connect] Successful connection Aug 29 19:57:07 volumio volumio[21862]: info: Mounting Device KINGSTON Aug 29 19:57:07 volumio sudo[21958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/KINGSTON -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 19:57:07 volumio sudo[21958]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:07 volumio sudo[21958]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:07 volumio volumio[21862]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Aug 29 19:57:07 volumio volumio[21862]: error: Failed to mount KINGSTON: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/KINGSTON" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 19:57:07 volumio volumio[21862]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Aug 29 19:57:07 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:07 volumio volumio[21862]: info: Loading plugin "volumio_command_line_client"... Aug 29 19:57:07 volumio volumio[21862]: info: Loading plugin "upnp"... Aug 29 19:57:07 volumio volumio[21862]: info: [1756472227159] Starting Upmpd Daemon Aug 29 19:57:07 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:07 volumio volumio[21862]: info: Loading plugin "my_music"... Aug 29 19:57:07 volumio volumio[21862]: info: Loading plugin "mpd"... Aug 29 19:57:07 volumio volumio[21862]: info: Loading plugin "upnp_browser"... Aug 29 19:57:07 volumio volumio[21862]: info: Loading plugin "alarm-clock"... Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "airplay_emulation"... Aug 29 19:57:08 volumio volumio[21862]: info: Starting Shairport Sync Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "last_100"... Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "webradio"... Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "i2s_dacs"... Aug 29 19:57:08 volumio volumio[21862]: info: I2S DAC not set, start Auto-detection Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "volumiodiscovery"... Aug 29 19:57:08 volumio volumio[21862]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:08 volumio volumio[21862]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:08 volumio node[21862]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:08 volumio volumio[21862]: *** WARNING *** For more information see Aug 29 19:57:08 volumio volumio[21862]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:08 volumio volumio[21862]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:08 volumio volumio[21862]: *** WARNING *** For more information see Aug 29 19:57:08 volumio node[21862]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:08 volumio node[21862]: *** WARNING *** For more information see Aug 29 19:57:08 volumio node[21862]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:08 volumio node[21862]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:08 volumio node[21862]: *** WARNING *** For more information see Aug 29 19:57:08 volumio volumio[21862]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 19:57:08 volumio volumio[21862]: info: Discovery: Started advertising with name: Volumio Aug 29 19:57:08 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "outputs"... Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "albumart"... Aug 29 19:57:08 volumio volumio[21862]: info: Plugin example_plugin is not enabled Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "inputs"... Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "updater_comm"... Aug 29 19:57:08 volumio volumio[21862]: info: Plugin mpdemulation is not enabled Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "rest_api"... Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "websocket"... Aug 29 19:57:08 volumio volumio[21862]: info: Starting Socket.io Server version 2.3.0 Aug 29 19:57:08 volumio volumio[21862]: info: Loading plugin "fusiondsp"... Aug 29 19:57:08 volumio volumio[21862]: Forking 3 albumart workers Aug 29 19:57:09 volumio volumio[21862]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 19:57:09 volumio volumio[21862]: info: Loading i18n strings for locale en Aug 29 19:57:09 volumio volumio[21862]: Updating browse sources language Aug 29 19:57:09 volumio sudo[21938]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::initPlayerControls Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:09 volumio volumio[21862]: Express server listening on port 3000 Aug 29 19:57:09 volumio volumio[21862]: [Metrics] WebUI: 6s 160.94ms Aug 29 19:57:09 volumio volumio[21862]: info: CoreStateMachine::resetVolumioState Aug 29 19:57:09 volumio volumio[21862]: info: CoreStateMachine::getcurrentVolume Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 19:57:09 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:09 volumio volumio[21862]: info: Volumio Network Manager: Network status updated: 1 Aug 29 19:57:10 volumio volumio[21862]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 19:57:10 volumio volumio-remote-updater[663]: [2025-08-29 19:57:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756472227 101 Aug 29 19:57:10 volumio volumio[21862]: 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 Aug 29 19:57:10 volumio volumio[21862]: Starting albumart workers Aug 29 19:57:10 volumio volumio[21862]: Starting albumart workers Aug 29 19:57:10 volumio volumio[21862]: info: Reloading queue from file Aug 29 19:57:10 volumio volumio[21862]: info: CoreStateMachine::setRepeat null single undefined Aug 29 19:57:10 volumio volumio[21862]: info: CoreStateMachine::pushState Aug 29 19:57:10 volumio volumio[21862]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:10 volumio volumio[21862]: info: CoreStateMachine::setRandom null Aug 29 19:57:10 volumio volumio[21862]: info: CoreStateMachine::pushState Aug 29 19:57:10 volumio volumio[21862]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:10 volumio volumio[21862]: info: Setting Device type: Raspberry PI Aug 29 19:57:10 volumio volumio[21862]: info: VolumeController:: Volume=0 Mute =false Aug 29 19:57:10 volumio volumio[21862]: info: CoreStateMachine::pushState Aug 29 19:57:10 volumio volumio[21862]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:10 volumio volumio[21862]: info: CoreStateMachine::updateTrackBlock Aug 29 19:57:10 volumio volumio[21862]: info: CorePlayQueue::getTrackBlock Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 19:57:10 volumio volumio[21862]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 19:57:10 volumio volumio[21862]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Aug 29 19:57:10 volumio volumio[21862]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 19:57:10 volumio volumio[21862]: Starting albumart workers Aug 29 19:57:10 volumio volumio[21862]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:57:10 volumio volumio[21862]: info: Discovery: adding 0f9308e2-32f3-4bb8-8a7d-d0a94be7f92c Aug 29 19:57:10 volumio volumio[21862]: info: Discovery: Found device Volumio Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:10 volumio volumio[21862]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:10 volumio volumio[21862]: info: VolumeController:: Volume=0 Mute =false Aug 29 19:57:10 volumio volumio[21862]: info: CoreStateMachine::pushState Aug 29 19:57:10 volumio volumio[21862]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:10 volumio volumio[21862]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 19:57:10 volumio volumio[21862]: info: Received Get System Info Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:57:10 volumio volumio[21862]: info: Discovery: Getting this device information Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:10 volumio volumio[21862]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:57:10 volumio volumio[21862]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:10 volumio volumio[21862]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:10 volumio volumio[21862]: info: Listing playlists Aug 29 19:57:10 volumio volumio[21862]: info: Completed loading Core Plugins Aug 29 19:57:10 volumio volumio[21862]: info: Preparing to generate the ALSA configuration file Aug 29 19:57:10 volumio volumio[21862]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 19:57:10 volumio volumio[21862]: info: Reading ALSA contributions from plugins. Aug 29 19:57:10 volumio volumio[21862]: info: Asound.conf file unchanged, so no further update is needed Aug 29 19:57:10 volumio volumio[21862]: info: Output device has changed, restarting MPD Aug 29 19:57:11 volumio volumio[21862]: info: Output device has changed, restarting Shairport Sync Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:11 volumio sudo[22008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:11 volumio sudo[22008]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:11 volumio sudo[22014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:57:11 volumio sudo[22014]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:11 volumio sudo[22008]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:11 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 19:57:11 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:57:11 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:57:11 volumio volumio[21862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:11 volumio volumio[21862]: info: ___________ START PLUGINS ___________ Aug 29 19:57:11 volumio volumio[21862]: info: ControllerMpd::onStart: Initializing MPD Aug 29 19:57:11 volumio volumio[21862]: info: Creating MPD Configuration file Aug 29 19:57:11 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:11 volumio volumio[21862]: info: [1756472231319] CoreMusicLibrary::Adding element Media Servers Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:11 volumio sudo[22038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:57:11 volumio sudo[22038]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:11 volumio sudo[22038]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:11 volumio sudo[22040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:57:11 volumio sudo[22040]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:11 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 19:57:11 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:57:11 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:57:11 volumio volumio[21862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:11 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:57:11 volumio volumio[21862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:11 volumio volumio[21862]: info: [1756472231529] CoreMusicLibrary::Adding element Last_100 Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:11 volumio volumio[21862]: info: [1756472231533] CoreMusicLibrary::Adding element Webradio Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:11 volumio volumio[21862]: info: Initializing BBC Radios Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:11 volumio volumio[21862]: info: Loading i18n strings for locale en Aug 29 19:57:11 volumio sudo[22046]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:57:11 volumio sudo[22046]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 19:57:11 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:11 volumio volumio[21862]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 19:57:11 volumio sudo[22046]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:11 volumio volumio[21862]: info: Volumio Calling Home Aug 29 19:57:11 volumio volumio[21862]: info: Preparing to generate the ALSA configuration file Aug 29 19:57:12 volumio volumio[21862]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 19:57:12 volumio volumio[21862]: info: Reading ALSA contributions from plugins. Aug 29 19:57:12 volumio volumio[21862]: info: MPD Permissions set Aug 29 19:57:12 volumio volumio[21862]: info: MPD Permissions set Aug 29 19:57:12 volumio volumio[21862]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:12 volumio volumio[21862]: info: Volumio called home Aug 29 19:57:12 volumio volumio[21862]: info: Starting Shairport Sync Aug 29 19:57:12 volumio volumio[21862]: info: Starting Shairport Sync Aug 29 19:57:12 volumio sudo[22074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:57:12 volumio volumio[21862]: info: Starting Shairport Sync Aug 29 19:57:12 volumio sudo[22074]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:12 volumio sudo[22076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:57:12 volumio sudo[22076]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:12 volumio sudo[22079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:57:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:12 volumio volumio[21862]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:12 volumio sudo[22079]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:12 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:57:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:12 volumio sudo[22074]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:12 volumio volumio[21862]: info: Asound.conf file unchanged, so no further update is needed Aug 29 19:57:12 volumio volumio[21862]: info: Output device has changed, restarting MPD Aug 29 19:57:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:57:12 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:57:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:12 volumio sudo[22079]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:12 volumio sudo[22076]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:12 volumio volumio[21862]: info: Output device has changed, restarting Shairport Sync Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:12 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:12 volumio sudo[22085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:57:12 volumio sudo[22085]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:12 volumio sudo[22085]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:12 volumio sudo[22089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:57:12 volumio sudo[22089]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:12 volumio volumio[21862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:12 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 29 19:57:12 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:57:12 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:57:12 volumio volumio[21862]: info: FusionDsp - Aug 29 19:57:13 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:57:13 volumio sudo[22096]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:57:13 volumio sudo[22096]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:13 volumio sudo[22096]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:13 volumio volumio[21862]: info: FusionDsp - Aug 29 19:57:13 volumio volumio[21862]: info: FusionDsp - Aug 29 19:57:13 volumio volumio[21862]: info: FusionDsp - Aug 29 19:57:14 volumio volumio[21862]: info: FusionDsp - Aug 29 19:57:14 volumio volumio[21862]: info: Shairport-Sync Started Aug 29 19:57:14 volumio volumio[21862]: Error adding Membership: Error: addMembership EINVAL Aug 29 19:57:14 volumio volumio[21862]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 19:57:14 volumio volumio[21862]: 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 Aug 29 19:57:14 volumio volumio[21862]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 19:57:14 volumio volumio[21862]: info: camilladsp service started and running in background, instance 1 Aug 29 19:57:14 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:14 volumio volumio[21862]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:57:14 volumio volumio[21862]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 19:57:14 volumio volumio[21862]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:57:14 volumio volumio[21862]: info: FusionDsp loaded Aug 29 19:57:14 volumio volumio[21862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:57:14 volumio sudo[22109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 19:57:14 volumio sudo[22109]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:14 volumio volumio[21862]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 19:57:14 volumio volumio[21862]: info: Adding Signal Path Element [object Object] Aug 29 19:57:14 volumio volumio[21862]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 19:57:14 volumio volumio[21862]: info: FusionDsp - ---- installed callbackRead Aug 29 19:57:14 volumio volumio[21862]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:57:14 volumio sudo[22109]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:14 volumio volumio[21862]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 19:57:14 volumio volumio[21862]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 19:57:14 volumio volumio[21862]: at onErrorNT (internal/child_process.js:465:16) Aug 29 19:57:14 volumio volumio[21862]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 29 19:57:14 volumio volumio[21862]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 29 19:57:14 volumio volumio[21862]: at listOnTimeout (internal/timers.js:523:9) Aug 29 19:57:14 volumio volumio[21862]: at processTimers (internal/timers.js:497:7) { Aug 29 19:57:14 volumio volumio[21862]: errno: -2, Aug 29 19:57:14 volumio volumio[21862]: code: 'ENOENT', Aug 29 19:57:14 volumio volumio[21862]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:57:14 volumio volumio[21862]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:57:14 volumio volumio[21862]: spawnargs: [ Aug 29 19:57:14 volumio volumio[21862]: '-p', Aug 29 19:57:14 volumio volumio[21862]: 9876, Aug 29 19:57:14 volumio volumio[21862]: '-o', Aug 29 19:57:14 volumio volumio[21862]: '/tmp/camilladsp.log', Aug 29 19:57:14 volumio volumio[21862]: '-l', Aug 29 19:57:14 volumio volumio[21862]: 'warn', Aug 29 19:57:14 volumio volumio[21862]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 19:57:14 volumio volumio[21862]: ] Aug 29 19:57:14 volumio volumio[21862]: } Aug 29 19:57:14 volumio volumio[21862]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:57:14 volumio mpd[22098]: Aug 29 19:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 19:57:14 volumio systemd[1]: Started Music Player Daemon. Aug 29 19:57:14 volumio sudo[22040]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:14 volumio sudo[22014]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:14 volumio sudo[22089]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:15 volumio sudo[22122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-29 19:56 Aug 29 19:57:15 volumio sudo[22122]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:15 volumio sudo[22122]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:15 volumio volumio-remote-updater[663]: [2025-08-29 19:57:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 19:57:15 volumio volumio-remote-updater[663]: [2025-08-29 19:57:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 19:57:15 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 19:57:15 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 19:57:15 volumio systemd[1]: Started dynamicswap service. Aug 29 19:57:15 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 19:57:15 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 19:57:15 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 83. Aug 29 19:57:15 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 19:57:15 volumio systemd[1]: Started Volumio Backend Module. Aug 29 19:57:15 volumio systemd[1]: Started dynamicswap service. Aug 29 19:57:16 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 19:57:18 volumio volumio[22132]: info: ------------------------------------------- Aug 29 19:57:18 volumio volumio[22132]: info: ----- Volumio3 ---- Aug 29 19:57:18 volumio volumio[22132]: info: ------------------------------------------- Aug 29 19:57:18 volumio volumio[22132]: info: ----- System startup ---- Aug 29 19:57:18 volumio volumio[22132]: info: ------------------------------------------- Aug 29 19:57:18 volumio volumio[22132]: info: MYVOLUMIO Environment detected Aug 29 19:57:18 volumio volumio[22132]: info: Plugin folders cleanup Aug 29 19:57:18 volumio volumio[22132]: info: Scanning into folder /volumio/app/plugins/ Aug 29 19:57:18 volumio volumio[22132]: info: Scanning category audio_interface Aug 29 19:57:18 volumio volumio[22132]: info: Scanning category miscellanea Aug 29 19:57:19 volumio volumio[22132]: info: Scanning category music_service Aug 29 19:57:19 volumio volumio[22132]: info: Scanning category plugins.json Aug 29 19:57:19 volumio volumio[22132]: info: Scanning category system_controller Aug 29 19:57:19 volumio volumio[22132]: info: Scanning category user_interface Aug 29 19:57:19 volumio volumio[22132]: info: Scanning into folder /data/plugins/ Aug 29 19:57:19 volumio volumio[22132]: info: Scanning category audio_interface Aug 29 19:57:19 volumio volumio[22132]: info: Plugin folders cleanup completed Aug 29 19:57:19 volumio volumio[22132]: info: ------------------------------------------- Aug 29 19:57:19 volumio volumio[22132]: info: ----- Core plugins startup ---- Aug 29 19:57:19 volumio volumio[22132]: info: ------------------------------------------- Aug 29 19:57:19 volumio volumio[22132]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 19:57:19 volumio volumio[22132]: info: Adding plugin upnp to MyMusic Plugins Aug 29 19:57:19 volumio volumio[22132]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 19:57:19 volumio volumio[22132]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 19:57:19 volumio volumio[22132]: info: Loading plugins from folder /data/plugins/ Aug 29 19:57:19 volumio volumio[22132]: info: Loading plugin "system"... Aug 29 19:57:19 volumio volumio[22132]: info: Loading plugin "appearance"... Aug 29 19:57:19 volumio wpa_supplicant[908]: wlan0: Failed to initiate sched scan Aug 29 19:57:20 volumio volumio[22132]: info: Loading plugin "network"... Aug 29 19:57:20 volumio volumio[22132]: info: Refreshing Cached IP Addresses Aug 29 19:57:20 volumio sudo[22162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 19:57:20 volumio sudo[22162]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:20 volumio sudo[22162]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:20 volumio sudo[22164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 19:57:20 volumio sudo[22164]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:20 volumio sudo[22164]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:20 volumio volumio[22132]: info: Loading plugin "services"... Aug 29 19:57:20 volumio volumio[22132]: info: Loading plugin "alsa_controller"... Aug 29 19:57:20 volumio sudo[22173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 19:57:20 volumio sudo[22173]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:20 volumio volumio-remote-updater[663]: [2025-08-29 19:57:20] [connect] Successful connection Aug 29 19:57:20 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:20 volumio volumio[22132]: info: Loading plugin "wizard"... Aug 29 19:57:20 volumio volumio[22132]: info: Loading plugin "networkfs"... Aug 29 19:57:20 volumio volumio[22132]: info: Starting Udev Watcher for removable devices Aug 29 19:57:20 volumio volumio[22132]: info: Ignoring mount for partition: boot Aug 29 19:57:20 volumio volumio[22132]: info: Ignoring mount for partition: volumio Aug 29 19:57:20 volumio volumio[22132]: info: Ignoring mount for partition: volumio_data Aug 29 19:57:20 volumio volumio[22132]: info: Mounting Device KINGSTON Aug 29 19:57:20 volumio sudo[22193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/KINGSTON -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 19:57:20 volumio sudo[22193]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:20 volumio sudo[22193]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:20 volumio volumio[22132]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Aug 29 19:57:20 volumio volumio[22132]: error: Failed to mount KINGSTON: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/KINGSTON" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 19:57:20 volumio volumio[22132]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Aug 29 19:57:20 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:20 volumio volumio[22132]: info: Loading plugin "volumio_command_line_client"... Aug 29 19:57:20 volumio volumio[22132]: info: Loading plugin "upnp"... Aug 29 19:57:20 volumio volumio[22132]: info: [1756472240838] Starting Upmpd Daemon Aug 29 19:57:20 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:20 volumio volumio[22132]: info: Loading plugin "my_music"... Aug 29 19:57:20 volumio volumio[22132]: info: Loading plugin "mpd"... Aug 29 19:57:21 volumio volumio[22132]: info: Loading plugin "upnp_browser"... Aug 29 19:57:21 volumio volumio[22132]: info: Loading plugin "alarm-clock"... Aug 29 19:57:21 volumio volumio[22132]: info: Loading plugin "airplay_emulation"... Aug 29 19:57:21 volumio volumio[22132]: info: Starting Shairport Sync Aug 29 19:57:21 volumio volumio[22132]: info: Loading plugin "last_100"... Aug 29 19:57:21 volumio volumio[22132]: info: Loading plugin "webradio"... Aug 29 19:57:21 volumio volumio[22132]: info: Loading plugin "i2s_dacs"... Aug 29 19:57:21 volumio volumio[22132]: info: I2S DAC not set, start Auto-detection Aug 29 19:57:21 volumio volumio[22132]: info: Loading plugin "volumiodiscovery"... Aug 29 19:57:22 volumio volumio[22132]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:22 volumio volumio[22132]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:22 volumio volumio[22132]: *** WARNING *** For more information see Aug 29 19:57:22 volumio node[22132]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:22 volumio volumio[22132]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:22 volumio volumio[22132]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:22 volumio volumio[22132]: *** WARNING *** For more information see Aug 29 19:57:22 volumio node[22132]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:22 volumio node[22132]: *** WARNING *** For more information see Aug 29 19:57:22 volumio node[22132]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:22 volumio node[22132]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:22 volumio node[22132]: *** WARNING *** For more information see Aug 29 19:57:22 volumio volumio[22132]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 19:57:22 volumio volumio[22132]: info: Discovery: Started advertising with name: Volumio Aug 29 19:57:22 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:22 volumio volumio[22132]: info: Loading plugin "outputs"... Aug 29 19:57:22 volumio volumio[22132]: info: Loading plugin "albumart"... Aug 29 19:57:22 volumio volumio[22132]: info: Plugin example_plugin is not enabled Aug 29 19:57:22 volumio volumio[22132]: info: Loading plugin "inputs"... Aug 29 19:57:22 volumio volumio[22132]: info: Loading plugin "updater_comm"... Aug 29 19:57:22 volumio volumio[22132]: info: Plugin mpdemulation is not enabled Aug 29 19:57:22 volumio volumio[22132]: info: Loading plugin "rest_api"... Aug 29 19:57:22 volumio volumio[22132]: info: Loading plugin "websocket"... Aug 29 19:57:22 volumio volumio[22132]: info: Starting Socket.io Server version 2.3.0 Aug 29 19:57:22 volumio volumio[22132]: info: Loading plugin "fusiondsp"... Aug 29 19:57:22 volumio volumio[22132]: Forking 3 albumart workers Aug 29 19:57:22 volumio volumio[22132]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 19:57:23 volumio sudo[22173]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:23 volumio volumio[22132]: info: Loading i18n strings for locale en Aug 29 19:57:23 volumio volumio[22132]: Updating browse sources language Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::initPlayerControls Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:23 volumio volumio[22132]: Express server listening on port 3000 Aug 29 19:57:23 volumio volumio[22132]: [Metrics] WebUI: 6s 86.28ms Aug 29 19:57:23 volumio volumio[22132]: info: CoreStateMachine::resetVolumioState Aug 29 19:57:23 volumio volumio[22132]: info: CoreStateMachine::getcurrentVolume Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 19:57:23 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:23 volumio volumio[22132]: info: Volumio Network Manager: Network status updated: 1 Aug 29 19:57:23 volumio volumio[22132]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 19:57:23 volumio volumio-remote-updater[663]: [2025-08-29 19:57:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756472240 101 Aug 29 19:57:23 volumio volumio[22132]: 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 Aug 29 19:57:23 volumio volumio[22132]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 29 19:57:23 volumio volumio[22132]: Starting albumart workers Aug 29 19:57:23 volumio volumio[22132]: Starting albumart workers Aug 29 19:57:23 volumio volumio[22132]: info: Reloading queue from file Aug 29 19:57:24 volumio volumio[22132]: info: CoreStateMachine::setRepeat null single undefined Aug 29 19:57:24 volumio volumio[22132]: info: CoreStateMachine::pushState Aug 29 19:57:24 volumio volumio[22132]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:24 volumio volumio[22132]: Starting albumart workers Aug 29 19:57:24 volumio volumio[22132]: info: CoreStateMachine::setRandom null Aug 29 19:57:24 volumio volumio[22132]: info: CoreStateMachine::pushState Aug 29 19:57:24 volumio volumio[22132]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:24 volumio volumio[22132]: info: Setting Device type: Raspberry PI Aug 29 19:57:24 volumio volumio[22132]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 19:57:24 volumio volumio[22132]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Aug 29 19:57:24 volumio volumio[22132]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 19:57:24 volumio volumio[22132]: info: VolumeController:: Volume=0 Mute =false Aug 29 19:57:24 volumio volumio[22132]: info: CoreStateMachine::pushState Aug 29 19:57:24 volumio volumio[22132]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:24 volumio volumio[22132]: info: CoreStateMachine::updateTrackBlock Aug 29 19:57:24 volumio volumio[22132]: info: CorePlayQueue::getTrackBlock Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 19:57:24 volumio volumio[22132]: info: Completed loading Core Plugins Aug 29 19:57:24 volumio volumio[22132]: info: Preparing to generate the ALSA configuration file Aug 29 19:57:24 volumio volumio[22132]: info: Discovery: adding 0f9308e2-32f3-4bb8-8a7d-d0a94be7f92c Aug 29 19:57:24 volumio volumio[22132]: info: Discovery: Found device Volumio Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:24 volumio volumio[22132]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:24 volumio volumio[22132]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 19:57:24 volumio volumio[22132]: info: Reading ALSA contributions from plugins. Aug 29 19:57:24 volumio volumio[22132]: info: Asound.conf file unchanged, so no further update is needed Aug 29 19:57:24 volumio volumio[22132]: info: Output device has changed, restarting MPD Aug 29 19:57:24 volumio volumio[22132]: info: Output device has changed, restarting Shairport Sync Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:24 volumio sudo[22238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:57:24 volumio sudo[22238]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:24 volumio sudo[22238]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:24 volumio sudo[22240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:57:24 volumio sudo[22240]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:24 volumio volumio[22132]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:24 volumio volumio[22132]: info: ___________ START PLUGINS ___________ Aug 29 19:57:24 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 19:57:24 volumio volumio[22132]: info: ControllerMpd::onStart: Initializing MPD Aug 29 19:57:24 volumio volumio[22132]: info: Creating MPD Configuration file Aug 29 19:57:24 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:57:24 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:57:24 volumio sudo[22248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:57:24 volumio sudo[22248]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:24 volumio volumio[22132]: info: [1756472244534] CoreMusicLibrary::Adding element Media Servers Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:24 volumio sudo[22248]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:24 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:57:24 volumio sudo[22251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:57:24 volumio sudo[22251]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:24 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 19:57:24 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:57:24 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:24 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:57:24 volumio volumio[22132]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:24 volumio sudo[22253]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:57:24 volumio sudo[22253]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:24 volumio sudo[22253]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:24 volumio volumio[22132]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:24 volumio volumio[22132]: info: [1756472244907] CoreMusicLibrary::Adding element Last_100 Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:24 volumio volumio[22132]: info: [1756472244913] CoreMusicLibrary::Adding element Webradio Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:24 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:24 volumio volumio[22132]: info: Initializing BBC Radios Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: Loading i18n strings for locale en Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 19:57:25 volumio volumio[22132]: info: Volumio Calling Home Aug 29 19:57:25 volumio volumio[22132]: info: Preparing to generate the ALSA configuration file Aug 29 19:57:25 volumio volumio[22132]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 19:57:25 volumio volumio[22132]: info: Reading ALSA contributions from plugins. Aug 29 19:57:25 volumio volumio[22132]: info: MPD Permissions set Aug 29 19:57:25 volumio volumio[22132]: info: MPD Permissions set Aug 29 19:57:25 volumio volumio[22132]: info: VolumeController:: Volume=0 Mute =false Aug 29 19:57:25 volumio volumio[22132]: info: CoreStateMachine::pushState Aug 29 19:57:25 volumio volumio[22132]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:25 volumio volumio[22132]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:25 volumio volumio[22132]: info: Volumio called home Aug 29 19:57:25 volumio volumio[22132]: info: Starting Shairport Sync Aug 29 19:57:26 volumio volumio[22132]: info: Starting Shairport Sync Aug 29 19:57:26 volumio sudo[22309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:57:26 volumio volumio[22132]: info: Starting Shairport Sync Aug 29 19:57:26 volumio sudo[22309]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:26 volumio sudo[22311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:57:26 volumio sudo[22311]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:26 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:26 volumio volumio[22132]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:26 volumio sudo[22314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:57:26 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:57:26 volumio sudo[22314]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:26 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:26 volumio volumio[22132]: info: Asound.conf file unchanged, so no further update is needed Aug 29 19:57:26 volumio volumio[22132]: info: Output device has changed, restarting MPD Aug 29 19:57:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:26 volumio sudo[22309]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:26 volumio volumio[22132]: info: Output device has changed, restarting Shairport Sync Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:26 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:57:26 volumio sudo[22319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:57:26 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:57:26 volumio sudo[22319]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:26 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:26 volumio sudo[22319]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:26 volumio sudo[22321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:57:26 volumio sudo[22321]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:26 volumio volumio[22132]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:26 volumio sudo[22314]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:26 volumio sudo[22311]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:26 volumio volumio[22132]: info: MPD Permissions set Aug 29 19:57:26 volumio volumio[22132]: info: Shairport-Sync Started Aug 29 19:57:26 volumio volumio[22132]: Error adding Membership: Error: addMembership EINVAL Aug 29 19:57:26 volumio volumio[22132]: info: Shairport-Sync Started Aug 29 19:57:26 volumio volumio[22132]: info: Shairport-Sync Started Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:26 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 29 19:57:26 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:57:26 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:57:26 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:26 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:26 volumio volumio[22132]: info: Starting Shairport Sync Aug 29 19:57:26 volumio sudo[22337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:57:26 volumio sudo[22337]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:26 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:57:26 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:57:26 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:26 volumio sudo[22331]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:57:26 volumio sudo[22331]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:26 volumio sudo[22331]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:26 volumio sudo[22337]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:26 volumio volumio[22132]: info: Shairport-Sync Started Aug 29 19:57:27 volumio volumio[22132]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 19:57:27 volumio volumio[22132]: 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 Aug 29 19:57:27 volumio volumio[22132]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 19:57:27 volumio volumio[22132]: info: camilladsp service started and running in background, instance 1 Aug 29 19:57:27 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:27 volumio volumio[22132]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:57:27 volumio volumio[22132]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 19:57:27 volumio volumio[22132]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:57:27 volumio volumio[22132]: info: FusionDsp loaded Aug 29 19:57:27 volumio volumio[22132]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:57:27 volumio sudo[22355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 19:57:27 volumio sudo[22355]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:27 volumio volumio[22132]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 19:57:27 volumio volumio[22132]: info: Adding Signal Path Element [object Object] Aug 29 19:57:27 volumio volumio[22132]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 19:57:27 volumio volumio[22132]: info: FusionDsp - ---- installed callbackRead Aug 29 19:57:27 volumio volumio[22132]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:57:27 volumio sudo[22355]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:27 volumio volumio[22132]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 19:57:27 volumio volumio[22132]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 19:57:27 volumio volumio[22132]: at onErrorNT (internal/child_process.js:465:16) Aug 29 19:57:27 volumio volumio[22132]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Aug 29 19:57:27 volumio volumio[22132]: errno: -2, Aug 29 19:57:27 volumio volumio[22132]: code: 'ENOENT', Aug 29 19:57:27 volumio volumio[22132]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:57:27 volumio volumio[22132]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:57:27 volumio volumio[22132]: spawnargs: [ Aug 29 19:57:27 volumio volumio[22132]: '-p', Aug 29 19:57:27 volumio volumio[22132]: 9876, Aug 29 19:57:27 volumio volumio[22132]: '-o', Aug 29 19:57:27 volumio volumio[22132]: '/tmp/camilladsp.log', Aug 29 19:57:27 volumio volumio[22132]: '-l', Aug 29 19:57:27 volumio volumio[22132]: 'warn', Aug 29 19:57:27 volumio volumio[22132]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 19:57:27 volumio volumio[22132]: ] Aug 29 19:57:27 volumio volumio[22132]: } Aug 29 19:57:27 volumio volumio[22132]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:57:28 volumio sudo[22366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-29 19:56 Aug 29 19:57:28 volumio sudo[22366]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:28 volumio mpd[22342]: Aug 29 19:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 19:57:28 volumio systemd[1]: Started Music Player Daemon. Aug 29 19:57:28 volumio sudo[22240]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:28 volumio sudo[22321]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:28 volumio sudo[22251]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:28 volumio sudo[22366]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:28 volumio volumio-remote-updater[663]: [2025-08-29 19:57:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 19:57:28 volumio volumio-remote-updater[663]: [2025-08-29 19:57:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 19:57:28 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 19:57:28 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 19:57:28 volumio systemd[1]: Started dynamicswap service. Aug 29 19:57:28 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 19:57:28 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 19:57:28 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 84. Aug 29 19:57:28 volumio systemd[1]: Started dynamicswap service. Aug 29 19:57:28 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 19:57:28 volumio systemd[1]: Started Volumio Backend Module. Aug 29 19:57:28 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 19:57:30 volumio volumio[22379]: info: ------------------------------------------- Aug 29 19:57:30 volumio volumio[22379]: info: ----- Volumio3 ---- Aug 29 19:57:30 volumio volumio[22379]: info: ------------------------------------------- Aug 29 19:57:30 volumio volumio[22379]: info: ----- System startup ---- Aug 29 19:57:30 volumio volumio[22379]: info: ------------------------------------------- Aug 29 19:57:31 volumio volumio[22379]: info: MYVOLUMIO Environment detected Aug 29 19:57:31 volumio volumio[22379]: info: Plugin folders cleanup Aug 29 19:57:31 volumio volumio[22379]: info: Scanning into folder /volumio/app/plugins/ Aug 29 19:57:31 volumio volumio[22379]: info: Scanning category audio_interface Aug 29 19:57:31 volumio volumio[22379]: info: Scanning category miscellanea Aug 29 19:57:31 volumio volumio[22379]: info: Scanning category music_service Aug 29 19:57:31 volumio volumio[22379]: info: Scanning category plugins.json Aug 29 19:57:31 volumio volumio[22379]: info: Scanning category system_controller Aug 29 19:57:31 volumio volumio[22379]: info: Scanning category user_interface Aug 29 19:57:31 volumio volumio[22379]: info: Scanning into folder /data/plugins/ Aug 29 19:57:31 volumio volumio[22379]: info: Scanning category audio_interface Aug 29 19:57:31 volumio volumio[22379]: info: Plugin folders cleanup completed Aug 29 19:57:31 volumio volumio[22379]: info: ------------------------------------------- Aug 29 19:57:31 volumio volumio[22379]: info: ----- Core plugins startup ---- Aug 29 19:57:31 volumio volumio[22379]: info: ------------------------------------------- Aug 29 19:57:31 volumio volumio[22379]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 19:57:31 volumio volumio[22379]: info: Adding plugin upnp to MyMusic Plugins Aug 29 19:57:31 volumio volumio[22379]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 19:57:31 volumio volumio[22379]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 19:57:31 volumio volumio[22379]: info: Loading plugins from folder /data/plugins/ Aug 29 19:57:31 volumio volumio[22379]: info: Loading plugin "system"... Aug 29 19:57:31 volumio volumio[22379]: info: Loading plugin "appearance"... Aug 29 19:57:33 volumio volumio[22379]: info: Loading plugin "network"... Aug 29 19:57:33 volumio volumio[22379]: info: Refreshing Cached IP Addresses Aug 29 19:57:33 volumio wpa_supplicant[908]: wlan0: Failed to initiate sched scan Aug 29 19:57:33 volumio sudo[22406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 19:57:33 volumio sudo[22406]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:33 volumio sudo[22406]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:33 volumio sudo[22408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 19:57:33 volumio sudo[22408]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:33 volumio sudo[22408]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:33 volumio volumio[22379]: info: Loading plugin "services"... Aug 29 19:57:33 volumio volumio[22379]: info: Loading plugin "alsa_controller"... Aug 29 19:57:33 volumio sudo[22417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 19:57:33 volumio sudo[22417]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:33 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:33 volumio volumio[22379]: info: Loading plugin "wizard"... Aug 29 19:57:33 volumio volumio[22379]: info: Loading plugin "networkfs"... Aug 29 19:57:33 volumio volumio[22379]: info: Starting Udev Watcher for removable devices Aug 29 19:57:33 volumio volumio[22379]: info: Ignoring mount for partition: boot Aug 29 19:57:33 volumio volumio[22379]: info: Ignoring mount for partition: volumio Aug 29 19:57:33 volumio volumio[22379]: info: Ignoring mount for partition: volumio_data Aug 29 19:57:33 volumio volumio[22379]: info: Mounting Device KINGSTON Aug 29 19:57:33 volumio sudo[22437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/KINGSTON -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 19:57:33 volumio sudo[22437]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:33 volumio volumio-remote-updater[663]: [2025-08-29 19:57:33] [connect] Successful connection Aug 29 19:57:33 volumio sudo[22437]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:33 volumio volumio[22379]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Aug 29 19:57:33 volumio volumio[22379]: error: Failed to mount KINGSTON: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/KINGSTON" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 19:57:33 volumio volumio[22379]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Aug 29 19:57:33 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:33 volumio volumio[22379]: info: Loading plugin "volumio_command_line_client"... Aug 29 19:57:33 volumio volumio[22379]: info: Loading plugin "upnp"... Aug 29 19:57:33 volumio volumio[22379]: info: [1756472253540] Starting Upmpd Daemon Aug 29 19:57:33 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:33 volumio volumio[22379]: info: Loading plugin "my_music"... Aug 29 19:57:33 volumio volumio[22379]: info: Loading plugin "mpd"... Aug 29 19:57:33 volumio volumio[22379]: info: Loading plugin "upnp_browser"... Aug 29 19:57:34 volumio volumio[22379]: info: Loading plugin "alarm-clock"... Aug 29 19:57:34 volumio volumio[22379]: info: Loading plugin "airplay_emulation"... Aug 29 19:57:34 volumio volumio[22379]: info: Starting Shairport Sync Aug 29 19:57:34 volumio volumio[22379]: info: Loading plugin "last_100"... Aug 29 19:57:34 volumio volumio[22379]: info: Loading plugin "webradio"... Aug 29 19:57:34 volumio volumio[22379]: info: Loading plugin "i2s_dacs"... Aug 29 19:57:34 volumio volumio[22379]: info: I2S DAC not set, start Auto-detection Aug 29 19:57:34 volumio volumio[22379]: info: Loading plugin "volumiodiscovery"... Aug 29 19:57:34 volumio volumio[22379]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:34 volumio volumio[22379]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:34 volumio node[22379]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:34 volumio volumio[22379]: *** WARNING *** For more information see Aug 29 19:57:34 volumio volumio[22379]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:34 volumio volumio[22379]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:34 volumio volumio[22379]: *** WARNING *** For more information see Aug 29 19:57:34 volumio node[22379]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:34 volumio node[22379]: *** WARNING *** For more information see Aug 29 19:57:34 volumio node[22379]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:34 volumio node[22379]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:34 volumio node[22379]: *** WARNING *** For more information see Aug 29 19:57:34 volumio volumio[22379]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 19:57:34 volumio volumio[22379]: info: Discovery: Started advertising with name: Volumio Aug 29 19:57:34 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:34 volumio volumio[22379]: info: Loading plugin "outputs"... Aug 29 19:57:34 volumio volumio[22379]: info: Loading plugin "albumart"... Aug 29 19:57:34 volumio volumio[22379]: info: Plugin example_plugin is not enabled Aug 29 19:57:34 volumio volumio[22379]: info: Loading plugin "inputs"... Aug 29 19:57:34 volumio volumio[22379]: info: Loading plugin "updater_comm"... Aug 29 19:57:35 volumio volumio[22379]: info: Plugin mpdemulation is not enabled Aug 29 19:57:35 volumio volumio[22379]: info: Loading plugin "rest_api"... Aug 29 19:57:35 volumio volumio[22379]: info: Loading plugin "websocket"... Aug 29 19:57:35 volumio volumio[22379]: info: Starting Socket.io Server version 2.3.0 Aug 29 19:57:35 volumio volumio[22379]: info: Loading plugin "fusiondsp"... Aug 29 19:57:35 volumio volumio[22379]: Forking 3 albumart workers Aug 29 19:57:35 volumio volumio[22379]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 19:57:35 volumio sudo[22417]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:35 volumio volumio[22379]: info: Loading i18n strings for locale en Aug 29 19:57:35 volumio volumio[22379]: Updating browse sources language Aug 29 19:57:35 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::initPlayerControls Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:36 volumio volumio[22379]: Express server listening on port 3000 Aug 29 19:57:36 volumio volumio[22379]: [Metrics] WebUI: 6s 3.55ms Aug 29 19:57:36 volumio volumio[22379]: info: CoreStateMachine::resetVolumioState Aug 29 19:57:36 volumio volumio[22379]: info: CoreStateMachine::getcurrentVolume Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:36 volumio volumio[22379]: info: Volumio Network Manager: Network status updated: 1 Aug 29 19:57:36 volumio volumio-remote-updater[663]: [2025-08-29 19:57:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756472253 101 Aug 29 19:57:36 volumio volumio[22379]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Aug 29 19:57:36 volumio volumio[22379]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 19:57:36 volumio volumio[22379]: Starting albumart workers Aug 29 19:57:36 volumio volumio[22379]: Starting albumart workers Aug 29 19:57:36 volumio volumio[22379]: info: Reloading queue from file Aug 29 19:57:36 volumio volumio[22379]: Starting albumart workers Aug 29 19:57:36 volumio volumio[22379]: info: CoreStateMachine::setRepeat null single undefined Aug 29 19:57:36 volumio volumio[22379]: info: CoreStateMachine::pushState Aug 29 19:57:36 volumio volumio[22379]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:36 volumio volumio[22379]: info: CoreStateMachine::setRandom null Aug 29 19:57:36 volumio volumio[22379]: info: CoreStateMachine::pushState Aug 29 19:57:36 volumio volumio[22379]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:36 volumio volumio[22379]: info: Setting Device type: Raspberry PI Aug 29 19:57:36 volumio volumio[22379]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 19:57:36 volumio volumio[22379]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Aug 29 19:57:36 volumio volumio[22379]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 19:57:36 volumio volumio[22379]: info: VolumeController:: Volume=0 Mute =false Aug 29 19:57:36 volumio volumio[22379]: info: CoreStateMachine::pushState Aug 29 19:57:36 volumio volumio[22379]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:36 volumio volumio[22379]: info: CoreStateMachine::updateTrackBlock Aug 29 19:57:36 volumio volumio[22379]: info: CorePlayQueue::getTrackBlock Aug 29 19:57:36 volumio volumio[22379]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 19:57:36 volumio volumio[22379]: info: Completed loading Core Plugins Aug 29 19:57:36 volumio volumio[22379]: info: Preparing to generate the ALSA configuration file Aug 29 19:57:37 volumio volumio[22379]: info: Discovery: adding 0f9308e2-32f3-4bb8-8a7d-d0a94be7f92c Aug 29 19:57:37 volumio volumio[22379]: info: Discovery: Found device Volumio Aug 29 19:57:37 volumio volumio[22379]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:37 volumio volumio[22379]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:37 volumio volumio[22379]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 19:57:37 volumio volumio[22379]: info: Reading ALSA contributions from plugins. Aug 29 19:57:37 volumio volumio[22379]: info: VolumeController:: Volume=0 Mute =false Aug 29 19:57:37 volumio volumio[22379]: info: CoreStateMachine::pushState Aug 29 19:57:37 volumio volumio[22379]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:37 volumio volumio[22379]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:37 volumio volumio[22379]: info: Asound.conf file unchanged, so no further update is needed Aug 29 19:57:37 volumio volumio[15560]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 29 19:57:37 volumio volumio[22379]: info: Output device has changed, restarting MPD Aug 29 19:57:37 volumio volumio[22379]: info: Output device has changed, restarting Shairport Sync Aug 29 19:57:37 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:37 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:37 volumio sudo[22497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:57:37 volumio sudo[22497]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:37 volumio sudo[22499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:57:37 volumio sudo[22499]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:37 volumio sudo[22497]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:37 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 19:57:37 volumio volumio[22379]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:37 volumio volumio[22379]: info: ___________ START PLUGINS ___________ Aug 29 19:57:37 volumio volumio[22379]: info: ControllerMpd::onStart: Initializing MPD Aug 29 19:57:37 volumio volumio[22379]: info: Creating MPD Configuration file Aug 29 19:57:37 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:57:37 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:57:37 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:57:37 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:37 volumio volumio[22379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:37 volumio volumio[22379]: info: [1756472257547] CoreMusicLibrary::Adding element Media Servers Aug 29 19:57:37 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:37 volumio sudo[22509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:57:37 volumio sudo[22509]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:37 volumio sudo[22507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:57:37 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 19:57:37 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:57:37 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:57:37 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:57:37 volumio sudo[22507]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:37 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:37 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:37 volumio sudo[22507]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:37 volumio sudo[22513]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:57:37 volumio sudo[22513]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:37 volumio sudo[22513]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:37 volumio volumio[22379]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:37 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:37 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:38 volumio volumio[22379]: info: [1756472258075] CoreMusicLibrary::Adding element Last_100 Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:38 volumio volumio[22379]: info: [1756472258080] CoreMusicLibrary::Adding element Webradio Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:38 volumio volumio[22379]: info: Initializing BBC Radios Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: Loading i18n strings for locale en Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 19:57:38 volumio volumio[22379]: info: Volumio Calling Home Aug 29 19:57:38 volumio volumio[22379]: info: Preparing to generate the ALSA configuration file Aug 29 19:57:38 volumio volumio[22379]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 19:57:38 volumio volumio[22379]: info: Reading ALSA contributions from plugins. Aug 29 19:57:38 volumio volumio[22379]: info: MPD Permissions set Aug 29 19:57:38 volumio volumio[22379]: info: MPD Permissions set Aug 29 19:57:38 volumio volumio[22379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:38 volumio volumio[22379]: info: Volumio called home Aug 29 19:57:39 volumio volumio[22379]: info: Starting Shairport Sync Aug 29 19:57:39 volumio volumio[22379]: info: Starting Shairport Sync Aug 29 19:57:39 volumio sudo[22570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:57:39 volumio sudo[22570]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:39 volumio volumio[22379]: info: Starting Shairport Sync Aug 29 19:57:39 volumio sudo[22572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:57:39 volumio sudo[22572]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:39 volumio sudo[22576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:57:39 volumio sudo[22576]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:57:39 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:57:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:39 volumio volumio[22379]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:39 volumio volumio[22379]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 19:57:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:39 volumio volumio[22379]: info: Asound.conf file unchanged, so no further update is needed Aug 29 19:57:39 volumio volumio[22379]: info: Output device has changed, restarting MPD Aug 29 19:57:39 volumio sudo[22570]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:39 volumio sudo[22572]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:57:39 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 29 19:57:39 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:57:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:39 volumio sudo[22580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:57:39 volumio sudo[22576]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:39 volumio volumio[22379]: info: Output device has changed, restarting Shairport Sync Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:39 volumio sudo[22580]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:39 volumio sudo[22580]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:39 volumio sudo[22583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:57:39 volumio sudo[22583]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:39 volumio volumio[22379]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:39 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 29 19:57:39 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:57:39 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:57:39 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:57:39 volumio volumio[22379]: info: MPD Permissions set Aug 29 19:57:39 volumio volumio[22379]: info: Shairport-Sync Started Aug 29 19:57:39 volumio volumio[22379]: Error adding Membership: Error: addMembership EINVAL Aug 29 19:57:39 volumio volumio[22379]: info: Shairport-Sync Started Aug 29 19:57:39 volumio volumio[22379]: info: Shairport-Sync Started Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:39 volumio volumio[22379]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 19:57:39 volumio volumio[22379]: info: Received Get System Info Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:57:39 volumio volumio[22379]: info: Discovery: Getting this device information Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:39 volumio volumio[22379]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:39 volumio volumio[22379]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:39 volumio volumio[22379]: info: Listing playlists Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:39 volumio sudo[22591]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:57:39 volumio sudo[22591]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:39 volumio sudo[22591]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:39 volumio volumio[22379]: info: Starting Shairport Sync Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:39 volumio sudo[22600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:57:39 volumio sudo[22600]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:39 volumio volumio[22379]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:39 volumio volumio[22379]: info: FusionDsp - Aug 29 19:57:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:57:39 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:57:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:57:40 volumio sudo[22600]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:40 volumio volumio[22379]: info: FusionDsp - Aug 29 19:57:40 volumio volumio[15560]: ............++++ Aug 29 19:57:40 volumio volumio[22379]: info: FusionDsp - Aug 29 19:57:40 volumio volumio[22379]: info: FusionDsp - Aug 29 19:57:41 volumio volumio[22379]: info: FusionDsp - Aug 29 19:57:41 volumio volumio[22379]: info: FusionDsp - Aug 29 19:57:41 volumio mpd[22598]: Aug 29 19:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 19:57:41 volumio systemd[1]: Started Music Player Daemon. Aug 29 19:57:41 volumio sudo[22509]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:41 volumio sudo[22499]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:41 volumio sudo[22583]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:41 volumio volumio[22379]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 19:57:41 volumio volumio[22379]: 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 Aug 29 19:57:41 volumio volumio[22379]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 19:57:41 volumio volumio[22379]: info: camilladsp service started and running in background, instance 1 Aug 29 19:57:41 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:41 volumio volumio[22379]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:57:41 volumio volumio[22379]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 19:57:41 volumio volumio[22379]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:57:41 volumio volumio[22379]: info: FusionDsp loaded Aug 29 19:57:41 volumio volumio[22379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:57:41 volumio sudo[22619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 19:57:41 volumio sudo[22619]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:41 volumio volumio[22379]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 19:57:41 volumio volumio[22379]: info: Adding Signal Path Element [object Object] Aug 29 19:57:41 volumio volumio[22379]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 19:57:41 volumio volumio[22379]: info: FusionDsp - ---- installed callbackRead Aug 29 19:57:41 volumio volumio[22379]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:57:41 volumio volumio[22379]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 19:57:41 volumio volumio[22379]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 19:57:41 volumio volumio[22379]: at onErrorNT (internal/child_process.js:465:16) Aug 29 19:57:41 volumio volumio[22379]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 29 19:57:41 volumio volumio[22379]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 29 19:57:41 volumio volumio[22379]: at listOnTimeout (internal/timers.js:523:9) Aug 29 19:57:41 volumio volumio[22379]: at processTimers (internal/timers.js:497:7) { Aug 29 19:57:41 volumio volumio[22379]: errno: -2, Aug 29 19:57:41 volumio volumio[22379]: code: 'ENOENT', Aug 29 19:57:41 volumio volumio[22379]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:57:41 volumio volumio[22379]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:57:41 volumio volumio[22379]: spawnargs: [ Aug 29 19:57:41 volumio volumio[22379]: '-p', Aug 29 19:57:41 volumio volumio[22379]: 9876, Aug 29 19:57:41 volumio volumio[22379]: '-o', Aug 29 19:57:41 volumio volumio[22379]: '/tmp/camilladsp.log', Aug 29 19:57:41 volumio volumio[22379]: '-l', Aug 29 19:57:41 volumio volumio[22379]: 'warn', Aug 29 19:57:41 volumio volumio[22379]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 19:57:41 volumio volumio[22379]: ] Aug 29 19:57:41 volumio volumio[22379]: } Aug 29 19:57:41 volumio volumio[22379]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:57:41 volumio sudo[22619]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:42 volumio sudo[22630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-29 19:56 Aug 29 19:57:42 volumio sudo[22630]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:42 volumio sudo[22630]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:42 volumio volumio-remote-updater[663]: [2025-08-29 19:57:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 19:57:42 volumio volumio-remote-updater[663]: [2025-08-29 19:57:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 19:57:42 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 19:57:42 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 19:57:42 volumio systemd[1]: Started dynamicswap service. Aug 29 19:57:43 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 19:57:43 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 19:57:43 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 85. Aug 29 19:57:43 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 19:57:43 volumio systemd[1]: Started Volumio Backend Module. Aug 29 19:57:43 volumio systemd[1]: Started dynamicswap service. Aug 29 19:57:43 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 19:57:43 volumio ntpd[798]: 202.29.58.130 local addr 192.168.1.112 -> Aug 29 19:57:45 volumio volumio[22640]: info: ------------------------------------------- Aug 29 19:57:45 volumio volumio[22640]: info: ----- Volumio3 ---- Aug 29 19:57:45 volumio volumio[22640]: info: ------------------------------------------- Aug 29 19:57:45 volumio volumio[22640]: info: ----- System startup ---- Aug 29 19:57:45 volumio volumio[22640]: info: ------------------------------------------- Aug 29 19:57:45 volumio wpa_supplicant[908]: wlan0: Failed to initiate sched scan Aug 29 19:57:46 volumio volumio[22640]: info: MYVOLUMIO Environment detected Aug 29 19:57:46 volumio volumio[22640]: info: Plugin folders cleanup Aug 29 19:57:46 volumio volumio[22640]: info: Scanning into folder /volumio/app/plugins/ Aug 29 19:57:46 volumio volumio[22640]: info: Scanning category audio_interface Aug 29 19:57:46 volumio volumio[22640]: info: Scanning category miscellanea Aug 29 19:57:46 volumio volumio[22640]: info: Scanning category music_service Aug 29 19:57:46 volumio volumio[22640]: info: Scanning category plugins.json Aug 29 19:57:46 volumio volumio[22640]: info: Scanning category system_controller Aug 29 19:57:46 volumio volumio[22640]: info: Scanning category user_interface Aug 29 19:57:46 volumio volumio[22640]: info: Scanning into folder /data/plugins/ Aug 29 19:57:46 volumio volumio[22640]: info: Scanning category audio_interface Aug 29 19:57:46 volumio volumio[22640]: info: Plugin folders cleanup completed Aug 29 19:57:46 volumio volumio[22640]: info: ------------------------------------------- Aug 29 19:57:46 volumio volumio[22640]: info: ----- Core plugins startup ---- Aug 29 19:57:46 volumio volumio[22640]: info: ------------------------------------------- Aug 29 19:57:46 volumio volumio[22640]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 19:57:46 volumio volumio[22640]: info: Adding plugin upnp to MyMusic Plugins Aug 29 19:57:46 volumio volumio[22640]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 19:57:46 volumio volumio[22640]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 19:57:46 volumio volumio[22640]: info: Loading plugins from folder /data/plugins/ Aug 29 19:57:46 volumio volumio[22640]: info: Loading plugin "system"... Aug 29 19:57:46 volumio volumio[22640]: info: Loading plugin "appearance"... Aug 29 19:57:47 volumio volumio[22640]: info: Loading plugin "network"... Aug 29 19:57:47 volumio volumio[22640]: info: Refreshing Cached IP Addresses Aug 29 19:57:47 volumio sudo[22667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 19:57:47 volumio sudo[22667]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:47 volumio sudo[22667]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:47 volumio sudo[22669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 19:57:47 volumio sudo[22669]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:47 volumio sudo[22669]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:47 volumio volumio[22640]: info: Loading plugin "services"... Aug 29 19:57:47 volumio volumio[22640]: info: Loading plugin "alsa_controller"... Aug 29 19:57:47 volumio sudo[22678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 19:57:47 volumio sudo[22678]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:47 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:47 volumio volumio[22640]: info: Loading plugin "wizard"... Aug 29 19:57:47 volumio volumio[22640]: info: Loading plugin "networkfs"... Aug 29 19:57:47 volumio volumio[22640]: info: Starting Udev Watcher for removable devices Aug 29 19:57:47 volumio volumio[22640]: info: Ignoring mount for partition: boot Aug 29 19:57:47 volumio volumio[22640]: info: Ignoring mount for partition: volumio Aug 29 19:57:47 volumio volumio[22640]: info: Ignoring mount for partition: volumio_data Aug 29 19:57:47 volumio volumio-remote-updater[663]: [2025-08-29 19:57:47] [connect] Successful connection Aug 29 19:57:47 volumio volumio[22640]: info: Mounting Device KINGSTON Aug 29 19:57:47 volumio sudo[22698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/KINGSTON -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 19:57:47 volumio sudo[22698]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:47 volumio sudo[22698]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:47 volumio volumio[22640]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Aug 29 19:57:47 volumio volumio[22640]: error: Failed to mount KINGSTON: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/KINGSTON" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 19:57:47 volumio volumio[22640]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Aug 29 19:57:47 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:47 volumio volumio[22640]: info: Loading plugin "volumio_command_line_client"... Aug 29 19:57:48 volumio volumio[22640]: info: Loading plugin "upnp"... Aug 29 19:57:48 volumio volumio[22640]: info: [1756472268009] Starting Upmpd Daemon Aug 29 19:57:48 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:48 volumio volumio[22640]: info: Loading plugin "my_music"... Aug 29 19:57:48 volumio volumio[22640]: info: Loading plugin "mpd"... Aug 29 19:57:48 volumio volumio[22640]: info: Loading plugin "upnp_browser"... Aug 29 19:57:48 volumio volumio[22640]: info: Loading plugin "alarm-clock"... Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "airplay_emulation"... Aug 29 19:57:49 volumio volumio[22640]: info: Starting Shairport Sync Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "last_100"... Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "webradio"... Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "i2s_dacs"... Aug 29 19:57:49 volumio volumio[22640]: info: I2S DAC not set, start Auto-detection Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "volumiodiscovery"... Aug 29 19:57:49 volumio volumio[22640]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:49 volumio volumio[22640]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:49 volumio node[22640]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:49 volumio volumio[22640]: *** WARNING *** For more information see Aug 29 19:57:49 volumio volumio[22640]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:49 volumio volumio[22640]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:49 volumio volumio[22640]: *** WARNING *** For more information see Aug 29 19:57:49 volumio node[22640]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:49 volumio node[22640]: *** WARNING *** For more information see Aug 29 19:57:49 volumio node[22640]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 19:57:49 volumio node[22640]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:57:49 volumio node[22640]: *** WARNING *** For more information see Aug 29 19:57:49 volumio volumio[22640]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 19:57:49 volumio volumio[22640]: info: Discovery: Started advertising with name: Volumio Aug 29 19:57:49 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "outputs"... Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "albumart"... Aug 29 19:57:49 volumio volumio[22640]: info: Plugin example_plugin is not enabled Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "inputs"... Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "updater_comm"... Aug 29 19:57:49 volumio volumio[22640]: info: Plugin mpdemulation is not enabled Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "rest_api"... Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "websocket"... Aug 29 19:57:49 volumio volumio[22640]: info: Starting Socket.io Server version 2.3.0 Aug 29 19:57:49 volumio volumio[22640]: info: Loading plugin "fusiondsp"... Aug 29 19:57:49 volumio volumio[22640]: Forking 3 albumart workers Aug 29 19:57:50 volumio sudo[22678]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:50 volumio volumio[22640]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 19:57:50 volumio volumio[22640]: info: Loading i18n strings for locale en Aug 29 19:57:50 volumio volumio[22640]: Updating browse sources language Aug 29 19:57:50 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::initPlayerControls Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:51 volumio volumio[22640]: Express server listening on port 3000 Aug 29 19:57:51 volumio volumio[22640]: [Metrics] WebUI: 6s 584.41ms Aug 29 19:57:51 volumio volumio[22640]: Starting albumart workers Aug 29 19:57:51 volumio volumio[22640]: info: CoreStateMachine::resetVolumioState Aug 29 19:57:51 volumio volumio[22640]: info: CoreStateMachine::getcurrentVolume Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:51 volumio volumio[22640]: info: Volumio Network Manager: Network status updated: 1 Aug 29 19:57:51 volumio volumio[22640]: Starting albumart workers Aug 29 19:57:51 volumio volumio[22640]: Starting albumart workers Aug 29 19:57:51 volumio volumio[22640]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 19:57:51 volumio volumio-remote-updater[663]: [2025-08-29 19:57:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756472267 101 Aug 29 19:57:51 volumio volumio[22640]: 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 Aug 29 19:57:51 volumio volumio[22640]: info: Reloading queue from file Aug 29 19:57:51 volumio volumio[22640]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 29 19:57:51 volumio volumio[22640]: info: CoreStateMachine::setRepeat null single undefined Aug 29 19:57:51 volumio volumio[22640]: info: CoreStateMachine::pushState Aug 29 19:57:51 volumio volumio[22640]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:51 volumio volumio[22640]: info: CoreStateMachine::setRandom null Aug 29 19:57:51 volumio volumio[22640]: info: CoreStateMachine::pushState Aug 29 19:57:51 volumio volumio[22640]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:51 volumio volumio[22640]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:51 volumio volumio[22640]: info: Setting Device type: Raspberry PI Aug 29 19:57:52 volumio volumio[22640]: info: VolumeController:: Volume=0 Mute =false Aug 29 19:57:52 volumio volumio[22640]: info: CoreStateMachine::pushState Aug 29 19:57:52 volumio volumio[22640]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:52 volumio volumio[22640]: info: CoreStateMachine::updateTrackBlock Aug 29 19:57:52 volumio volumio[22640]: info: CorePlayQueue::getTrackBlock Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 19:57:52 volumio volumio[22640]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 19:57:52 volumio volumio[22640]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Aug 29 19:57:52 volumio volumio[22640]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:52 volumio volumio[22640]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 19:57:52 volumio volumio[22640]: info: Received Get System Info Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:57:52 volumio volumio[22640]: info: Discovery: Getting this device information Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:52 volumio volumio[22640]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:52 volumio volumio[22640]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:52 volumio volumio[22640]: info: Listing playlists Aug 29 19:57:52 volumio volumio[22640]: info: Discovery: adding 0f9308e2-32f3-4bb8-8a7d-d0a94be7f92c Aug 29 19:57:52 volumio volumio[22640]: info: Discovery: Found device Volumio Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::volumioGetState Aug 29 19:57:52 volumio volumio[22640]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:52 volumio volumio[22640]: info: Completed loading Core Plugins Aug 29 19:57:52 volumio volumio[22640]: info: Preparing to generate the ALSA configuration file Aug 29 19:57:52 volumio volumio[22640]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 19:57:52 volumio volumio[22640]: info: Reading ALSA contributions from plugins. Aug 29 19:57:52 volumio volumio[22640]: info: VolumeController:: Volume=0 Mute =false Aug 29 19:57:52 volumio volumio[22640]: info: CoreStateMachine::pushState Aug 29 19:57:52 volumio volumio[22640]: info: CorePlayQueue::getTrack 0 Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::volumioPushState Aug 29 19:57:52 volumio volumio[22640]: info: Asound.conf file unchanged, so no further update is needed Aug 29 19:57:52 volumio volumio[22640]: info: Output device has changed, restarting MPD Aug 29 19:57:52 volumio volumio[22640]: info: Output device has changed, restarting Shairport Sync Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:52 volumio sudo[22756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:57:52 volumio sudo[22756]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:52 volumio sudo[22762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:57:52 volumio sudo[22762]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:52 volumio sudo[22756]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:52 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 19:57:52 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:57:52 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:57:52 volumio volumio[22640]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:52 volumio volumio[22640]: info: ___________ START PLUGINS ___________ Aug 29 19:57:52 volumio volumio[22640]: info: ControllerMpd::onStart: Initializing MPD Aug 29 19:57:52 volumio volumio[22640]: info: Creating MPD Configuration file Aug 29 19:57:52 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:57:52 volumio sudo[22770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:57:52 volumio sudo[22770]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:52 volumio volumio[22640]: info: [1756472272740] CoreMusicLibrary::Adding element Media Servers Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:52 volumio sudo[22770]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:52 volumio sudo[22777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:57:52 volumio sudo[22777]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:52 volumio sudo[22768]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:57:52 volumio sudo[22768]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:52 volumio sudo[22768]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:52 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 19:57:52 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:57:52 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:57:52 volumio volumio[22640]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:52 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:52 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:57:52 volumio volumio[22640]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:53 volumio volumio[22640]: info: [1756472273000] CoreMusicLibrary::Adding element Last_100 Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:57:53 volumio volumio[22640]: info: [1756472273005] CoreMusicLibrary::Adding element Webradio Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:53 volumio volumio[22640]: info: Initializing BBC Radios Aug 29 19:57:53 volumio sudo[22786]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:57:53 volumio sudo[22786]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:53 volumio sudo[22786]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: Loading i18n strings for locale en Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 19:57:53 volumio volumio[22640]: info: Volumio Calling Home Aug 29 19:57:53 volumio volumio[22640]: info: Preparing to generate the ALSA configuration file Aug 29 19:57:53 volumio volumio[22640]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 19:57:53 volumio volumio[22640]: info: Reading ALSA contributions from plugins. Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:57:53 volumio volumio[22640]: info: MPD Permissions set Aug 29 19:57:53 volumio volumio[22640]: info: MPD Permissions set Aug 29 19:57:53 volumio volumio[22640]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:57:53 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:54 volumio volumio[22640]: info: FusionDsp - Aug 29 19:57:54 volumio volumio[22640]: info: FusionDsp - Aug 29 19:57:54 volumio volumio[22640]: info: FusionDsp - Aug 29 19:57:54 volumio volumio[22640]: info: FusionDsp - Aug 29 19:57:54 volumio mpd[22801]: Aug 29 19:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 19:57:54 volumio systemd[1]: Started Music Player Daemon. Aug 29 19:57:54 volumio sudo[22762]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:54 volumio sudo[22777]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:55 volumio volumio[22640]: info: FusionDsp - Aug 29 19:57:55 volumio volumio[22640]: info: FusionDsp - Aug 29 19:57:55 volumio volumio[22640]: info: FusionDsp - Aug 29 19:57:55 volumio volumio[22640]: info: Volumio called home Aug 29 19:57:55 volumio volumio[22640]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 19:57:55 volumio volumio[22640]: 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 Aug 29 19:57:55 volumio volumio[22640]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 19:57:55 volumio volumio[22640]: info: camilladsp service started and running in background, instance 1 Aug 29 19:57:55 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:57:55 volumio volumio[22640]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:57:55 volumio volumio[22640]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 19:57:55 volumio volumio[22640]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 19:57:55 volumio volumio[22640]: info: FusionDsp loaded Aug 29 19:57:55 volumio volumio[22640]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:57:55 volumio sudo[22825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 19:57:55 volumio sudo[22825]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:55 volumio volumio[22640]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 19:57:55 volumio volumio[22640]: info: Adding Signal Path Element [object Object] Aug 29 19:57:55 volumio volumio[22640]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 19:57:55 volumio volumio[22640]: info: FusionDsp - ---- installed callbackRead Aug 29 19:57:55 volumio volumio[22640]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:57:55 volumio volumio[22640]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 19:57:55 volumio volumio[22640]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 19:57:55 volumio volumio[22640]: at onErrorNT (internal/child_process.js:465:16) Aug 29 19:57:55 volumio volumio[22640]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 29 19:57:55 volumio volumio[22640]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 29 19:57:55 volumio volumio[22640]: at listOnTimeout (internal/timers.js:523:9) Aug 29 19:57:55 volumio volumio[22640]: at processTimers (internal/timers.js:497:7) { Aug 29 19:57:55 volumio volumio[22640]: errno: -2, Aug 29 19:57:55 volumio volumio[22640]: code: 'ENOENT', Aug 29 19:57:55 volumio volumio[22640]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:57:55 volumio volumio[22640]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 19:57:55 volumio volumio[22640]: spawnargs: [ Aug 29 19:57:55 volumio volumio[22640]: '-p', Aug 29 19:57:55 volumio volumio[22640]: 9876, Aug 29 19:57:55 volumio volumio[22640]: '-o', Aug 29 19:57:55 volumio volumio[22640]: '/tmp/camilladsp.log', Aug 29 19:57:55 volumio volumio[22640]: '-l', Aug 29 19:57:55 volumio volumio[22640]: 'warn', Aug 29 19:57:55 volumio volumio[22640]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 19:57:55 volumio volumio[22640]: ] Aug 29 19:57:55 volumio volumio[22640]: } Aug 29 19:57:55 volumio volumio[22640]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:57:55 volumio sudo[22825]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:56 volumio sudo[22836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-29 19:56 Aug 29 19:57:56 volumio sudo[22836]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:57:56 volumio sudo[22836]: pam_unix(sudo:session): session closed for user root Aug 29 19:57:57 volumio volumio-remote-updater[663]: [2025-08-29 19:57:57] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 19:57:57 volumio volumio-remote-updater[663]: [2025-08-29 19:57:57] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 19:57:57 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 19:57:57 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 19:57:57 volumio systemd[1]: Started dynamicswap service. Aug 29 19:57:57 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 19:57:57 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 19:57:57 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 86. Aug 29 19:57:57 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 19:57:57 volumio systemd[1]: Started Volumio Backend Module. Aug 29 19:57:57 volumio systemd[1]: Started dynamicswap service. Aug 29 19:57:57 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 19:57:59 volumio volumio[22846]: info: ------------------------------------------- Aug 29 19:57:59 volumio volumio[22846]: info: ----- Volumio3 ---- Aug 29 19:57:59 volumio volumio[22846]: info: ------------------------------------------- Aug 29 19:57:59 volumio volumio[22846]: info: ----- System startup ---- Aug 29 19:57:59 volumio volumio[22846]: info: ------------------------------------------- Aug 29 19:58:00 volumio wpa_supplicant[908]: wlan0: Failed to initiate sched scan Aug 29 19:58:00 volumio volumio[22846]: info: MYVOLUMIO Environment detected Aug 29 19:58:00 volumio volumio[22846]: info: Plugin folders cleanup Aug 29 19:58:00 volumio volumio[22846]: info: Scanning into folder /volumio/app/plugins/ Aug 29 19:58:00 volumio volumio[22846]: info: Scanning category audio_interface Aug 29 19:58:00 volumio volumio[22846]: info: Scanning category miscellanea Aug 29 19:58:00 volumio volumio[22846]: info: Scanning category music_service Aug 29 19:58:00 volumio volumio[22846]: info: Scanning category plugins.json Aug 29 19:58:00 volumio volumio[22846]: info: Scanning category system_controller Aug 29 19:58:00 volumio volumio[22846]: info: Scanning category user_interface Aug 29 19:58:00 volumio volumio[22846]: info: Scanning into folder /data/plugins/ Aug 29 19:58:00 volumio volumio[22846]: info: Scanning category audio_interface Aug 29 19:58:00 volumio volumio[22846]: info: Plugin folders cleanup completed Aug 29 19:58:00 volumio volumio[22846]: info: ------------------------------------------- Aug 29 19:58:00 volumio volumio[22846]: info: ----- Core plugins startup ---- Aug 29 19:58:00 volumio volumio[22846]: info: ------------------------------------------- Aug 29 19:58:00 volumio volumio[22846]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 19:58:00 volumio volumio[22846]: info: Adding plugin upnp to MyMusic Plugins Aug 29 19:58:00 volumio volumio[22846]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 19:58:00 volumio volumio[22846]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 19:58:00 volumio volumio[22846]: info: Loading plugins from folder /data/plugins/ Aug 29 19:58:00 volumio volumio[22846]: info: Loading plugin "system"... Aug 29 19:58:00 volumio volumio[22846]: info: Loading plugin "appearance"... Aug 29 19:58:01 volumio volumio[22846]: info: Loading plugin "network"... Aug 29 19:58:01 volumio volumio[22846]: info: Refreshing Cached IP Addresses Aug 29 19:58:01 volumio sudo[22876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 19:58:01 volumio sudo[22876]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:58:01 volumio sudo[22876]: pam_unix(sudo:session): session closed for user root Aug 29 19:58:01 volumio sudo[22878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 19:58:01 volumio sudo[22878]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:58:01 volumio sudo[22878]: pam_unix(sudo:session): session closed for user root Aug 29 19:58:01 volumio volumio[22846]: info: Loading plugin "services"... Aug 29 19:58:01 volumio volumio[22846]: info: Loading plugin "alsa_controller"... Aug 29 19:58:01 volumio sudo[22887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 19:58:01 volumio sudo[22887]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:58:02 volumio volumio-remote-updater[663]: [2025-08-29 19:58:02] [connect] Successful connection Aug 29 19:58:02 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:58:02 volumio volumio[22846]: info: Loading plugin "wizard"... Aug 29 19:58:02 volumio volumio[22846]: info: Loading plugin "networkfs"... Aug 29 19:58:02 volumio volumio[22846]: info: Starting Udev Watcher for removable devices Aug 29 19:58:02 volumio volumio[22846]: info: Ignoring mount for partition: boot Aug 29 19:58:02 volumio volumio[22846]: info: Ignoring mount for partition: volumio Aug 29 19:58:02 volumio volumio[22846]: info: Ignoring mount for partition: volumio_data Aug 29 19:58:02 volumio volumio[22846]: info: Mounting Device KINGSTON Aug 29 19:58:02 volumio sudo[22907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/KINGSTON -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 19:58:02 volumio sudo[22907]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:58:02 volumio sudo[22907]: pam_unix(sudo:session): session closed for user root Aug 29 19:58:02 volumio volumio[22846]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Aug 29 19:58:02 volumio volumio[22846]: error: Failed to mount KINGSTON: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/KINGSTON" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 19:58:02 volumio volumio[22846]: mount: /media/KINGSTON: /dev/sda1 already mounted on /media/KINGSTON. Aug 29 19:58:02 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:58:02 volumio volumio[22846]: info: Loading plugin "volumio_command_line_client"... Aug 29 19:58:02 volumio volumio[22846]: info: Loading plugin "upnp"... Aug 29 19:58:02 volumio volumio[22846]: info: [1756472282370] Starting Upmpd Daemon Aug 29 19:58:02 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:58:02 volumio volumio[22846]: info: Loading plugin "my_music"... Aug 29 19:58:02 volumio volumio[22846]: info: Loading plugin "mpd"... Aug 29 19:58:02 volumio volumio[22846]: info: Loading plugin "upnp_browser"... Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "alarm-clock"... Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "airplay_emulation"... Aug 29 19:58:03 volumio volumio[22846]: info: Starting Shairport Sync Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "last_100"... Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "webradio"... Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "i2s_dacs"... Aug 29 19:58:03 volumio volumio[22846]: info: I2S DAC not set, start Auto-detection Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "volumiodiscovery"... Aug 29 19:58:03 volumio volumio[22846]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 19:58:03 volumio volumio[22846]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:58:03 volumio node[22846]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 19:58:03 volumio volumio[22846]: *** WARNING *** For more information see Aug 29 19:58:03 volumio volumio[22846]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 19:58:03 volumio volumio[22846]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:58:03 volumio volumio[22846]: *** WARNING *** For more information see Aug 29 19:58:03 volumio node[22846]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:58:03 volumio node[22846]: *** WARNING *** For more information see Aug 29 19:58:03 volumio node[22846]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 19:58:03 volumio node[22846]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 19:58:03 volumio node[22846]: *** WARNING *** For more information see Aug 29 19:58:03 volumio volumio[22846]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 19:58:03 volumio volumio[22846]: info: Discovery: Started advertising with name: Volumio Aug 29 19:58:03 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "outputs"... Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "albumart"... Aug 29 19:58:03 volumio volumio[22846]: info: Plugin example_plugin is not enabled Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "inputs"... Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "updater_comm"... Aug 29 19:58:03 volumio volumio[22846]: info: Plugin mpdemulation is not enabled Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "rest_api"... Aug 29 19:58:03 volumio volumio[22846]: info: Loading plugin "websocket"... Aug 29 19:58:04 volumio volumio[22846]: info: Starting Socket.io Server version 2.3.0 Aug 29 19:58:04 volumio volumio[22846]: info: Loading plugin "fusiondsp"... Aug 29 19:58:04 volumio volumio[22846]: Forking 3 albumart workers Aug 29 19:58:04 volumio sudo[22887]: pam_unix(sudo:session): session closed for user root Aug 29 19:58:04 volumio volumio[22846]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 19:58:04 volumio volumio[22846]: info: Loading i18n strings for locale en Aug 29 19:58:05 volumio volumio[22846]: Updating browse sources language Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:58:05 volumio volumio[22846]: Starting albumart workers Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::initPlayerControls Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:58:05 volumio volumio[22846]: Express server listening on port 3000 Aug 29 19:58:05 volumio volumio[22846]: [Metrics] WebUI: 6s 806.65ms Aug 29 19:58:05 volumio volumio[22846]: info: CoreStateMachine::resetVolumioState Aug 29 19:58:05 volumio volumio[22846]: info: CoreStateMachine::getcurrentVolume Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 19:58:05 volumio volumio[22846]: Starting albumart workers Aug 29 19:58:05 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:58:06 volumio volumio[22846]: info: Volumio Network Manager: Network status updated: 1 Aug 29 19:58:06 volumio volumio[22846]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 19:58:06 volumio volumio-remote-updater[663]: [2025-08-29 19:58:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756472282 101 Aug 29 19:58:06 volumio volumio[22846]: 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 Aug 29 19:58:06 volumio volumio[22846]: Starting albumart workers Aug 29 19:58:06 volumio volumio[22846]: info: Reloading queue from file Aug 29 19:58:06 volumio volumio[22846]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.103 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 29 19:58:06 volumio volumio[22846]: info: CoreStateMachine::setRepeat null single undefined Aug 29 19:58:06 volumio volumio[22846]: info: CoreStateMachine::pushState Aug 29 19:58:06 volumio volumio[22846]: info: CorePlayQueue::getTrack 0 Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::volumioPushState Aug 29 19:58:06 volumio volumio[22846]: info: CoreStateMachine::setRandom null Aug 29 19:58:06 volumio volumio[22846]: info: CoreStateMachine::pushState Aug 29 19:58:06 volumio volumio[22846]: info: CorePlayQueue::getTrack 0 Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::volumioPushState Aug 29 19:58:06 volumio volumio[22846]: info: Setting Device type: Raspberry PI Aug 29 19:58:06 volumio volumio[22846]: info: VolumeController:: Volume=0 Mute =false Aug 29 19:58:06 volumio volumio[22846]: info: CoreStateMachine::pushState Aug 29 19:58:06 volumio volumio[22846]: info: CorePlayQueue::getTrack 0 Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::volumioPushState Aug 29 19:58:06 volumio volumio[22846]: info: CoreStateMachine::updateTrackBlock Aug 29 19:58:06 volumio volumio[22846]: info: CorePlayQueue::getTrackBlock Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 19:58:06 volumio volumio[22846]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 19:58:06 volumio volumio[22846]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Aug 29 19:58:06 volumio volumio[22846]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::volumioGetState Aug 29 19:58:06 volumio volumio[22846]: info: CorePlayQueue::getTrack 0 Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 19:58:06 volumio volumio[22846]: info: Received Get System Info Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:58:06 volumio volumio[22846]: info: Discovery: Getting this device information Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::volumioGetState Aug 29 19:58:06 volumio volumio[22846]: info: CorePlayQueue::getTrack 0 Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::volumioGetState Aug 29 19:58:06 volumio volumio[22846]: info: CorePlayQueue::getTrack 0 Aug 29 19:58:06 volumio volumio[22846]: info: Listing playlists Aug 29 19:58:06 volumio volumio[22846]: info: Discovery: adding 0f9308e2-32f3-4bb8-8a7d-d0a94be7f92c Aug 29 19:58:06 volumio volumio[22846]: info: Discovery: Found device Volumio Aug 29 19:58:06 volumio volumio[22846]: info: CoreCommandRouter::volumioGetState Aug 29 19:58:06 volumio volumio[22846]: info: CorePlayQueue::getTrack 0 Aug 29 19:58:06 volumio volumio[22846]: info: Completed loading Core Plugins Aug 29 19:58:06 volumio volumio[22846]: info: Preparing to generate the ALSA configuration file Aug 29 19:58:06 volumio volumio[22846]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 19:58:06 volumio volumio[22846]: info: Reading ALSA contributions from plugins. Aug 29 19:58:07 volumio volumio[22846]: info: VolumeController:: Volume=0 Mute =false Aug 29 19:58:07 volumio volumio[22846]: info: CoreStateMachine::pushState Aug 29 19:58:07 volumio volumio[22846]: info: CorePlayQueue::getTrack 0 Aug 29 19:58:07 volumio volumio[22846]: info: CoreCommandRouter::volumioPushState Aug 29 19:58:07 volumio volumio[22846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:58:07 volumio volumio[22846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:58:07 volumio volumio[22846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:58:07 volumio volumio[22846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:58:07 volumio volumio[22846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:58:07 volumio volumio[22846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:58:07 volumio volumio[22846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:58:07 volumio volumio[22846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:58:07 volumio volumio[22846]: info: FusionDsp - Aug 29 19:58:07 volumio volumio[22846]: info: FusionDsp - Aug 29 19:58:07 volumio volumio[22846]: info: FusionDsp - Aug 29 19:58:07 volumio volumio[22846]: info: FusionDsp - Aug 29 19:58:08 volumio volumio[22846]: info: FusionDsp - Aug 29 19:58:08 volumio volumio[22846]: info: FusionDsp - Aug 29 19:58:08 volumio volumio[22846]: info: FusionDsp - Aug 29 19:58:08 volumio volumio[22846]: info: FusionDsp - Aug 29 19:58:09 volumio volumio[22846]: info: FusionDsp - undefined Aug 29 19:58:09 volumio volumio[22846]: info: FusionDsp - undefined Aug 29 19:58:09 volumio volumio[22846]: info: FusionDsp - undefined Aug 29 19:58:09 volumio volumio[22846]: info: FusionDsp - undefined Aug 29 19:58:09 volumio volumio[22846]: info: FusionDsp - undefined Aug 29 19:58:09 volumio volumio[22846]: info: FusionDsp - undefined Aug 29 19:58:09 volumio volumio[22846]: info: FusionDsp - undefined Aug 29 19:58:09 volumio volumio[22846]: info: FusionDsp - undefined Aug 29 19:58:09 volumio volumio[22846]: info: Asound.conf file unchanged, so no further update is needed Aug 29 19:58:09 volumio volumio[22846]: info: Output device has changed, restarting MPD Aug 29 19:58:09 volumio sudo[23014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:58:09 volumio sudo[23014]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:58:09 volumio volumio[22846]: info: Output device has changed, restarting Shairport Sync Aug 29 19:58:09 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:09 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:58:09 volumio sudo[23014]: pam_unix(sudo:session): session closed for user root Aug 29 19:58:09 volumio sudo[23017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:58:09 volumio sudo[23017]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:58:09 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 19:58:09 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:58:09 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:58:09 volumio volumio[22846]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:58:09 volumio volumio[22846]: info: ___________ START PLUGINS ___________ Aug 29 19:58:09 volumio volumio[22846]: info: ControllerMpd::onStart: Initializing MPD Aug 29 19:58:09 volumio volumio[22846]: info: Creating MPD Configuration file Aug 29 19:58:09 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:58:09 volumio sudo[23024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 19:58:09 volumio sudo[23024]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:58:09 volumio sudo[23024]: pam_unix(sudo:session): session closed for user root Aug 29 19:58:09 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:58:09 volumio volumio[22846]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:58:09 volumio volumio[22846]: info: [1756472289787] CoreMusicLibrary::Adding element Media Servers Aug 29 19:58:09 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:58:09 volumio sudo[23027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 19:58:09 volumio sudo[23027]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:58:09 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 19:58:09 volumio systemd[1]: mpd.service: Succeeded. Aug 29 19:58:09 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 19:58:09 volumio systemd[1]: Starting Music Player Daemon... Aug 29 19:58:09 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:09 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:58:09 volumio volumio[22846]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:58:09 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:09 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:58:10 volumio sudo[23029]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:58:10 volumio sudo[23029]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:58:10 volumio sudo[23029]: pam_unix(sudo:session): session closed for user root Aug 29 19:58:10 volumio volumio[22846]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:58:10 volumio volumio[22846]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:58:10 volumio volumio[22846]: info: [1756472290079] CoreMusicLibrary::Adding element Last_100 Aug 29 19:58:10 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:58:10 volumio volumio[22846]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:58:10 volumio volumio[22846]: info: [1756472290084] CoreMusicLibrary::Adding element Webradio Aug 29 19:58:10 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:58:10 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:58:10 volumio volumio[22846]: info: Initializing BBC Radios Aug 29 19:58:10 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:58:10 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:58:10 volumio volumio[22846]: info: Loading i18n strings for locale en Aug 29 19:58:10 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 19:58:10 volumio volumio[22846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:58:10 volumio volumio[22846]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 19:58:10 volumio volumio[22846]: info: Volumio Calling Home Aug 29 19:58:10 volumio volumio[22846]: info: Preparing to generate the ALSA configuration file Aug 29 19:58:10 volumio volumio[22846]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 19:58:10 volumio volumio[22846]: info: Reading ALSA contributions from plugins. Aug 29 19:58:10 volumio volumio[22846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:58:10 volumio volumio[22846]: TypeError: Cannot read property 'off' of undefined Aug 29 19:58:10 volumio volumio[22846]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Aug 29 19:58:10 volumio volumio[22846]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 29 19:58:10 volumio volumio[22846]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Aug 29 19:58:10 volumio volumio[22846]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Aug 29 19:58:10 volumio volumio[22846]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Aug 29 19:58:10 volumio volumio[22846]: at Socket.emit (events.js:315:20) Aug 29 19:58:10 volumio volumio[22846]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 29 19:58:10 volumio volumio[22846]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 29 19:58:10 volumio volumio[22846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:58:11 volumio sudo[23061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-29 19:57 Aug 29 19:58:11 volumio sudo[23061]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"