-- Logs begin at Thu 2024-08-29 21:40:11 CEST, end at Thu 2024-08-29 21:51:18 CEST. -- Aug 29 21:50:00 volumio volumio[12801]: info: Loading plugin "alarm-clock"... Aug 29 21:50:00 volumio volumio[12801]: info: Loading plugin "airplay_emulation"... Aug 29 21:50:00 volumio volumio[12801]: info: Starting Shairport Sync Aug 29 21:50:00 volumio volumio[12801]: info: Loading plugin "last_100"... Aug 29 21:50:00 volumio volumio[12801]: info: Loading plugin "webradio"... Aug 29 21:50:00 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:00 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 156. Aug 29 21:50:00 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:00 volumio volumio[12801]: info: Loading plugin "i2s_dacs"... Aug 29 21:50:00 volumio volumio[12801]: info: Loading plugin "volumiodiscovery"... Aug 29 21:50:00 volumio volumio[12801]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:00 volumio volumio[12801]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:00 volumio volumio[12801]: *** WARNING *** For more information see Aug 29 21:50:00 volumio volumio[12801]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:00 volumio volumio[12801]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:00 volumio volumio[12801]: *** WARNING *** For more information see Aug 29 21:50:00 volumio volumio[12801]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 21:50:00 volumio volumio[12801]: info: Discovery: Started advertising with name: Volumio Aug 29 21:50:00 volumio node[12801]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:00 volumio node[12801]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:00 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:00 volumio node[12801]: *** WARNING *** For more information see Aug 29 21:50:00 volumio node[12801]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:00 volumio node[12801]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:00 volumio node[12801]: *** WARNING *** For more information see Aug 29 21:50:00 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:00 volumio volumio[12801]: info: Loading plugin "spop"... Aug 29 21:50:01 volumio volumio[12849]: Traceback (most recent call last): Aug 29 21:50:01 volumio volumio[12849]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:01 volumio volumio[12849]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:01 volumio volumio[12849]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:01 volumio volumio[12801]: info: Loading plugin "ytcr"... Aug 29 21:50:01 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:01 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:01 volumio volumio-remote-updater[647]: [2024-08-29 21:50:01] [connect] Successful connection Aug 29 21:50:02 volumio volumio[12801]: info: Loading plugin "ytmusic"... Aug 29 21:50:02 volumio sudo[12834]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:03 volumio volumio[12801]: info: Loading plugin "now_playing"... Aug 29 21:50:03 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 157. Aug 29 21:50:03 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:03 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:04 volumio volumio[12801]: info: Loading plugin "outputs"... Aug 29 21:50:04 volumio volumio[12801]: info: Loading plugin "albumart"... Aug 29 21:50:04 volumio volumio[12801]: info: Plugin example_plugin is not enabled Aug 29 21:50:04 volumio volumio[12801]: info: Loading plugin "inputs"... Aug 29 21:50:04 volumio volumio[12801]: info: Loading plugin "updater_comm"... Aug 29 21:50:04 volumio volumio[12801]: info: Plugin mpdemulation is not enabled Aug 29 21:50:04 volumio volumio[12801]: info: Loading plugin "rest_api"... Aug 29 21:50:04 volumio volumio[12801]: info: Loading plugin "websocket"... Aug 29 21:50:04 volumio volumio[12852]: Traceback (most recent call last): Aug 29 21:50:04 volumio volumio[12852]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:04 volumio volumio[12852]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:04 volumio volumio[12852]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:04 volumio volumio[12801]: info: Starting Socket.io Server version 2.3.0 Aug 29 21:50:04 volumio volumio[12801]: info: Loading plugin "fusiondsp"... Aug 29 21:50:04 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:04 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:04 volumio volumio[12801]: Forking 3 albumart workers Aug 29 21:50:04 volumio volumio[12801]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 21:50:04 volumio volumio[12801]: info: Loading plugin "minidlna"... Aug 29 21:50:04 volumio volumio[12801]: info: Applying required configuration parameters for plugin minidlna Aug 29 21:50:04 volumio volumio[12801]: info: Loading plugin "radio_paradise"... Aug 29 21:50:05 volumio volumio[12801]: info: Applying required configuration parameters for plugin radio_paradise Aug 29 21:50:05 volumio volumio[12801]: info: [1724961005057] [RadioParadise] API delay: 5 Aug 29 21:50:05 volumio volumio[12801]: info: Loading plugin "Systeminfo"... Aug 29 21:50:05 volumio volumio[12801]: Starting albumart workers Aug 29 21:50:05 volumio volumio[12801]: Starting albumart workers Aug 29 21:50:05 volumio volumio[12801]: info: Loading plugin "touch_display"... Aug 29 21:50:05 volumio volumio[12801]: Starting albumart workers Aug 29 21:50:05 volumio volumio[12801]: info: Applying required configuration parameters for plugin touch_display Aug 29 21:50:05 volumio volumio[12801]: info: Loading i18n strings for locale en Aug 29 21:50:05 volumio volumio[12801]: Updating browse sources language Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::initPlayerControls Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:05 volumio volumio[12801]: Express server listening on port 3000 Aug 29 21:50:05 volumio volumio[12801]: [Metrics] WebUI: 8s 200.23ms Aug 29 21:50:05 volumio volumio[12801]: info: CoreStateMachine::resetVolumioState Aug 29 21:50:05 volumio volumio[12801]: info: CoreStateMachine::getcurrentVolume Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:50:05 volumio volumio[12801]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 21:50:05 volumio volumio[12801]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 21:50:05 volumio volumio-remote-updater[647]: [2024-08-29 21:50:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724961001 101 Aug 29 21:50:05 volumio volumio[12801]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:50:05 volumio volumio[12801]: info: Discovery: Getting this device information Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:05 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:05 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:50:06 volumio volumio[12801]: 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 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:06 volumio volumio[12801]: info: Reloading queue from file Aug 29 21:50:06 volumio volumio[12801]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:50:06 volumio volumio[12801]: info: CoreStateMachine::pushState Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:06 volumio volumio[12801]: info: CoreStateMachine::updateTrackBlock Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrackBlock Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:50:06 volumio volumio[12801]: info: CoreStateMachine::setRepeat null single undefined Aug 29 21:50:06 volumio volumio[12801]: info: CoreStateMachine::pushState Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:06 volumio volumio[12801]: info: CoreStateMachine::setRandom null Aug 29 21:50:06 volumio volumio[12801]: info: CoreStateMachine::pushState Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:06 volumio volumio[12801]: info: Setting Device type: Raspberry PI Aug 29 21:50:06 volumio volumio[12801]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 21:50:06 volumio volumio[12801]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Aug 29 21:50:06 volumio volumio[12801]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 21:50:06 volumio volumio[12801]: info: Completed loading Core Plugins Aug 29 21:50:06 volumio volumio[12801]: info: Preparing to generate the ALSA configuration file Aug 29 21:50:06 volumio volumio[12801]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:50:06 volumio volumio[12801]: info: Reading ALSA contributions from plugins. Aug 29 21:50:06 volumio volumio[12801]: verbose: New Socket.io Connection to 192.168.0.12:3000 from 192.168.0.8 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:50:06 volumio volumio[12801]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 29 21:50:06 volumio volumio[12801]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:50:06 volumio volumio[12801]: info: CoreStateMachine::pushState Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:06 volumio volumio[12801]: info: Discovery: adding a69d09fe-a052-4dd2-a613-4db4de2e2bb3 Aug 29 21:50:06 volumio volumio[12801]: info: Discovery: Found device Volumio Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 21:50:06 volumio volumio[12801]: info: Received Get System Info Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:50:06 volumio volumio[12801]: info: Discovery: Getting this device information Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:06 volumio volumio[12801]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:06 volumio volumio[12801]: info: Listing playlists Aug 29 21:50:06 volumio volumio[12801]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:50:06 volumio volumio[12801]: info: Output device has changed, restarting MPD Aug 29 21:50:06 volumio sudo[12918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:50:06 volumio sudo[12918]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:06 volumio sudo[12918]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:06 volumio volumio[12801]: info: Output device has changed, restarting Shairport Sync Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:06 volumio sudo[12921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:50:06 volumio sudo[12921]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:06 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:06 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 158. Aug 29 21:50:06 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:06 volumio volumio[12801]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:06 volumio volumio[12801]: info: ___________ START PLUGINS ___________ Aug 29 21:50:06 volumio volumio[12801]: info: ControllerMpd::onStart: Initializing MPD Aug 29 21:50:06 volumio volumio[12801]: info: Creating MPD Configuration file Aug 29 21:50:06 volumio sudo[12928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:50:06 volumio sudo[12928]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:06 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:06 volumio sudo[12928]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:06 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:06 volumio sudo[12931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:06 volumio sudo[12931]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:06 volumio volumio[12801]: info: [1724961006639] CoreMusicLibrary::Adding element Media Servers Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:06 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:50:06 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:50:06 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:50:06 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 21:50:06 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:50:06 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:06 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:50:06 volumio volumio[12801]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:06 volumio sudo[12934]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:50:06 volumio sudo[12934]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:06 volumio sudo[12934]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:06 volumio volumio[12801]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:06 volumio volumio[12801]: info: [1724961006796] CoreMusicLibrary::Adding element Last_100 Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:06 volumio volumio[12801]: info: [1724961006799] CoreMusicLibrary::Adding element Webradio Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:06 volumio volumio[12801]: info: Initializing BBC Radios Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:06 volumio volumio[12801]: info: Creating Spotify config file Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:06 volumio volumio[12801]: info: [1724961006992] CoreMusicLibrary::Adding element YouTube Music Aug 29 21:50:06 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:06 volumio volumio[12801]: Cannot find translation for source YouTube Music Aug 29 21:50:07 volumio volumio[12801]: info: [now-playing] ConfigUpdater: config is up to date. Aug 29 21:50:07 volumio volumio[12801]: info: Loading i18n strings for locale en Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:07 volumio volumio[12801]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 21:50:07 volumio volumio[12801]: info: Loading i18n strings for locale en Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:07 volumio volumio[12801]: info: [1724961007088] CoreMusicLibrary::Adding element Radio Paradise Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:07 volumio volumio[12801]: Cannot find translation for source YouTube Music Aug 29 21:50:07 volumio volumio[12801]: Cannot find translation for source Radio Paradise Aug 29 21:50:07 volumio volumio[12801]: info: Loading i18n strings for locale en Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:07 volumio volumio[12801]: info: Volumio Calling Home Aug 29 21:50:07 volumio volumio[12801]: info: Preparing to generate the ALSA configuration file Aug 29 21:50:07 volumio sudo[12959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 21:50:07 volumio sudo[12959]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:07 volumio systemd[1]: Reloading. Aug 29 21:50:07 volumio volumio[12801]: info: [now-playing] Fetching API key... Aug 29 21:50:07 volumio volumio[12801]: info: [now-playing] App is listening on port 4004. Aug 29 21:50:07 volumio volumio[12801]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 29 21:50:07 volumio volumio[12801]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:50:07 volumio volumio[12801]: info: Reading ALSA contributions from plugins. Aug 29 21:50:07 volumio volumio[12924]: Traceback (most recent call last): Aug 29 21:50:07 volumio volumio[12924]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:07 volumio volumio[12924]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:07 volumio volumio[12924]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:07 volumio volumio[12801]: info: minidlna: Found miniDLNA version 1.2.1 Aug 29 21:50:07 volumio volumio[12801]: info: minidlna: Starting minidlna.service Aug 29 21:50:07 volumio volumio[12801]: info: MPD Permissions set Aug 29 21:50:07 volumio volumio[12801]: info: MPD Permissions set Aug 29 21:50:07 volumio sudo[12975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 29 21:50:07 volumio sudo[12975]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:07 volumio volumio[12801]: info: Volumio called home Aug 29 21:50:07 volumio volumio[12801]: info: Spotify config file written Aug 29 21:50:07 volumio sudo[12978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 21:50:07 volumio sudo[12978]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:07 volumio volumio[12801]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:07 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 21:50:07 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:08 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:08 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:08 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:08 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:08 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:08 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:08 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:08 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:08 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:08 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:08 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:08 volumio mpd[12942]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:50:08 volumio mpd[12942]: output: No 'audio_output' defined in config file Aug 29 21:50:08 volumio mpd[12942]: output: Successfully detected a sndio audio device Aug 29 21:50:08 volumio mpd[12942]: zeroconf: No global port, disabling zeroconf Aug 29 21:50:08 volumio volumio[12801]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 21:50:08 volumio systemd[1]: Started Music Player Daemon. Aug 29 21:50:08 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:08 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:08 volumio sudo[12931]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:08 volumio sudo[12959]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:08 volumio sudo[12921]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:08 volumio systemd[1]: Stopping go-librespot Daemon... Aug 29 21:50:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Aug 29 21:50:08 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Aug 29 21:50:08 volumio systemd[1]: Stopped go-librespot Daemon. Aug 29 21:50:08 volumio sudo[12975]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:08 volumio systemd[1]: Started go-librespot Daemon. Aug 29 21:50:08 volumio go-librespot[12990]: Librespot-go daemon starting... Aug 29 21:50:08 volumio sudo[12978]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:08 volumio go-librespot[12990]: time="2024-08-29T21:50:08+02:00" level=info msg="generated new device id: b76439b44532116f5719612c3fcb43971c83be71" Aug 29 21:50:08 volumio go-librespot[12990]: time="2024-08-29T21:50:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 21:50:08 volumio go-librespot[12990]: time="2024-08-29T21:50:08+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 21:50:08 volumio go-librespot[12990]: time="2024-08-29T21:50:08+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 29 21:50:08 volumio go-librespot[12990]: time="2024-08-29T21:50:08+02:00" level=debug msg="zeroconf server listening on port 44241" Aug 29 21:50:09 volumio volumio[12801]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 21:50:09 volumio volumio[12801]: 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 21:50:09 volumio volumio[12801]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 21:50:09 volumio volumio[12801]: 0+0 records in Aug 29 21:50:09 volumio volumio[12801]: 0+0 records out Aug 29 21:50:09 volumio volumio[12801]: 0 bytes copied, 0.000132869 s, 0.0 kB/s Aug 29 21:50:09 volumio volumio[12801]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 21:50:09 volumio volumio[12801]: info: camilladsp service started and running in background, instance 1 Aug 29 21:50:09 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:09 volumio volumio[12801]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 21:50:09 volumio volumio[12801]: 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 21:50:09 volumio volumio[12801]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 21:50:09 volumio volumio[12801]: info: FusionDsp loaded Aug 29 21:50:09 volumio volumio[12801]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:50:09 volumio sudo[13010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 21:50:09 volumio volumio[12801]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 21:50:09 volumio sudo[13010]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:09 volumio volumio[12801]: info: Adding Signal Path Element [object Object] Aug 29 21:50:09 volumio volumio[12801]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 21:50:09 volumio volumio[12801]: info: FusionDsp - ---- installed callbackRead Aug 29 21:50:09 volumio volumio[12801]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:50:09 volumio volumio[12801]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 21:50:09 volumio volumio[12801]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 21:50:09 volumio volumio[12801]: at onErrorNT (internal/child_process.js:465:16) Aug 29 21:50:09 volumio volumio[12801]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 29 21:50:09 volumio volumio[12801]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 29 21:50:09 volumio volumio[12801]: at listOnTimeout (internal/timers.js:523:9) Aug 29 21:50:09 volumio volumio[12801]: at processTimers (internal/timers.js:497:7) { Aug 29 21:50:09 volumio volumio[12801]: errno: -2, Aug 29 21:50:09 volumio volumio[12801]: code: 'ENOENT', Aug 29 21:50:09 volumio volumio[12801]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 21:50:09 volumio volumio[12801]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 21:50:09 volumio volumio[12801]: spawnargs: [ Aug 29 21:50:09 volumio volumio[12801]: '-p', Aug 29 21:50:09 volumio volumio[12801]: 9876, Aug 29 21:50:09 volumio volumio[12801]: '-o', Aug 29 21:50:09 volumio volumio[12801]: '/tmp/camilladsp.log', Aug 29 21:50:09 volumio volumio[12801]: '-l', Aug 29 21:50:09 volumio volumio[12801]: 'warn', Aug 29 21:50:09 volumio volumio[12801]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 21:50:09 volumio volumio[12801]: ] Aug 29 21:50:09 volumio volumio[12801]: } Aug 29 21:50:09 volumio volumio[12801]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:50:09 volumio sudo[13021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 21:49 Aug 29 21:50:09 volumio sudo[13021]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:09 volumio sudo[13021]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:09 volumio volumio-remote-updater[647]: [2024-08-29 21:50:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 21:50:09 volumio volumio-remote-updater[647]: [2024-08-29 21:50:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 21:50:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:09 volumio systemd[984]: media-USB_STICK.mount: Succeeded. Aug 29 21:50:09 volumio ntfs-3g[12848]: Unmounting /dev/sda1 (USB STICK) Aug 29 21:50:09 volumio systemd[1]: media-USB_STICK.mount: Succeeded. Aug 29 21:50:09 volumio sudo[13010]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 21:50:09 volumio systemd[1]: Started dynamicswap service. Aug 29 21:50:09 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 21:50:10 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 21:50:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 39. Aug 29 21:50:10 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 21:50:10 volumio systemd[1]: Started Volumio Backend Module. Aug 29 21:50:10 volumio systemd[1]: Started dynamicswap service. Aug 29 21:50:10 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 21:50:10 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:10 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 159. Aug 29 21:50:10 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:10 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:11 volumio volumio[13046]: Traceback (most recent call last): Aug 29 21:50:11 volumio volumio[13046]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:11 volumio volumio[13046]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:11 volumio volumio[13046]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:11 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:11 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:11 volumio volumio[13033]: info: ------------------------------------------- Aug 29 21:50:11 volumio volumio[13033]: info: ----- Volumio3 ---- Aug 29 21:50:11 volumio volumio[13033]: info: ------------------------------------------- Aug 29 21:50:11 volumio volumio[13033]: info: ----- System startup ---- Aug 29 21:50:11 volumio volumio[13033]: info: ------------------------------------------- Aug 29 21:50:12 volumio volumio[13033]: info: MYVOLUMIO Environment detected Aug 29 21:50:12 volumio volumio[13033]: info: Plugin folders cleanup Aug 29 21:50:12 volumio volumio[13033]: info: Scanning into folder /volumio/app/plugins/ Aug 29 21:50:12 volumio volumio[13033]: info: Scanning category audio_interface Aug 29 21:50:12 volumio volumio[13033]: info: Scanning category miscellanea Aug 29 21:50:12 volumio volumio[13033]: info: Scanning category music_service Aug 29 21:50:12 volumio volumio[13033]: info: Scanning category plugins.json Aug 29 21:50:12 volumio volumio[13033]: info: Scanning category system_controller Aug 29 21:50:12 volumio volumio[13033]: info: Scanning category user_interface Aug 29 21:50:12 volumio volumio[13033]: info: Scanning into folder /data/plugins/ Aug 29 21:50:12 volumio volumio[13033]: info: Scanning category audio_interface Aug 29 21:50:12 volumio volumio[13033]: info: Scanning category music_service Aug 29 21:50:12 volumio volumio[13033]: info: Scanning category user_interface Aug 29 21:50:12 volumio volumio[13033]: info: Plugin folders cleanup completed Aug 29 21:50:12 volumio volumio[13033]: info: ------------------------------------------- Aug 29 21:50:12 volumio volumio[13033]: info: ----- Core plugins startup ---- Aug 29 21:50:12 volumio volumio[13033]: info: ------------------------------------------- Aug 29 21:50:12 volumio volumio[13033]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 21:50:12 volumio volumio[13033]: info: Adding plugin upnp to MyMusic Plugins Aug 29 21:50:12 volumio volumio[13033]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 21:50:12 volumio volumio[13033]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 21:50:12 volumio volumio[13033]: info: Loading plugins from folder /data/plugins/ Aug 29 21:50:12 volumio volumio[13033]: info: Loading plugin "system"... Aug 29 21:50:12 volumio volumio[13033]: info: Loading plugin "appearance"... Aug 29 21:50:12 volumio volumio[13033]: info: Loading plugin "network"... Aug 29 21:50:12 volumio volumio[13033]: info: Refreshing Cached IP Addresses Aug 29 21:50:12 volumio sudo[13061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 21:50:12 volumio sudo[13061]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:12 volumio sudo[13061]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:12 volumio sudo[13063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:50:12 volumio sudo[13063]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:12 volumio volumio[13033]: info: Loading plugin "services"... Aug 29 21:50:12 volumio volumio[13033]: info: Loading plugin "alsa_controller"... Aug 29 21:50:12 volumio sudo[13063]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:12 volumio sudo[13066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 21:50:12 volumio sudo[13066]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:12 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:12 volumio volumio[13033]: info: Loading plugin "wizard"... Aug 29 21:50:12 volumio volumio[13033]: info: Loading plugin "networkfs"... Aug 29 21:50:12 volumio volumio[13033]: info: Starting Udev Watcher for removable devices Aug 29 21:50:12 volumio volumio[13033]: info: Ignoring mount for partition: boot Aug 29 21:50:12 volumio volumio[13033]: info: Ignoring mount for partition: volumio Aug 29 21:50:12 volumio volumio[13033]: info: Ignoring mount for partition: volumio_data Aug 29 21:50:12 volumio volumio[13033]: info: Mounting Device USB_STICK Aug 29 21:50:12 volumio sudo[13077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/USB_STICK -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 21:50:12 volumio sudo[13077]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:13 volumio ntfs-3g[13080]: Version 2017.3.23AR.3 integrated FUSE 28 Aug 29 21:50:13 volumio ntfs-3g[13080]: Mounted /dev/sda1 (Read-Write, label "USB STICK", NTFS 3.1) Aug 29 21:50:13 volumio ntfs-3g[13080]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 21:50:13 volumio ntfs-3g[13080]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Aug 29 21:50:13 volumio ntfs-3g[13080]: Global ownership and permissions enforced, configuration type 7 Aug 29 21:50:13 volumio sudo[13077]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:13 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "volumio_command_line_client"... Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "upnp"... Aug 29 21:50:13 volumio volumio[13033]: info: [1724961013104] Starting Upmpd Daemon Aug 29 21:50:13 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "my_music"... Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "mpd"... Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "upnp_browser"... Aug 29 21:50:13 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:13 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 160. Aug 29 21:50:13 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:13 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "alarm-clock"... Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "airplay_emulation"... Aug 29 21:50:13 volumio volumio[13033]: info: Starting Shairport Sync Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "last_100"... Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "webradio"... Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "i2s_dacs"... Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "volumiodiscovery"... Aug 29 21:50:13 volumio volumio[13033]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:13 volumio volumio[13033]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:13 volumio node[13033]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:13 volumio volumio[13033]: *** WARNING *** For more information see Aug 29 21:50:13 volumio volumio[13033]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:13 volumio volumio[13033]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:13 volumio volumio[13033]: *** WARNING *** For more information see Aug 29 21:50:13 volumio node[13033]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:13 volumio node[13033]: *** WARNING *** For more information see Aug 29 21:50:13 volumio node[13033]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:13 volumio node[13033]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:13 volumio node[13033]: *** WARNING *** For more information see Aug 29 21:50:13 volumio volumio[13033]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 21:50:13 volumio volumio[13033]: info: Discovery: Started advertising with name: Volumio Aug 29 21:50:13 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:13 volumio volumio[13033]: info: Loading plugin "spop"... Aug 29 21:50:14 volumio volumio[13082]: Traceback (most recent call last): Aug 29 21:50:14 volumio volumio[13082]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:14 volumio volumio[13082]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:14 volumio volumio[13082]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:14 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:14 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:14 volumio volumio[13033]: info: Loading plugin "ytcr"... Aug 29 21:50:14 volumio volumio-remote-updater[647]: [2024-08-29 21:50:14] [connect] Successful connection Aug 29 21:50:16 volumio volumio[13033]: info: Loading plugin "ytmusic"... Aug 29 21:50:16 volumio sudo[13066]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:16 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:16 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 161. Aug 29 21:50:16 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:16 volumio volumio[13033]: info: Loading plugin "now_playing"... Aug 29 21:50:16 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:17 volumio volumio[13085]: Traceback (most recent call last): Aug 29 21:50:17 volumio volumio[13085]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:17 volumio volumio[13085]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:17 volumio volumio[13085]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:17 volumio volumio[13033]: info: Loading plugin "outputs"... Aug 29 21:50:17 volumio volumio[13033]: info: Loading plugin "albumart"... Aug 29 21:50:17 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:17 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:17 volumio volumio[13033]: info: Plugin example_plugin is not enabled Aug 29 21:50:17 volumio volumio[13033]: info: Loading plugin "inputs"... Aug 29 21:50:17 volumio volumio[13033]: info: Loading plugin "updater_comm"... Aug 29 21:50:17 volumio volumio[13033]: info: Plugin mpdemulation is not enabled Aug 29 21:50:17 volumio volumio[13033]: info: Loading plugin "rest_api"... Aug 29 21:50:17 volumio volumio[13033]: info: Loading plugin "websocket"... Aug 29 21:50:17 volumio volumio[13033]: info: Starting Socket.io Server version 2.3.0 Aug 29 21:50:17 volumio volumio[13033]: info: Loading plugin "fusiondsp"... Aug 29 21:50:17 volumio volumio[13033]: Forking 3 albumart workers Aug 29 21:50:18 volumio volumio[13033]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 21:50:18 volumio volumio[13033]: info: Loading plugin "minidlna"... Aug 29 21:50:18 volumio volumio[13033]: info: Applying required configuration parameters for plugin minidlna Aug 29 21:50:18 volumio volumio[13033]: info: Loading plugin "radio_paradise"... Aug 29 21:50:18 volumio volumio[13033]: info: Applying required configuration parameters for plugin radio_paradise Aug 29 21:50:18 volumio volumio[13033]: info: [1724961018456] [RadioParadise] API delay: 5 Aug 29 21:50:18 volumio volumio[13033]: info: Loading plugin "Systeminfo"... Aug 29 21:50:18 volumio volumio[13033]: Starting albumart workers Aug 29 21:50:18 volumio volumio[13033]: info: Loading plugin "touch_display"... Aug 29 21:50:18 volumio volumio[13033]: Starting albumart workers Aug 29 21:50:18 volumio volumio[13033]: Starting albumart workers Aug 29 21:50:18 volumio volumio[13033]: info: Applying required configuration parameters for plugin touch_display Aug 29 21:50:19 volumio volumio[13033]: info: Loading i18n strings for locale en Aug 29 21:50:19 volumio volumio[13033]: Updating browse sources language Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::initPlayerControls Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: Express server listening on port 3000 Aug 29 21:50:19 volumio volumio[13033]: [Metrics] WebUI: 8s 90.85ms Aug 29 21:50:19 volumio volumio[13033]: info: CoreStateMachine::resetVolumioState Aug 29 21:50:19 volumio volumio[13033]: info: CoreStateMachine::getcurrentVolume Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:50:19 volumio volumio[13033]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 21:50:19 volumio volumio[13033]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 21:50:19 volumio volumio-remote-updater[647]: [2024-08-29 21:50:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724961014 101 Aug 29 21:50:19 volumio volumio[13033]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Aug 29 21:50:19 volumio volumio[13033]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:50:19 volumio volumio[13033]: info: Discovery: Getting this device information Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:19 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:50:19 volumio volumio[13033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: info: Reloading queue from file Aug 29 21:50:19 volumio volumio[13033]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:50:19 volumio volumio[13033]: info: CoreStateMachine::pushState Aug 29 21:50:19 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:19 volumio volumio[13033]: info: CoreStateMachine::updateTrackBlock Aug 29 21:50:19 volumio volumio[13033]: info: CorePlayQueue::getTrackBlock Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:50:19 volumio volumio[13033]: info: CoreStateMachine::setRepeat null single undefined Aug 29 21:50:19 volumio volumio[13033]: info: CoreStateMachine::pushState Aug 29 21:50:19 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:19 volumio volumio[13033]: info: CoreStateMachine::setRandom null Aug 29 21:50:19 volumio volumio[13033]: info: CoreStateMachine::pushState Aug 29 21:50:19 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:19 volumio volumio[13033]: info: Setting Device type: Raspberry PI Aug 29 21:50:19 volumio volumio[13033]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 21:50:19 volumio volumio[13033]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Aug 29 21:50:19 volumio volumio[13033]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 21:50:19 volumio volumio[13033]: info: Completed loading Core Plugins Aug 29 21:50:19 volumio volumio[13033]: info: Preparing to generate the ALSA configuration file Aug 29 21:50:19 volumio volumio[13033]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:50:19 volumio volumio[13033]: info: Reading ALSA contributions from plugins. Aug 29 21:50:19 volumio volumio[13033]: verbose: New Socket.io Connection to 192.168.0.12:3000 from 192.168.0.8 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:19 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:50:19 volumio volumio[13033]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:50:19 volumio volumio[13033]: info: CoreStateMachine::pushState Aug 29 21:50:19 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:19 volumio volumio[13033]: info: Discovery: adding a69d09fe-a052-4dd2-a613-4db4de2e2bb3 Aug 29 21:50:19 volumio volumio[13033]: info: Discovery: Found device Volumio Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:19 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:19 volumio volumio[13033]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:50:19 volumio volumio[13033]: info: Output device has changed, restarting MPD Aug 29 21:50:19 volumio sudo[13151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:50:19 volumio sudo[13151]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:19 volumio sudo[13151]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:19 volumio volumio[13033]: info: Output device has changed, restarting Shairport Sync Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:19 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:19 volumio sudo[13154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:50:19 volumio sudo[13154]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:19 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:19 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 162. Aug 29 21:50:19 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:19 volumio volumio[13033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:19 volumio volumio[13033]: info: ___________ START PLUGINS ___________ Aug 29 21:50:19 volumio volumio[13033]: info: ControllerMpd::onStart: Initializing MPD Aug 29 21:50:19 volumio volumio[13033]: info: Creating MPD Configuration file Aug 29 21:50:19 volumio sudo[13161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:50:19 volumio sudo[13161]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:19 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:20 volumio sudo[13161]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:20 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 21:50:20 volumio sudo[13164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:50:20 volumio sudo[13164]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:20 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:20 volumio volumio[13033]: info: [1724961020023] CoreMusicLibrary::Adding element Media Servers Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:20 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:50:20 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:50:20 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 21:50:20 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:50:20 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:20 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:20 volumio volumio[13033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:20 volumio sudo[13167]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:50:20 volumio sudo[13167]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:20 volumio sudo[13167]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:20 volumio volumio[13033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:20 volumio volumio[13033]: info: [1724961020180] CoreMusicLibrary::Adding element Last_100 Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:20 volumio volumio[13033]: info: [1724961020183] CoreMusicLibrary::Adding element Webradio Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:20 volumio volumio[13033]: info: Initializing BBC Radios Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:20 volumio volumio[13033]: info: Creating Spotify config file Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:20 volumio volumio[13033]: info: [1724961020372] CoreMusicLibrary::Adding element YouTube Music Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:20 volumio volumio[13033]: Cannot find translation for source YouTube Music Aug 29 21:50:20 volumio volumio[13033]: info: [now-playing] ConfigUpdater: config is up to date. Aug 29 21:50:20 volumio volumio[13033]: info: Loading i18n strings for locale en Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:20 volumio volumio[13033]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 21:50:20 volumio volumio[13033]: info: Loading i18n strings for locale en Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:20 volumio volumio[13033]: info: [1724961020468] CoreMusicLibrary::Adding element Radio Paradise Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:20 volumio volumio[13033]: Cannot find translation for source YouTube Music Aug 29 21:50:20 volumio volumio[13033]: Cannot find translation for source Radio Paradise Aug 29 21:50:20 volumio volumio[13033]: info: Loading i18n strings for locale en Aug 29 21:50:20 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:20 volumio volumio[13033]: info: Volumio Calling Home Aug 29 21:50:20 volumio volumio[13033]: info: Preparing to generate the ALSA configuration file Aug 29 21:50:20 volumio sudo[13192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 21:50:20 volumio sudo[13192]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:20 volumio systemd[1]: Reloading. Aug 29 21:50:20 volumio volumio[13033]: info: [now-playing] Fetching API key... Aug 29 21:50:20 volumio volumio[13033]: info: [now-playing] App is listening on port 4004. Aug 29 21:50:20 volumio volumio[13033]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 29 21:50:20 volumio volumio[13033]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:50:20 volumio volumio[13033]: info: Reading ALSA contributions from plugins. Aug 29 21:50:20 volumio volumio[13033]: info: minidlna: Found miniDLNA version 1.2.1 Aug 29 21:50:20 volumio volumio[13033]: info: minidlna: Starting minidlna.service Aug 29 21:50:20 volumio volumio[13033]: info: MPD Permissions set Aug 29 21:50:20 volumio volumio[13033]: info: MPD Permissions set Aug 29 21:50:21 volumio sudo[13202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 29 21:50:21 volumio sudo[13202]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:21 volumio volumio[13033]: info: Volumio called home Aug 29 21:50:21 volumio volumio[13159]: Traceback (most recent call last): Aug 29 21:50:21 volumio volumio[13159]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:21 volumio volumio[13159]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:21 volumio volumio[13159]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:21 volumio volumio[13033]: info: Spotify config file written Aug 29 21:50:21 volumio sudo[13211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 21:50:21 volumio sudo[13211]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:21 volumio volumio[13033]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 21:50:21 volumio mpd[13175]: Aug 29 21:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:50:21 volumio volumio[13033]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 29 21:50:21 volumio volumio[13033]: info: Starting Shairport Sync Aug 29 21:50:21 volumio volumio[13033]: info: Starting Shairport Sync Aug 29 21:50:21 volumio sudo[13224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:50:21 volumio sudo[13224]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:21 volumio volumio[13033]: info: Starting Shairport Sync Aug 29 21:50:21 volumio sudo[13227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:50:21 volumio sudo[13227]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:21 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:21 volumio sudo[13230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:50:21 volumio sudo[13230]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:21 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:21 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 21:50:21 volumio volumio[13033]: info: Received Get System Info Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:50:21 volumio volumio[13033]: info: Discovery: Getting this device information Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:21 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:21 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:21 volumio volumio[13033]: info: Listing playlists Aug 29 21:50:21 volumio systemd[1]: Started Music Player Daemon. Aug 29 21:50:21 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:21 volumio sudo[13154]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:21 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:21 volumio sudo[13164]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:21 volumio sudo[13192]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:21 volumio volumio[13033]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:50:21 volumio volumio[13033]: info: Output device has changed, restarting MPD Aug 29 21:50:21 volumio volumio[13033]: info: Output device has changed, restarting Shairport Sync Aug 29 21:50:21 volumio sudo[13233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:50:21 volumio sudo[13233]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:21 volumio sudo[13202]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:21 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:21 volumio systemd[1]: Stopping go-librespot Daemon... Aug 29 21:50:21 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 21:50:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 21:50:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Aug 29 21:50:21 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Aug 29 21:50:21 volumio systemd[1]: Stopped go-librespot Daemon. Aug 29 21:50:21 volumio sudo[13235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:50:21 volumio sudo[13235]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:21 volumio volumio[13033]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:21 volumio sudo[13233]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:21 volumio systemd[1]: Started go-librespot Daemon. Aug 29 21:50:21 volumio go-librespot[13237]: Librespot-go daemon starting... Aug 29 21:50:21 volumio sudo[13211]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 21:50:21 volumio sudo[13224]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:21 volumio sudo[13227]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:21 volumio sudo[13230]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:21 volumio volumio[13033]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 21:50:21 volumio go-librespot[13237]: time="2024-08-29T21:50:21+02:00" level=info msg="generated new device id: 94cb0f95f10ad524646d47239ec999e04a666533" Aug 29 21:50:21 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 21:50:21 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:50:21 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:50:21 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:50:21 volumio sudo[13250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 21:50:21 volumio sudo[13250]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:22 volumio sudo[13250]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:22 volumio sudo[13252]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:50:22 volumio sudo[13252]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:22 volumio volumio[13033]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 21:50:22 volumio volumio[13033]: info: touch_display: Volumio Kiosk started Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:22 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:22 volumio sudo[13252]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:22 volumio volumio[13033]: info: MPD Permissions set Aug 29 21:50:22 volumio volumio[13033]: info: Shairport-Sync Started Aug 29 21:50:22 volumio volumio[13033]: Error adding Membership: Error: addMembership EINVAL Aug 29 21:50:22 volumio volumio[13033]: info: Shairport-Sync Started Aug 29 21:50:22 volumio volumio[13033]: info: Shairport-Sync Started Aug 29 21:50:22 volumio volumio[13033]: info: minidlna: systemctl start minidlna.service succeeded. Aug 29 21:50:22 volumio go-librespot[13237]: time="2024-08-29T21:50:22+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 21:50:22 volumio go-librespot[13237]: time="2024-08-29T21:50:22+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 21:50:22 volumio go-librespot[13237]: time="2024-08-29T21:50:22+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 29 21:50:22 volumio go-librespot[13237]: time="2024-08-29T21:50:22+02:00" level=debug msg="zeroconf server listening on port 41235" Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:22 volumio volumio[13033]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:22 volumio volumio[13033]: error: Cannot initialize MPD Connection: MPD is not running Aug 29 21:50:22 volumio volumio[13033]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:22 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:22 volumio mpd[13261]: Aug 29 21:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:50:22 volumio systemd[1]: Started Music Player Daemon. Aug 29 21:50:22 volumio sudo[13235]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:23 volumio volumio[13033]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 21:50:23 volumio volumio[13033]: 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 21:50:23 volumio volumio[13033]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 21:50:23 volumio volumio[13033]: 0+0 records in Aug 29 21:50:23 volumio volumio[13033]: 0+0 records out Aug 29 21:50:23 volumio volumio[13033]: 0 bytes copied, 0.00010574 s, 0.0 kB/s Aug 29 21:50:23 volumio volumio[13033]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 21:50:23 volumio volumio[13033]: info: camilladsp service started and running in background, instance 1 Aug 29 21:50:23 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:23 volumio volumio[13033]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 21:50:23 volumio volumio[13033]: 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 21:50:23 volumio volumio[13033]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 21:50:23 volumio volumio[13033]: info: FusionDsp loaded Aug 29 21:50:23 volumio volumio[13033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:50:23 volumio sudo[13286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 21:50:23 volumio sudo[13286]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:23 volumio volumio[13033]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 21:50:23 volumio volumio[13033]: info: Adding Signal Path Element [object Object] Aug 29 21:50:23 volumio volumio[13033]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 21:50:23 volumio volumio[13033]: info: FusionDsp - ---- installed callbackRead Aug 29 21:50:23 volumio volumio[13033]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:50:23 volumio volumio[13033]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 21:50:23 volumio volumio[13033]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 21:50:23 volumio volumio[13033]: at onErrorNT (internal/child_process.js:465:16) Aug 29 21:50:23 volumio volumio[13033]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 29 21:50:23 volumio volumio[13033]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 29 21:50:23 volumio volumio[13033]: at listOnTimeout (internal/timers.js:523:9) Aug 29 21:50:23 volumio volumio[13033]: at processTimers (internal/timers.js:497:7) { Aug 29 21:50:23 volumio volumio[13033]: errno: -2, Aug 29 21:50:23 volumio volumio[13033]: code: 'ENOENT', Aug 29 21:50:23 volumio volumio[13033]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 21:50:23 volumio volumio[13033]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 21:50:23 volumio volumio[13033]: spawnargs: [ Aug 29 21:50:23 volumio volumio[13033]: '-p', Aug 29 21:50:23 volumio volumio[13033]: 9876, Aug 29 21:50:23 volumio volumio[13033]: '-o', Aug 29 21:50:23 volumio volumio[13033]: '/tmp/camilladsp.log', Aug 29 21:50:23 volumio volumio[13033]: '-l', Aug 29 21:50:23 volumio volumio[13033]: 'warn', Aug 29 21:50:23 volumio volumio[13033]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 21:50:23 volumio volumio[13033]: ] Aug 29 21:50:23 volumio volumio[13033]: } Aug 29 21:50:23 volumio volumio[13033]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:50:23 volumio sudo[13297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 21:49 Aug 29 21:50:23 volumio sudo[13297]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:23 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:23 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 163. Aug 29 21:50:23 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:23 volumio sudo[13297]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:23 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:23 volumio sudo[13286]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:24 volumio volumio-remote-updater[647]: [2024-08-29 21:50:24] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 21:50:24 volumio volumio-remote-updater[647]: [2024-08-29 21:50:24] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 21:50:24 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:24 volumio ntfs-3g[13080]: Unmounting /dev/sda1 (USB STICK) Aug 29 21:50:24 volumio systemd[984]: media-USB_STICK.mount: Succeeded. Aug 29 21:50:24 volumio systemd[1]: media-USB_STICK.mount: Succeeded. Aug 29 21:50:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 21:50:24 volumio systemd[1]: Started dynamicswap service. Aug 29 21:50:24 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 21:50:24 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 21:50:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 40. Aug 29 21:50:24 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 21:50:24 volumio systemd[1]: Started Volumio Backend Module. Aug 29 21:50:24 volumio systemd[1]: Started dynamicswap service. Aug 29 21:50:24 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 21:50:24 volumio volumio[13299]: Traceback (most recent call last): Aug 29 21:50:24 volumio volumio[13299]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:24 volumio volumio[13299]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:24 volumio volumio[13299]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:24 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:24 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:25 volumio volumio[13310]: info: ------------------------------------------- Aug 29 21:50:25 volumio volumio[13310]: info: ----- Volumio3 ---- Aug 29 21:50:25 volumio volumio[13310]: info: ------------------------------------------- Aug 29 21:50:25 volumio volumio[13310]: info: ----- System startup ---- Aug 29 21:50:25 volumio volumio[13310]: info: ------------------------------------------- Aug 29 21:50:25 volumio volumio[13310]: info: MYVOLUMIO Environment detected Aug 29 21:50:26 volumio volumio[13310]: info: Plugin folders cleanup Aug 29 21:50:26 volumio volumio[13310]: info: Scanning into folder /volumio/app/plugins/ Aug 29 21:50:26 volumio volumio[13310]: info: Scanning category audio_interface Aug 29 21:50:26 volumio volumio[13310]: info: Scanning category miscellanea Aug 29 21:50:26 volumio volumio[13310]: info: Scanning category music_service Aug 29 21:50:26 volumio volumio[13310]: info: Scanning category plugins.json Aug 29 21:50:26 volumio volumio[13310]: info: Scanning category system_controller Aug 29 21:50:26 volumio volumio[13310]: info: Scanning category user_interface Aug 29 21:50:26 volumio volumio[13310]: info: Scanning into folder /data/plugins/ Aug 29 21:50:26 volumio volumio[13310]: info: Scanning category audio_interface Aug 29 21:50:26 volumio volumio[13310]: info: Scanning category music_service Aug 29 21:50:26 volumio volumio[13310]: info: Scanning category user_interface Aug 29 21:50:26 volumio volumio[13310]: info: Plugin folders cleanup completed Aug 29 21:50:26 volumio volumio[13310]: info: ------------------------------------------- Aug 29 21:50:26 volumio volumio[13310]: info: ----- Core plugins startup ---- Aug 29 21:50:26 volumio volumio[13310]: info: ------------------------------------------- Aug 29 21:50:26 volumio volumio[13310]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 21:50:26 volumio volumio[13310]: info: Adding plugin upnp to MyMusic Plugins Aug 29 21:50:26 volumio volumio[13310]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 21:50:26 volumio volumio[13310]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 21:50:26 volumio volumio[13310]: info: Loading plugins from folder /data/plugins/ Aug 29 21:50:26 volumio volumio[13310]: info: Loading plugin "system"... Aug 29 21:50:26 volumio volumio[13310]: info: Loading plugin "appearance"... Aug 29 21:50:26 volumio volumio[13310]: info: Loading plugin "network"... Aug 29 21:50:26 volumio volumio[13310]: info: Refreshing Cached IP Addresses Aug 29 21:50:26 volumio sudo[13337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 21:50:26 volumio sudo[13337]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:26 volumio sudo[13337]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:26 volumio sudo[13339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:50:26 volumio volumio[13310]: info: Loading plugin "services"... Aug 29 21:50:26 volumio sudo[13339]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:26 volumio volumio[13310]: info: Loading plugin "alsa_controller"... Aug 29 21:50:26 volumio sudo[13339]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:26 volumio sudo[13342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 21:50:26 volumio sudo[13342]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:26 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:26 volumio volumio[13310]: info: Loading plugin "wizard"... Aug 29 21:50:26 volumio volumio[13310]: info: Loading plugin "networkfs"... Aug 29 21:50:26 volumio volumio[13310]: info: Starting Udev Watcher for removable devices Aug 29 21:50:26 volumio volumio[13310]: info: Ignoring mount for partition: boot Aug 29 21:50:26 volumio volumio[13310]: info: Ignoring mount for partition: volumio Aug 29 21:50:26 volumio volumio[13310]: info: Ignoring mount for partition: volumio_data Aug 29 21:50:26 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:26 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 164. Aug 29 21:50:26 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:26 volumio volumio[13310]: info: Mounting Device USB_STICK Aug 29 21:50:26 volumio sudo[13354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/USB_STICK -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 21:50:26 volumio sudo[13354]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:26 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:27 volumio ntfs-3g[13357]: Version 2017.3.23AR.3 integrated FUSE 28 Aug 29 21:50:27 volumio ntfs-3g[13357]: Mounted /dev/sda1 (Read-Write, label "USB STICK", NTFS 3.1) Aug 29 21:50:27 volumio ntfs-3g[13357]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 21:50:27 volumio ntfs-3g[13357]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Aug 29 21:50:27 volumio ntfs-3g[13357]: Global ownership and permissions enforced, configuration type 7 Aug 29 21:50:27 volumio sudo[13354]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:27 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "volumio_command_line_client"... Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "upnp"... Aug 29 21:50:27 volumio volumio[13310]: info: [1724961027112] Starting Upmpd Daemon Aug 29 21:50:27 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "my_music"... Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "mpd"... Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "upnp_browser"... Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "alarm-clock"... Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "airplay_emulation"... Aug 29 21:50:27 volumio volumio[13310]: info: Starting Shairport Sync Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "last_100"... Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "webradio"... Aug 29 21:50:27 volumio volumio[13352]: Traceback (most recent call last): Aug 29 21:50:27 volumio volumio[13352]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:27 volumio volumio[13352]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:27 volumio volumio[13352]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "i2s_dacs"... Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "volumiodiscovery"... Aug 29 21:50:27 volumio volumio[13310]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:27 volumio volumio[13310]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:27 volumio node[13310]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:27 volumio volumio[13310]: *** WARNING *** For more information see Aug 29 21:50:27 volumio volumio[13310]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:27 volumio volumio[13310]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:27 volumio volumio[13310]: *** WARNING *** For more information see Aug 29 21:50:27 volumio node[13310]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:27 volumio node[13310]: *** WARNING *** For more information see Aug 29 21:50:27 volumio node[13310]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:27 volumio node[13310]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:27 volumio node[13310]: *** WARNING *** For more information see Aug 29 21:50:27 volumio volumio[13310]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 21:50:27 volumio volumio[13310]: info: Discovery: Started advertising with name: Volumio Aug 29 21:50:27 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:27 volumio volumio[13310]: info: Loading plugin "spop"... Aug 29 21:50:27 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:27 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:28 volumio volumio[13310]: info: Loading plugin "ytcr"... Aug 29 21:50:29 volumio volumio-remote-updater[647]: [2024-08-29 21:50:29] [connect] Successful connection Aug 29 21:50:29 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:29 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 165. Aug 29 21:50:29 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:29 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:30 volumio volumio[13310]: info: Loading plugin "ytmusic"... Aug 29 21:50:30 volumio sudo[13342]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:30 volumio volumio[13360]: Traceback (most recent call last): Aug 29 21:50:30 volumio volumio[13360]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:30 volumio volumio[13360]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:30 volumio volumio[13360]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:30 volumio volumio[13310]: info: Loading plugin "now_playing"... Aug 29 21:50:30 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:30 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:31 volumio volumio[13310]: info: Loading plugin "outputs"... Aug 29 21:50:31 volumio volumio[13310]: info: Loading plugin "albumart"... Aug 29 21:50:31 volumio volumio[13310]: info: Plugin example_plugin is not enabled Aug 29 21:50:31 volumio volumio[13310]: info: Loading plugin "inputs"... Aug 29 21:50:31 volumio volumio[13310]: info: Loading plugin "updater_comm"... Aug 29 21:50:31 volumio volumio[13310]: info: Plugin mpdemulation is not enabled Aug 29 21:50:31 volumio volumio[13310]: info: Loading plugin "rest_api"... Aug 29 21:50:31 volumio volumio[13310]: info: Loading plugin "websocket"... Aug 29 21:50:31 volumio volumio[13310]: info: Starting Socket.io Server version 2.3.0 Aug 29 21:50:31 volumio volumio[13310]: info: Loading plugin "fusiondsp"... Aug 29 21:50:31 volumio volumio[13310]: Forking 3 albumart workers Aug 29 21:50:32 volumio volumio[13310]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 21:50:32 volumio volumio[13310]: info: Loading plugin "minidlna"... Aug 29 21:50:32 volumio volumio[13310]: info: Applying required configuration parameters for plugin minidlna Aug 29 21:50:32 volumio volumio[13310]: info: Loading plugin "radio_paradise"... Aug 29 21:50:32 volumio volumio[13310]: info: Applying required configuration parameters for plugin radio_paradise Aug 29 21:50:32 volumio volumio[13310]: info: [1724961032487] [RadioParadise] API delay: 5 Aug 29 21:50:32 volumio volumio[13310]: info: Loading plugin "Systeminfo"... Aug 29 21:50:32 volumio volumio[13310]: Starting albumart workers Aug 29 21:50:32 volumio volumio[13310]: Starting albumart workers Aug 29 21:50:32 volumio volumio[13310]: Starting albumart workers Aug 29 21:50:32 volumio volumio[13310]: info: Loading plugin "touch_display"... Aug 29 21:50:32 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:32 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 166. Aug 29 21:50:32 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:32 volumio volumio[13310]: info: Applying required configuration parameters for plugin touch_display Aug 29 21:50:33 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:33 volumio volumio[13310]: info: Loading i18n strings for locale en Aug 29 21:50:33 volumio volumio[13310]: Updating browse sources language Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::initPlayerControls Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:33 volumio volumio[13310]: Express server listening on port 3000 Aug 29 21:50:33 volumio volumio[13310]: [Metrics] WebUI: 8s 220.39ms Aug 29 21:50:33 volumio volumio[13310]: info: CoreStateMachine::resetVolumioState Aug 29 21:50:33 volumio volumio[13310]: info: CoreStateMachine::getcurrentVolume Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:50:33 volumio volumio[13310]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 21:50:33 volumio volumio[13310]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 21:50:33 volumio volumio-remote-updater[647]: [2024-08-29 21:50:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724961029 101 Aug 29 21:50:33 volumio volumio[13310]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Aug 29 21:50:33 volumio volumio[13310]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:50:33 volumio volumio[13310]: info: Discovery: Getting this device information Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:33 volumio volumio[13310]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:50:33 volumio volumio[13310]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:33 volumio volumio[13310]: info: Reloading queue from file Aug 29 21:50:33 volumio volumio[13310]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:50:33 volumio volumio[13310]: info: CoreStateMachine::pushState Aug 29 21:50:33 volumio volumio[13310]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:33 volumio volumio[13310]: info: CoreStateMachine::updateTrackBlock Aug 29 21:50:33 volumio volumio[13310]: info: CorePlayQueue::getTrackBlock Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:50:33 volumio volumio[13310]: info: CoreStateMachine::setRepeat null single undefined Aug 29 21:50:33 volumio volumio[13310]: info: CoreStateMachine::pushState Aug 29 21:50:33 volumio volumio[13310]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:33 volumio volumio[13310]: info: CoreStateMachine::setRandom null Aug 29 21:50:33 volumio volumio[13310]: info: CoreStateMachine::pushState Aug 29 21:50:33 volumio volumio[13310]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:33 volumio volumio[13310]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:33 volumio volumio[13310]: info: Setting Device type: Raspberry PI Aug 29 21:50:33 volumio volumio[13310]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 21:50:33 volumio volumio[13310]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Aug 29 21:50:33 volumio volumio[13310]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 21:50:33 volumio volumio[13310]: info: Completed loading Core Plugins Aug 29 21:50:33 volumio volumio[13310]: info: Preparing to generate the ALSA configuration file Aug 29 21:50:33 volumio volumio[13310]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:50:33 volumio volumio[13310]: info: Reading ALSA contributions from plugins. Aug 29 21:50:33 volumio volumio[13310]: verbose: New Socket.io Connection to 192.168.0.12:3000 from 192.168.0.8 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 29 21:50:33 volumio volumio[13389]: Traceback (most recent call last): Aug 29 21:50:33 volumio volumio[13389]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:33 volumio volumio[13389]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:33 volumio volumio[13389]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:34 volumio volumio[13310]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:50:34 volumio volumio[13310]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:50:34 volumio volumio[13310]: info: CoreStateMachine::pushState Aug 29 21:50:34 volumio volumio[13310]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:34 volumio volumio[13310]: info: Discovery: adding a69d09fe-a052-4dd2-a613-4db4de2e2bb3 Aug 29 21:50:34 volumio volumio[13310]: info: Discovery: Found device Volumio Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:34 volumio volumio[13310]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:34 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:34 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:34 volumio volumio[13310]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:50:34 volumio volumio[13310]: info: Output device has changed, restarting MPD Aug 29 21:50:34 volumio sudo[13428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:50:34 volumio sudo[13428]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:34 volumio sudo[13428]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:34 volumio volumio[13310]: info: Output device has changed, restarting Shairport Sync Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:34 volumio sudo[13431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:50:34 volumio sudo[13431]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:34 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 21:50:34 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:50:34 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:50:34 volumio volumio[13310]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:34 volumio volumio[13310]: info: ___________ START PLUGINS ___________ Aug 29 21:50:34 volumio volumio[13310]: info: ControllerMpd::onStart: Initializing MPD Aug 29 21:50:34 volumio volumio[13310]: info: Creating MPD Configuration file Aug 29 21:50:34 volumio sudo[13438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:50:34 volumio sudo[13438]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:34 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:34 volumio volumio[13310]: info: [1724961034262] CoreMusicLibrary::Adding element Media Servers Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:34 volumio sudo[13438]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:34 volumio sudo[13441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:50:34 volumio sudo[13441]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:34 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 21:50:34 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:50:34 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:50:34 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:50:34 volumio volumio[13310]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:34 volumio sudo[13444]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:50:34 volumio volumio[13310]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:34 volumio sudo[13444]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:34 volumio volumio[13310]: info: [1724961034391] CoreMusicLibrary::Adding element Last_100 Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:34 volumio volumio[13310]: info: [1724961034393] CoreMusicLibrary::Adding element Webradio Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:34 volumio sudo[13444]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:34 volumio volumio[13310]: info: Initializing BBC Radios Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:34 volumio volumio[13310]: info: Creating Spotify config file Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:34 volumio volumio[13310]: info: [1724961034541] CoreMusicLibrary::Adding element YouTube Music Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:34 volumio volumio[13310]: Cannot find translation for source YouTube Music Aug 29 21:50:34 volumio volumio[13310]: info: [now-playing] ConfigUpdater: config is up to date. Aug 29 21:50:34 volumio volumio[13310]: info: Loading i18n strings for locale en Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:34 volumio volumio[13310]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 21:50:34 volumio volumio[13310]: info: Loading i18n strings for locale en Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:34 volumio volumio[13310]: info: [1724961034623] CoreMusicLibrary::Adding element Radio Paradise Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:34 volumio volumio[13310]: Cannot find translation for source YouTube Music Aug 29 21:50:34 volumio volumio[13310]: Cannot find translation for source Radio Paradise Aug 29 21:50:34 volumio volumio[13310]: info: Loading i18n strings for locale en Aug 29 21:50:34 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:34 volumio volumio[13310]: info: Volumio Calling Home Aug 29 21:50:34 volumio volumio[13310]: info: Preparing to generate the ALSA configuration file Aug 29 21:50:34 volumio sudo[13468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 21:50:34 volumio sudo[13468]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:34 volumio systemd[1]: Reloading. Aug 29 21:50:34 volumio volumio[13310]: info: [now-playing] Fetching API key... Aug 29 21:50:34 volumio volumio[13310]: info: [now-playing] App is listening on port 4004. Aug 29 21:50:35 volumio volumio[13310]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 29 21:50:35 volumio volumio[13310]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:50:35 volumio volumio[13310]: info: Reading ALSA contributions from plugins. Aug 29 21:50:35 volumio volumio[13310]: info: minidlna: Found miniDLNA version 1.2.1 Aug 29 21:50:35 volumio volumio[13310]: info: minidlna: Starting minidlna.service Aug 29 21:50:35 volumio volumio[13310]: info: MPD Permissions set Aug 29 21:50:35 volumio volumio[13310]: info: MPD Permissions set Aug 29 21:50:35 volumio volumio[13310]: info: Volumio called home Aug 29 21:50:35 volumio volumio[13310]: info: Spotify config file written Aug 29 21:50:35 volumio sudo[13484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 29 21:50:35 volumio sudo[13484]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:35 volumio volumio[13310]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio sudo[13487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 21:50:35 volumio sudo[13487]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 21:50:35 volumio volumio[13310]: info: Starting Shairport Sync Aug 29 21:50:35 volumio mpd[13451]: Aug 29 21:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:50:35 volumio volumio[13310]: info: Starting Shairport Sync Aug 29 21:50:35 volumio sudo[13500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:50:35 volumio sudo[13500]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:35 volumio volumio[13310]: info: Starting Shairport Sync Aug 29 21:50:35 volumio sudo[13503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:50:35 volumio sudo[13503]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:35 volumio sudo[13506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:50:35 volumio sudo[13506]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:35 volumio volumio[13310]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:35 volumio volumio[13310]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:50:35 volumio volumio[13310]: info: Output device has changed, restarting MPD Aug 29 21:50:35 volumio systemd[1]: Started Music Player Daemon. Aug 29 21:50:35 volumio sudo[13509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:50:35 volumio sudo[13509]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:35 volumio sudo[13431]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:35 volumio sudo[13468]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:35 volumio sudo[13441]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:35 volumio sudo[13509]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:35 volumio volumio[13310]: info: Output device has changed, restarting Shairport Sync Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:35 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:35 volumio sudo[13512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:50:35 volumio sudo[13512]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:35 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 21:50:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 21:50:35 volumio systemd[1]: Stopping go-librespot Daemon... Aug 29 21:50:35 volumio sudo[13484]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Aug 29 21:50:35 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Aug 29 21:50:35 volumio systemd[1]: Stopped go-librespot Daemon. Aug 29 21:50:35 volumio volumio[13310]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:35 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 21:50:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 21:50:35 volumio volumio[13310]: info: MPD Permissions set Aug 29 21:50:35 volumio volumio[13310]: info: minidlna: systemctl start minidlna.service succeeded. Aug 29 21:50:35 volumio volumio[13310]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 21:50:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 21:50:35 volumio sudo[13520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 21:50:35 volumio sudo[13520]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:35 volumio systemd[1]: Started go-librespot Daemon. Aug 29 21:50:35 volumio sudo[13506]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:35 volumio sudo[13500]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:35 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:50:35 volumio go-librespot[13522]: Librespot-go daemon starting... Aug 29 21:50:35 volumio sudo[13503]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:35 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:50:35 volumio sudo[13487]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:35 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:50:35 volumio go-librespot[13522]: time="2024-08-29T21:50:35+02:00" level=info msg="generated new device id: e0608941278e0ce21d0bf3eb98643f1095fac389" Aug 29 21:50:35 volumio sudo[13520]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:35 volumio volumio[13310]: info: Shairport-Sync Started Aug 29 21:50:35 volumio volumio[13310]: Error adding Membership: Error: addMembership EINVAL Aug 29 21:50:35 volumio volumio[13310]: info: Shairport-Sync Started Aug 29 21:50:35 volumio volumio[13310]: info: Shairport-Sync Started Aug 29 21:50:35 volumio volumio[13310]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 21:50:35 volumio volumio[13310]: info: touch_display: Volumio Kiosk started Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:36 volumio volumio[13310]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:36 volumio volumio[13310]: error: Cannot initialize MPD Connection: MPD is not running Aug 29 21:50:36 volumio sudo[13531]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:50:36 volumio sudo[13531]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:36 volumio sudo[13531]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:36 volumio volumio[13310]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:36 volumio volumio[13310]: info: Starting Shairport Sync Aug 29 21:50:36 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:36 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 167. Aug 29 21:50:36 volumio go-librespot[13522]: time="2024-08-29T21:50:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 29 21:50:36 volumio go-librespot[13522]: time="2024-08-29T21:50:36+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 29 21:50:36 volumio go-librespot[13522]: time="2024-08-29T21:50:36+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 29 21:50:36 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:36 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:36 volumio go-librespot[13522]: time="2024-08-29T21:50:36+02:00" level=debug msg="zeroconf server listening on port 39461" Aug 29 21:50:36 volumio sudo[13544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:50:36 volumio volumio[13310]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 21:50:36 volumio sudo[13544]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 21:50:36 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 21:50:36 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 21:50:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 21:50:36 volumio sudo[13544]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:36 volumio volumio[13310]: info: Shairport-Sync Started Aug 29 21:50:36 volumio volumio[13310]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 21:50:36 volumio volumio[13310]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 29 21:50:36 volumio volumio[13310]: info: [now-playing] OpenWeatherMap API key obtained. Aug 29 21:50:36 volumio volumio[13310]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 21:50:36 volumio volumio[13310]: 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 21:50:36 volumio volumio[13310]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 21:50:36 volumio volumio[13310]: 0+0 records in Aug 29 21:50:36 volumio volumio[13310]: 0+0 records out Aug 29 21:50:36 volumio volumio[13310]: 0 bytes copied, 0.000342255 s, 0.0 kB/s Aug 29 21:50:36 volumio volumio[13310]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 21:50:36 volumio volumio[13310]: info: camilladsp service started and running in background, instance 1 Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:36 volumio volumio[13310]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 21:50:36 volumio volumio[13310]: 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 21:50:36 volumio volumio[13310]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 21:50:36 volumio volumio[13310]: info: FusionDsp loaded Aug 29 21:50:36 volumio volumio[13310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:50:36 volumio sudo[13566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 21:50:36 volumio volumio[13310]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 21:50:36 volumio sudo[13566]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:36 volumio volumio[13310]: info: Adding Signal Path Element [object Object] Aug 29 21:50:36 volumio volumio[13310]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 21:50:36 volumio volumio[13310]: info: FusionDsp - ---- installed callbackRead Aug 29 21:50:36 volumio volumio[13310]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:50:36 volumio volumio[13310]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 21:50:36 volumio volumio[13310]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 21:50:36 volumio volumio[13310]: at onErrorNT (internal/child_process.js:465:16) Aug 29 21:50:36 volumio volumio[13310]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 29 21:50:36 volumio volumio[13310]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 29 21:50:36 volumio volumio[13310]: at listOnTimeout (internal/timers.js:523:9) Aug 29 21:50:36 volumio volumio[13310]: at processTimers (internal/timers.js:497:7) { Aug 29 21:50:36 volumio volumio[13310]: errno: -2, Aug 29 21:50:36 volumio volumio[13310]: code: 'ENOENT', Aug 29 21:50:36 volumio volumio[13310]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 21:50:36 volumio volumio[13310]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 21:50:36 volumio volumio[13310]: spawnargs: [ Aug 29 21:50:36 volumio volumio[13310]: '-p', Aug 29 21:50:36 volumio volumio[13310]: 9876, Aug 29 21:50:36 volumio volumio[13310]: '-o', Aug 29 21:50:36 volumio volumio[13310]: '/tmp/camilladsp.log', Aug 29 21:50:36 volumio volumio[13310]: '-l', Aug 29 21:50:36 volumio volumio[13310]: 'warn', Aug 29 21:50:36 volumio volumio[13310]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 21:50:36 volumio volumio[13310]: ] Aug 29 21:50:36 volumio volumio[13310]: } Aug 29 21:50:36 volumio volumio[13310]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:50:36 volumio sudo[13566]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:37 volumio volumio[13545]: Traceback (most recent call last): Aug 29 21:50:37 volumio volumio[13545]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:37 volumio volumio[13545]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:37 volumio volumio[13545]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:37 volumio mpd[13538]: Aug 29 21:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:50:37 volumio systemd[1]: Started Music Player Daemon. Aug 29 21:50:37 volumio sudo[13512]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:37 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:37 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:37 volumio sudo[13579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 21:49 Aug 29 21:50:37 volumio sudo[13579]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:37 volumio sudo[13579]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:37 volumio volumio-remote-updater[647]: [2024-08-29 21:50:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 21:50:37 volumio volumio-remote-updater[647]: [2024-08-29 21:50:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 21:50:37 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:37 volumio ntfs-3g[13357]: Unmounting /dev/sda1 (USB STICK) Aug 29 21:50:37 volumio systemd[984]: media-USB_STICK.mount: Succeeded. Aug 29 21:50:37 volumio systemd[1]: media-USB_STICK.mount: Succeeded. Aug 29 21:50:37 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 21:50:37 volumio systemd[1]: Started dynamicswap service. Aug 29 21:50:37 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 21:50:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 21:50:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 41. Aug 29 21:50:37 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 21:50:37 volumio systemd[1]: Started Volumio Backend Module. Aug 29 21:50:37 volumio systemd[1]: Started dynamicswap service. Aug 29 21:50:37 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 21:50:39 volumio volumio[13591]: info: ------------------------------------------- Aug 29 21:50:39 volumio volumio[13591]: info: ----- Volumio3 ---- Aug 29 21:50:39 volumio volumio[13591]: info: ------------------------------------------- Aug 29 21:50:39 volumio volumio[13591]: info: ----- System startup ---- Aug 29 21:50:39 volumio volumio[13591]: info: ------------------------------------------- Aug 29 21:50:39 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:39 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 168. Aug 29 21:50:39 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:39 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:39 volumio volumio[13591]: info: MYVOLUMIO Environment detected Aug 29 21:50:39 volumio volumio[13591]: info: Plugin folders cleanup Aug 29 21:50:39 volumio volumio[13591]: info: Scanning into folder /volumio/app/plugins/ Aug 29 21:50:39 volumio volumio[13591]: info: Scanning category audio_interface Aug 29 21:50:39 volumio volumio[13591]: info: Scanning category miscellanea Aug 29 21:50:39 volumio volumio[13591]: info: Scanning category music_service Aug 29 21:50:39 volumio volumio[13591]: info: Scanning category plugins.json Aug 29 21:50:39 volumio volumio[13591]: info: Scanning category system_controller Aug 29 21:50:39 volumio volumio[13591]: info: Scanning category user_interface Aug 29 21:50:39 volumio volumio[13591]: info: Scanning into folder /data/plugins/ Aug 29 21:50:39 volumio volumio[13591]: info: Scanning category audio_interface Aug 29 21:50:39 volumio volumio[13591]: info: Scanning category music_service Aug 29 21:50:39 volumio volumio[13591]: info: Scanning category user_interface Aug 29 21:50:39 volumio volumio[13591]: info: Plugin folders cleanup completed Aug 29 21:50:39 volumio volumio[13591]: info: ------------------------------------------- Aug 29 21:50:39 volumio volumio[13591]: info: ----- Core plugins startup ---- Aug 29 21:50:39 volumio volumio[13591]: info: ------------------------------------------- Aug 29 21:50:39 volumio volumio[13591]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 21:50:39 volumio volumio[13591]: info: Adding plugin upnp to MyMusic Plugins Aug 29 21:50:39 volumio volumio[13591]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 21:50:39 volumio volumio[13591]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 21:50:39 volumio volumio[13591]: info: Loading plugins from folder /data/plugins/ Aug 29 21:50:39 volumio volumio[13591]: info: Loading plugin "system"... Aug 29 21:50:39 volumio volumio[13591]: info: Loading plugin "appearance"... Aug 29 21:50:40 volumio volumio[13612]: Traceback (most recent call last): Aug 29 21:50:40 volumio volumio[13612]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:40 volumio volumio[13612]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:40 volumio volumio[13612]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:40 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:40 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:40 volumio volumio[13591]: info: Loading plugin "network"... Aug 29 21:50:40 volumio volumio[13591]: info: Refreshing Cached IP Addresses Aug 29 21:50:40 volumio sudo[13619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 21:50:40 volumio sudo[13619]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:40 volumio sudo[13619]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:40 volumio sudo[13621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:50:40 volumio sudo[13621]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:40 volumio volumio[13591]: info: Loading plugin "services"... Aug 29 21:50:40 volumio volumio[13591]: info: Loading plugin "alsa_controller"... Aug 29 21:50:40 volumio sudo[13621]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:40 volumio sudo[13624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 21:50:40 volumio sudo[13624]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:40 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:40 volumio volumio[13591]: info: Loading plugin "wizard"... Aug 29 21:50:40 volumio volumio[13591]: info: Loading plugin "networkfs"... Aug 29 21:50:40 volumio volumio[13591]: info: Starting Udev Watcher for removable devices Aug 29 21:50:40 volumio volumio[13591]: info: Ignoring mount for partition: boot Aug 29 21:50:40 volumio volumio[13591]: info: Ignoring mount for partition: volumio Aug 29 21:50:40 volumio volumio[13591]: info: Ignoring mount for partition: volumio_data Aug 29 21:50:40 volumio volumio[13591]: info: Mounting Device USB_STICK Aug 29 21:50:40 volumio sudo[13635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/USB_STICK -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 21:50:40 volumio sudo[13635]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:40 volumio ntfs-3g[13638]: Version 2017.3.23AR.3 integrated FUSE 28 Aug 29 21:50:40 volumio ntfs-3g[13638]: Mounted /dev/sda1 (Read-Write, label "USB STICK", NTFS 3.1) Aug 29 21:50:40 volumio ntfs-3g[13638]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 21:50:40 volumio ntfs-3g[13638]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Aug 29 21:50:40 volumio ntfs-3g[13638]: Global ownership and permissions enforced, configuration type 7 Aug 29 21:50:40 volumio sudo[13635]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:40 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:40 volumio volumio[13591]: info: Loading plugin "volumio_command_line_client"... Aug 29 21:50:40 volumio volumio[13591]: info: Loading plugin "upnp"... Aug 29 21:50:40 volumio volumio[13591]: info: [1724961040884] Starting Upmpd Daemon Aug 29 21:50:40 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:40 volumio volumio[13591]: info: Loading plugin "my_music"... Aug 29 21:50:40 volumio volumio[13591]: info: Loading plugin "mpd"... Aug 29 21:50:41 volumio volumio[13591]: info: Loading plugin "upnp_browser"... Aug 29 21:50:41 volumio volumio[13591]: info: Loading plugin "alarm-clock"... Aug 29 21:50:41 volumio volumio[13591]: info: Loading plugin "airplay_emulation"... Aug 29 21:50:41 volumio volumio[13591]: info: Starting Shairport Sync Aug 29 21:50:41 volumio volumio[13591]: info: Loading plugin "last_100"... Aug 29 21:50:41 volumio volumio[13591]: info: Loading plugin "webradio"... Aug 29 21:50:41 volumio volumio[13591]: info: Loading plugin "i2s_dacs"... Aug 29 21:50:41 volumio volumio[13591]: info: Loading plugin "volumiodiscovery"... Aug 29 21:50:41 volumio volumio[13591]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:41 volumio volumio[13591]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:41 volumio volumio[13591]: *** WARNING *** For more information see Aug 29 21:50:41 volumio volumio[13591]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:41 volumio volumio[13591]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:41 volumio volumio[13591]: *** WARNING *** For more information see Aug 29 21:50:41 volumio node[13591]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:41 volumio node[13591]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:41 volumio node[13591]: *** WARNING *** For more information see Aug 29 21:50:41 volumio node[13591]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:41 volumio node[13591]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:41 volumio node[13591]: *** WARNING *** For more information see Aug 29 21:50:41 volumio volumio[13591]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 21:50:41 volumio volumio[13591]: info: Discovery: Started advertising with name: Volumio Aug 29 21:50:41 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:41 volumio volumio[13591]: info: Loading plugin "spop"... Aug 29 21:50:42 volumio volumio[13591]: info: Loading plugin "ytcr"... Aug 29 21:50:42 volumio volumio-remote-updater[647]: [2024-08-29 21:50:42] [connect] Successful connection Aug 29 21:50:42 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:42 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 169. Aug 29 21:50:42 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:42 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:43 volumio volumio[13641]: Traceback (most recent call last): Aug 29 21:50:43 volumio volumio[13641]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:43 volumio volumio[13641]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:43 volumio volumio[13641]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:43 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:43 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:43 volumio volumio[13591]: info: Loading plugin "ytmusic"... Aug 29 21:50:44 volumio sudo[13624]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:44 volumio volumio[13591]: info: Loading plugin "now_playing"... Aug 29 21:50:45 volumio volumio[13591]: info: Loading plugin "outputs"... Aug 29 21:50:45 volumio volumio[13591]: info: Loading plugin "albumart"... Aug 29 21:50:45 volumio volumio[13591]: info: Plugin example_plugin is not enabled Aug 29 21:50:45 volumio volumio[13591]: info: Loading plugin "inputs"... Aug 29 21:50:45 volumio volumio[13591]: info: Loading plugin "updater_comm"... Aug 29 21:50:45 volumio volumio[13591]: info: Plugin mpdemulation is not enabled Aug 29 21:50:45 volumio volumio[13591]: info: Loading plugin "rest_api"... Aug 29 21:50:45 volumio volumio[13591]: info: Loading plugin "websocket"... Aug 29 21:50:45 volumio volumio[13591]: info: Starting Socket.io Server version 2.3.0 Aug 29 21:50:45 volumio volumio[13591]: info: Loading plugin "fusiondsp"... Aug 29 21:50:45 volumio volumio[13591]: Forking 3 albumart workers Aug 29 21:50:45 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:45 volumio volumio[13591]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 21:50:45 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 170. Aug 29 21:50:45 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:45 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:45 volumio volumio[13591]: info: Loading plugin "minidlna"... Aug 29 21:50:45 volumio volumio[13591]: info: Applying required configuration parameters for plugin minidlna Aug 29 21:50:46 volumio volumio[13591]: info: Loading plugin "radio_paradise"... Aug 29 21:50:46 volumio volumio[13591]: Starting albumart workers Aug 29 21:50:46 volumio volumio[13591]: info: Applying required configuration parameters for plugin radio_paradise Aug 29 21:50:46 volumio volumio[13591]: info: [1724961046402] [RadioParadise] API delay: 5 Aug 29 21:50:46 volumio volumio[13591]: info: Loading plugin "Systeminfo"... Aug 29 21:50:46 volumio volumio[13591]: Starting albumart workers Aug 29 21:50:46 volumio volumio[13591]: Starting albumart workers Aug 29 21:50:46 volumio volumio[13591]: info: Loading plugin "touch_display"... Aug 29 21:50:46 volumio volumio[13669]: Traceback (most recent call last): Aug 29 21:50:46 volumio volumio[13669]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:46 volumio volumio[13669]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:46 volumio volumio[13669]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:46 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:46 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:47 volumio volumio[13591]: info: Applying required configuration parameters for plugin touch_display Aug 29 21:50:47 volumio volumio[13591]: info: Loading i18n strings for locale en Aug 29 21:50:47 volumio volumio[13591]: Updating browse sources language Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::initPlayerControls Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: Express server listening on port 3000 Aug 29 21:50:47 volumio volumio[13591]: [Metrics] WebUI: 8s 530.58ms Aug 29 21:50:47 volumio volumio[13591]: info: CoreStateMachine::resetVolumioState Aug 29 21:50:47 volumio volumio[13591]: info: CoreStateMachine::getcurrentVolume Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:50:47 volumio volumio[13591]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 21:50:47 volumio volumio[13591]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 21:50:47 volumio volumio-remote-updater[647]: [2024-08-29 21:50:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724961042 101 Aug 29 21:50:47 volumio volumio[13591]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:50:47 volumio volumio[13591]: info: Discovery: Getting this device information Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:47 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:50:47 volumio volumio[13591]: 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 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: info: Reloading queue from file Aug 29 21:50:47 volumio volumio[13591]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:50:47 volumio volumio[13591]: info: CoreStateMachine::pushState Aug 29 21:50:47 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:47 volumio volumio[13591]: info: CoreStateMachine::updateTrackBlock Aug 29 21:50:47 volumio volumio[13591]: info: CorePlayQueue::getTrackBlock Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:50:47 volumio volumio[13591]: info: CoreStateMachine::setRepeat null single undefined Aug 29 21:50:47 volumio volumio[13591]: info: CoreStateMachine::pushState Aug 29 21:50:47 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:47 volumio volumio[13591]: info: CoreStateMachine::setRandom null Aug 29 21:50:47 volumio volumio[13591]: info: CoreStateMachine::pushState Aug 29 21:50:47 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:47 volumio volumio[13591]: info: Setting Device type: Raspberry PI Aug 29 21:50:47 volumio volumio[13591]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 21:50:47 volumio volumio[13591]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Aug 29 21:50:47 volumio volumio[13591]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 21:50:47 volumio volumio[13591]: info: Completed loading Core Plugins Aug 29 21:50:47 volumio volumio[13591]: info: Preparing to generate the ALSA configuration file Aug 29 21:50:47 volumio volumio[13591]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:50:47 volumio volumio[13591]: info: Reading ALSA contributions from plugins. Aug 29 21:50:47 volumio volumio[13591]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:50:47 volumio volumio[13591]: info: CoreStateMachine::pushState Aug 29 21:50:47 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::volumioPushState Aug 29 21:50:47 volumio volumio[13591]: info: Discovery: adding a69d09fe-a052-4dd2-a613-4db4de2e2bb3 Aug 29 21:50:47 volumio volumio[13591]: info: Discovery: Found device Volumio Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:47 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:47 volumio volumio[13591]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:50:47 volumio volumio[13591]: info: Output device has changed, restarting MPD Aug 29 21:50:47 volumio sudo[13708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:50:47 volumio sudo[13708]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:47 volumio sudo[13708]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:47 volumio volumio[13591]: info: Output device has changed, restarting Shairport Sync Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:47 volumio sudo[13711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:50:47 volumio sudo[13711]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:47 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 21:50:47 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:50:47 volumio volumio[13591]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:47 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:50:47 volumio volumio[13591]: info: ___________ START PLUGINS ___________ Aug 29 21:50:47 volumio volumio[13591]: info: ControllerMpd::onStart: Initializing MPD Aug 29 21:50:47 volumio volumio[13591]: info: Creating MPD Configuration file Aug 29 21:50:47 volumio sudo[13718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:50:47 volumio sudo[13718]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:47 volumio sudo[13718]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:50:47 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:47 volumio volumio[13591]: info: [1724961047998] CoreMusicLibrary::Adding element Media Servers Aug 29 21:50:47 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:48 volumio sudo[13721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:50:48 volumio sudo[13721]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:48 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 21:50:48 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:50:48 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:50:48 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:50:48 volumio volumio[13591]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:48 volumio sudo[13724]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:50:48 volumio sudo[13724]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:48 volumio volumio[13591]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:48 volumio volumio[13591]: info: [1724961048129] CoreMusicLibrary::Adding element Last_100 Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:48 volumio volumio[13591]: info: [1724961048131] CoreMusicLibrary::Adding element Webradio Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:48 volumio sudo[13724]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:48 volumio volumio[13591]: info: Initializing BBC Radios Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: Creating Spotify config file Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:48 volumio volumio[13591]: info: [1724961048277] CoreMusicLibrary::Adding element YouTube Music Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:48 volumio volumio[13591]: Cannot find translation for source YouTube Music Aug 29 21:50:48 volumio volumio[13591]: info: [now-playing] ConfigUpdater: config is up to date. Aug 29 21:50:48 volumio volumio[13591]: info: Loading i18n strings for locale en Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 21:50:48 volumio volumio[13591]: info: Loading i18n strings for locale en Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:50:48 volumio volumio[13591]: info: [1724961048359] CoreMusicLibrary::Adding element Radio Paradise Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:48 volumio volumio[13591]: Cannot find translation for source YouTube Music Aug 29 21:50:48 volumio volumio[13591]: Cannot find translation for source Radio Paradise Aug 29 21:50:48 volumio volumio[13591]: info: Loading i18n strings for locale en Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:48 volumio volumio[13591]: info: Volumio Calling Home Aug 29 21:50:48 volumio volumio[13591]: info: Preparing to generate the ALSA configuration file Aug 29 21:50:48 volumio sudo[13748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 21:50:48 volumio sudo[13748]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:48 volumio systemd[1]: Reloading. Aug 29 21:50:48 volumio volumio[13591]: info: [now-playing] Fetching API key... Aug 29 21:50:48 volumio volumio[13591]: info: [now-playing] App is listening on port 4004. Aug 29 21:50:48 volumio volumio[13591]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 29 21:50:48 volumio volumio[13591]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:50:48 volumio volumio[13591]: info: Reading ALSA contributions from plugins. Aug 29 21:50:48 volumio volumio[13591]: info: minidlna: Found miniDLNA version 1.2.1 Aug 29 21:50:48 volumio volumio[13591]: info: minidlna: Starting minidlna.service Aug 29 21:50:48 volumio volumio[13591]: info: MPD Permissions set Aug 29 21:50:48 volumio volumio[13591]: info: MPD Permissions set Aug 29 21:50:48 volumio volumio[13591]: info: Volumio called home Aug 29 21:50:48 volumio volumio[13591]: info: Spotify config file written Aug 29 21:50:48 volumio sudo[13764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 29 21:50:48 volumio sudo[13764]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:48 volumio sudo[13767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 21:50:48 volumio sudo[13767]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:48 volumio volumio[13591]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:48 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 21:50:49 volumio mpd[13731]: Aug 29 21:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:50:49 volumio volumio[13591]: info: Starting Shairport Sync Aug 29 21:50:49 volumio volumio[13591]: info: Starting Shairport Sync Aug 29 21:50:49 volumio sudo[13780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:50:49 volumio sudo[13780]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:49 volumio volumio[13591]: info: Starting Shairport Sync Aug 29 21:50:49 volumio sudo[13783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:50:49 volumio sudo[13783]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:49 volumio sudo[13786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:50:49 volumio sudo[13786]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:49 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:49 volumio volumio[13591]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:50:49 volumio volumio[13591]: info: Output device has changed, restarting MPD Aug 29 21:50:49 volumio sudo[13789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:50:49 volumio sudo[13789]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:49 volumio systemd[1]: Started Music Player Daemon. Aug 29 21:50:49 volumio sudo[13711]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:49 volumio sudo[13748]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:49 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:49 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 171. Aug 29 21:50:49 volumio sudo[13789]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:49 volumio sudo[13721]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:49 volumio volumio[13591]: info: Output device has changed, restarting Shairport Sync Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:49 volumio sudo[13792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:50:49 volumio sudo[13792]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:49 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 21:50:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 21:50:49 volumio systemd[1]: Stopping go-librespot Daemon... Aug 29 21:50:49 volumio sudo[13764]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:49 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:49 volumio volumio[13591]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:50:49 volumio volumio[13591]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 29 21:50:49 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:49 volumio volumio[13591]: info: MPD Permissions set Aug 29 21:50:49 volumio volumio[13591]: info: minidlna: systemctl start minidlna.service succeeded. Aug 29 21:50:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Aug 29 21:50:49 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Aug 29 21:50:49 volumio systemd[1]: Stopped go-librespot Daemon. Aug 29 21:50:49 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 21:50:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 21:50:49 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:50:49 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:50:49 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:50:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 21:50:49 volumio sudo[13783]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:49 volumio sudo[13786]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:49 volumio systemd[1]: Started go-librespot Daemon. Aug 29 21:50:49 volumio sudo[13767]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:49 volumio sudo[13780]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:49 volumio volumio[13591]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 21:50:49 volumio go-librespot[13802]: Librespot-go daemon starting... Aug 29 21:50:49 volumio go-librespot[13802]: time="2024-08-29T21:50:49+02:00" level=info msg="generated new device id: 89b875bee4750925be72d768512d0d814a74044d" Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 21:50:49 volumio sudo[13812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 21:50:49 volumio sudo[13812]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:49 volumio volumio[13591]: error: Cannot initialize MPD Connection: MPD is not running Aug 29 21:50:49 volumio sudo[13799]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:50:49 volumio sudo[13799]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:49 volumio volumio[13591]: info: Shairport-Sync Started Aug 29 21:50:49 volumio volumio[13591]: Error adding Membership: Error: addMembership EINVAL Aug 29 21:50:49 volumio volumio[13591]: info: Shairport-Sync Started Aug 29 21:50:49 volumio volumio[13591]: info: Shairport-Sync Started Aug 29 21:50:49 volumio sudo[13799]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:49 volumio sudo[13812]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:49 volumio volumio[13591]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 21:50:49 volumio volumio[13591]: info: touch_display: Volumio Kiosk started Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:49 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:49 volumio volumio[13591]: info: Starting Shairport Sync Aug 29 21:50:49 volumio go-librespot[13802]: time="2024-08-29T21:50:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 21:50:49 volumio go-librespot[13802]: time="2024-08-29T21:50:49+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 21:50:49 volumio go-librespot[13802]: time="2024-08-29T21:50:49+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 29 21:50:49 volumio go-librespot[13802]: time="2024-08-29T21:50:49+02:00" level=debug msg="zeroconf server listening on port 36731" Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:49 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:49 volumio sudo[13826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:50:49 volumio sudo[13826]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:49 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:49 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 21:50:49 volumio volumio[13591]: info: Received Get System Info Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:50:49 volumio volumio[13591]: info: Discovery: Getting this device information Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:49 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:50:49 volumio volumio[13591]: info: CoreCommandRouter::volumioGetState Aug 29 21:50:49 volumio volumio[13591]: info: CorePlayQueue::getTrack 0 Aug 29 21:50:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 21:50:49 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 21:50:49 volumio volumio[13591]: info: Listing playlists Aug 29 21:50:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 21:50:50 volumio volumio[13591]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 21:50:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 21:50:50 volumio sudo[13826]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:50 volumio volumio[13591]: info: Shairport-Sync Started Aug 29 21:50:50 volumio volumio[13591]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 21:50:50 volumio volumio[13591]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:50:50 volumio volumio[13591]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 21:50:50 volumio volumio[13591]: 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 21:50:50 volumio volumio[13591]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 21:50:50 volumio volumio[13591]: 0+0 records in Aug 29 21:50:50 volumio volumio[13591]: 0+0 records out Aug 29 21:50:50 volumio volumio[13591]: 0 bytes copied, 0.000341162 s, 0.0 kB/s Aug 29 21:50:50 volumio volumio[13591]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 21:50:50 volumio volumio[13591]: info: camilladsp service started and running in background, instance 1 Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:50:50 volumio volumio[13591]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 21:50:50 volumio volumio[13591]: 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 21:50:50 volumio volumio[13591]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 21:50:50 volumio volumio[13591]: info: FusionDsp loaded Aug 29 21:50:50 volumio volumio[13591]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:50:50 volumio sudo[13847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 21:50:50 volumio sudo[13847]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:50 volumio volumio[13591]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 21:50:50 volumio volumio[13591]: info: Adding Signal Path Element [object Object] Aug 29 21:50:50 volumio volumio[13591]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 21:50:50 volumio volumio[13591]: info: FusionDsp - ---- installed callbackRead Aug 29 21:50:50 volumio volumio[13591]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:50:50 volumio volumio[13591]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 21:50:50 volumio volumio[13591]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 21:50:50 volumio volumio[13591]: at onErrorNT (internal/child_process.js:465:16) Aug 29 21:50:50 volumio volumio[13591]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 29 21:50:50 volumio volumio[13591]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 29 21:50:50 volumio volumio[13591]: at listOnTimeout (internal/timers.js:523:9) Aug 29 21:50:50 volumio volumio[13591]: at processTimers (internal/timers.js:497:7) { Aug 29 21:50:50 volumio volumio[13591]: errno: -2, Aug 29 21:50:50 volumio volumio[13591]: code: 'ENOENT', Aug 29 21:50:50 volumio volumio[13591]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 21:50:50 volumio volumio[13591]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 21:50:50 volumio volumio[13591]: spawnargs: [ Aug 29 21:50:50 volumio volumio[13591]: '-p', Aug 29 21:50:50 volumio volumio[13591]: 9876, Aug 29 21:50:50 volumio volumio[13591]: '-o', Aug 29 21:50:50 volumio volumio[13591]: '/tmp/camilladsp.log', Aug 29 21:50:50 volumio volumio[13591]: '-l', Aug 29 21:50:50 volumio volumio[13591]: 'warn', Aug 29 21:50:50 volumio volumio[13591]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 21:50:50 volumio volumio[13591]: ] Aug 29 21:50:50 volumio volumio[13591]: } Aug 29 21:50:50 volumio volumio[13591]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:50:50 volumio sudo[13847]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:50 volumio volumio[13797]: Traceback (most recent call last): Aug 29 21:50:50 volumio volumio[13797]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:50 volumio volumio[13797]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:50 volumio volumio[13797]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:50 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:50 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:50 volumio mpd[13816]: Aug 29 21:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:50:50 volumio systemd[1]: Started Music Player Daemon. Aug 29 21:50:50 volumio sudo[13792]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:51 volumio sudo[13860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 21:49 Aug 29 21:50:51 volumio sudo[13860]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:51 volumio sudo[13860]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:51 volumio volumio-remote-updater[647]: [2024-08-29 21:50:51] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 21:50:51 volumio volumio-remote-updater[647]: [2024-08-29 21:50:51] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 21:50:51 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:51 volumio systemd[984]: media-USB_STICK.mount: Succeeded. Aug 29 21:50:51 volumio systemd[1]: media-USB_STICK.mount: Succeeded. Aug 29 21:50:51 volumio ntfs-3g[13638]: Unmounting /dev/sda1 (USB STICK) Aug 29 21:50:51 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 21:50:51 volumio systemd[1]: Started dynamicswap service. Aug 29 21:50:51 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 21:50:51 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 21:50:51 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 42. Aug 29 21:50:51 volumio systemd[1]: Started dynamicswap service. Aug 29 21:50:51 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 21:50:51 volumio systemd[1]: Started Volumio Backend Module. Aug 29 21:50:51 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 21:50:52 volumio volumio[13873]: info: ------------------------------------------- Aug 29 21:50:52 volumio volumio[13873]: info: ----- Volumio3 ---- Aug 29 21:50:52 volumio volumio[13873]: info: ------------------------------------------- Aug 29 21:50:52 volumio volumio[13873]: info: ----- System startup ---- Aug 29 21:50:52 volumio volumio[13873]: info: ------------------------------------------- Aug 29 21:50:52 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:52 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 172. Aug 29 21:50:52 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:52 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:53 volumio volumio[13873]: info: MYVOLUMIO Environment detected Aug 29 21:50:53 volumio volumio[13873]: info: Plugin folders cleanup Aug 29 21:50:53 volumio volumio[13873]: info: Scanning into folder /volumio/app/plugins/ Aug 29 21:50:53 volumio volumio[13873]: info: Scanning category audio_interface Aug 29 21:50:53 volumio volumio[13873]: info: Scanning category miscellanea Aug 29 21:50:53 volumio volumio[13873]: info: Scanning category music_service Aug 29 21:50:53 volumio volumio[13873]: info: Scanning category plugins.json Aug 29 21:50:53 volumio volumio[13873]: info: Scanning category system_controller Aug 29 21:50:53 volumio volumio[13873]: info: Scanning category user_interface Aug 29 21:50:53 volumio volumio[13873]: info: Scanning into folder /data/plugins/ Aug 29 21:50:53 volumio volumio[13873]: info: Scanning category audio_interface Aug 29 21:50:53 volumio volumio[13873]: info: Scanning category music_service Aug 29 21:50:53 volumio volumio[13873]: info: Scanning category user_interface Aug 29 21:50:53 volumio volumio[13873]: info: Plugin folders cleanup completed Aug 29 21:50:53 volumio volumio[13873]: info: ------------------------------------------- Aug 29 21:50:53 volumio volumio[13873]: info: ----- Core plugins startup ---- Aug 29 21:50:53 volumio volumio[13873]: info: ------------------------------------------- Aug 29 21:50:53 volumio volumio[13873]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 21:50:53 volumio volumio[13873]: info: Adding plugin upnp to MyMusic Plugins Aug 29 21:50:53 volumio volumio[13873]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 21:50:53 volumio volumio[13873]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 21:50:53 volumio volumio[13873]: info: Loading plugins from folder /data/plugins/ Aug 29 21:50:53 volumio volumio[13873]: info: Loading plugin "system"... Aug 29 21:50:53 volumio volumio[13873]: info: Loading plugin "appearance"... Aug 29 21:50:53 volumio volumio[13937]: Traceback (most recent call last): Aug 29 21:50:53 volumio volumio[13937]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:53 volumio volumio[13937]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:53 volumio volumio[13937]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:53 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:53 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "network"... Aug 29 21:50:54 volumio volumio[13873]: info: Refreshing Cached IP Addresses Aug 29 21:50:54 volumio sudo[13945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 21:50:54 volumio sudo[13945]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:54 volumio sudo[13945]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:54 volumio sudo[13947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:50:54 volumio sudo[13947]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "services"... Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "alsa_controller"... Aug 29 21:50:54 volumio sudo[13947]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:54 volumio sudo[13950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 21:50:54 volumio sudo[13950]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:54 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "wizard"... Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "networkfs"... Aug 29 21:50:54 volumio volumio[13873]: info: Starting Udev Watcher for removable devices Aug 29 21:50:54 volumio volumio[13873]: info: Ignoring mount for partition: boot Aug 29 21:50:54 volumio volumio[13873]: info: Ignoring mount for partition: volumio Aug 29 21:50:54 volumio volumio[13873]: info: Ignoring mount for partition: volumio_data Aug 29 21:50:54 volumio volumio[13873]: info: Mounting Device USB_STICK Aug 29 21:50:54 volumio sudo[13961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/USB_STICK -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 21:50:54 volumio sudo[13961]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:50:54 volumio ntfs-3g[13964]: Version 2017.3.23AR.3 integrated FUSE 28 Aug 29 21:50:54 volumio ntfs-3g[13964]: Mounted /dev/sda1 (Read-Write, label "USB STICK", NTFS 3.1) Aug 29 21:50:54 volumio ntfs-3g[13964]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 21:50:54 volumio ntfs-3g[13964]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Aug 29 21:50:54 volumio ntfs-3g[13964]: Global ownership and permissions enforced, configuration type 7 Aug 29 21:50:54 volumio sudo[13961]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:54 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "volumio_command_line_client"... Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "upnp"... Aug 29 21:50:54 volumio volumio[13873]: info: [1724961054419] Starting Upmpd Daemon Aug 29 21:50:54 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "my_music"... Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "mpd"... Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "upnp_browser"... Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "alarm-clock"... Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "airplay_emulation"... Aug 29 21:50:54 volumio volumio[13873]: info: Starting Shairport Sync Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "last_100"... Aug 29 21:50:54 volumio volumio[13873]: info: Loading plugin "webradio"... Aug 29 21:50:55 volumio volumio[13873]: info: Loading plugin "i2s_dacs"... Aug 29 21:50:55 volumio volumio[13873]: info: Loading plugin "volumiodiscovery"... Aug 29 21:50:55 volumio volumio[13873]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:55 volumio volumio[13873]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:55 volumio volumio[13873]: *** WARNING *** For more information see Aug 29 21:50:55 volumio volumio[13873]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:55 volumio volumio[13873]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:55 volumio volumio[13873]: *** WARNING *** For more information see Aug 29 21:50:55 volumio node[13873]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:55 volumio node[13873]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:55 volumio node[13873]: *** WARNING *** For more information see Aug 29 21:50:55 volumio node[13873]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:50:55 volumio node[13873]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:50:55 volumio node[13873]: *** WARNING *** For more information see Aug 29 21:50:55 volumio volumio[13873]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 21:50:55 volumio volumio[13873]: info: Discovery: Started advertising with name: Volumio Aug 29 21:50:55 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:50:55 volumio volumio[13873]: info: Loading plugin "spop"... Aug 29 21:50:55 volumio volumio[13873]: info: Loading plugin "ytcr"... Aug 29 21:50:56 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:56 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 173. Aug 29 21:50:56 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:56 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:56 volumio volumio-remote-updater[647]: [2024-08-29 21:50:56] [connect] Successful connection Aug 29 21:50:57 volumio volumio[13967]: Traceback (most recent call last): Aug 29 21:50:57 volumio volumio[13967]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:50:57 volumio volumio[13967]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:50:57 volumio volumio[13967]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:50:57 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:50:57 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:50:57 volumio volumio[13873]: info: Loading plugin "ytmusic"... Aug 29 21:50:57 volumio sudo[13950]: pam_unix(sudo:session): session closed for user root Aug 29 21:50:58 volumio volumio[13873]: info: Loading plugin "now_playing"... Aug 29 21:50:58 volumio volumio[13873]: info: Loading plugin "outputs"... Aug 29 21:50:58 volumio volumio[13873]: info: Loading plugin "albumart"... Aug 29 21:50:58 volumio volumio[13873]: info: Plugin example_plugin is not enabled Aug 29 21:50:58 volumio volumio[13873]: info: Loading plugin "inputs"... Aug 29 21:50:58 volumio volumio[13873]: info: Loading plugin "updater_comm"... Aug 29 21:50:59 volumio volumio[13873]: info: Plugin mpdemulation is not enabled Aug 29 21:50:59 volumio volumio[13873]: info: Loading plugin "rest_api"... Aug 29 21:50:59 volumio volumio[13873]: info: Loading plugin "websocket"... Aug 29 21:50:59 volumio volumio[13873]: info: Starting Socket.io Server version 2.3.0 Aug 29 21:50:59 volumio volumio[13873]: info: Loading plugin "fusiondsp"... Aug 29 21:50:59 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:50:59 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 174. Aug 29 21:50:59 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:50:59 volumio volumio[13873]: Forking 3 albumart workers Aug 29 21:50:59 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:50:59 volumio volumio[13873]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 21:50:59 volumio volumio[13873]: info: Loading plugin "minidlna"... Aug 29 21:50:59 volumio volumio[13873]: info: Applying required configuration parameters for plugin minidlna Aug 29 21:50:59 volumio volumio[13873]: info: Loading plugin "radio_paradise"... Aug 29 21:50:59 volumio volumio[13873]: info: Applying required configuration parameters for plugin radio_paradise Aug 29 21:50:59 volumio volumio[13873]: info: [1724961059959] [RadioParadise] API delay: 5 Aug 29 21:50:59 volumio volumio[13873]: info: Loading plugin "Systeminfo"... Aug 29 21:51:00 volumio volumio[13873]: Starting albumart workers Aug 29 21:51:00 volumio volumio[13873]: Starting albumart workers Aug 29 21:51:00 volumio volumio[13873]: Starting albumart workers Aug 29 21:51:00 volumio volumio[13873]: info: Loading plugin "touch_display"... Aug 29 21:51:00 volumio volumio[13975]: Traceback (most recent call last): Aug 29 21:51:00 volumio volumio[13975]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:51:00 volumio volumio[13975]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:51:00 volumio volumio[13975]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:51:00 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:51:00 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:51:00 volumio volumio[13873]: info: Applying required configuration parameters for plugin touch_display Aug 29 21:51:00 volumio volumio[13873]: info: Loading i18n strings for locale en Aug 29 21:51:00 volumio volumio[13873]: Updating browse sources language Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::initPlayerControls Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:51:00 volumio volumio[13873]: Express server listening on port 3000 Aug 29 21:51:00 volumio volumio[13873]: [Metrics] WebUI: 8s 529.13ms Aug 29 21:51:00 volumio volumio[13873]: info: CoreStateMachine::resetVolumioState Aug 29 21:51:00 volumio volumio[13873]: info: CoreStateMachine::getcurrentVolume Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:51:00 volumio volumio[13873]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 21:51:00 volumio volumio[13873]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 21:51:00 volumio volumio-remote-updater[647]: [2024-08-29 21:51:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724961056 101 Aug 29 21:51:00 volumio volumio[13873]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:51:00 volumio volumio[13873]: info: Discovery: Getting this device information Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:00 volumio volumio[13873]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:00 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:51:01 volumio volumio[13873]: 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 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:01 volumio volumio[13873]: info: Reloading queue from file Aug 29 21:51:01 volumio volumio[13873]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:51:01 volumio volumio[13873]: info: CoreStateMachine::pushState Aug 29 21:51:01 volumio volumio[13873]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioPushState Aug 29 21:51:01 volumio volumio[13873]: info: CoreStateMachine::updateTrackBlock Aug 29 21:51:01 volumio volumio[13873]: info: CorePlayQueue::getTrackBlock Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:51:01 volumio volumio[13873]: info: CoreStateMachine::setRepeat null single undefined Aug 29 21:51:01 volumio volumio[13873]: info: CoreStateMachine::pushState Aug 29 21:51:01 volumio volumio[13873]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioPushState Aug 29 21:51:01 volumio volumio[13873]: info: CoreStateMachine::setRandom null Aug 29 21:51:01 volumio volumio[13873]: info: CoreStateMachine::pushState Aug 29 21:51:01 volumio volumio[13873]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioPushState Aug 29 21:51:01 volumio volumio[13873]: info: Setting Device type: Raspberry PI Aug 29 21:51:01 volumio volumio[13873]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 21:51:01 volumio volumio[13873]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Aug 29 21:51:01 volumio volumio[13873]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 21:51:01 volumio volumio[13873]: info: Completed loading Core Plugins Aug 29 21:51:01 volumio volumio[13873]: info: Preparing to generate the ALSA configuration file Aug 29 21:51:01 volumio volumio[13873]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:51:01 volumio volumio[13873]: info: Reading ALSA contributions from plugins. Aug 29 21:51:01 volumio volumio[13873]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:01 volumio volumio[13873]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:01 volumio volumio[13873]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 21:51:01 volumio volumio[13873]: info: Received Get System Info Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:51:01 volumio volumio[13873]: info: Discovery: Getting this device information Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:01 volumio volumio[13873]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:01 volumio volumio[13873]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:01 volumio volumio[13873]: info: Listing playlists Aug 29 21:51:01 volumio volumio[13873]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:51:01 volumio volumio[13873]: info: CoreStateMachine::pushState Aug 29 21:51:01 volumio volumio[13873]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioPushState Aug 29 21:51:01 volumio volumio[13873]: info: Discovery: adding a69d09fe-a052-4dd2-a613-4db4de2e2bb3 Aug 29 21:51:01 volumio volumio[13873]: info: Discovery: Found device Volumio Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:01 volumio volumio[13873]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:51:01 volumio volumio[13873]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:51:01 volumio volumio[13873]: info: Output device has changed, restarting MPD Aug 29 21:51:01 volumio sudo[14034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:51:01 volumio sudo[14034]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:01 volumio sudo[14034]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:01 volumio volumio[13873]: info: Output device has changed, restarting Shairport Sync Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:01 volumio sudo[14037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:51:01 volumio sudo[14037]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:01 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 21:51:01 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:51:01 volumio volumio[13873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:51:01 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:51:01 volumio volumio[13873]: info: ___________ START PLUGINS ___________ Aug 29 21:51:01 volumio volumio[13873]: info: ControllerMpd::onStart: Initializing MPD Aug 29 21:51:01 volumio volumio[13873]: info: Creating MPD Configuration file Aug 29 21:51:01 volumio sudo[14044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:51:01 volumio sudo[14044]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:01 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:51:01 volumio volumio[13873]: info: [1724961061670] CoreMusicLibrary::Adding element Media Servers Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:01 volumio sudo[14044]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:01 volumio sudo[14047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:51:01 volumio sudo[14047]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:01 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 21:51:01 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:51:01 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:51:01 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:51:01 volumio volumio[13873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:01 volumio sudo[14050]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:51:01 volumio sudo[14050]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:01 volumio volumio[13873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:51:01 volumio volumio[13873]: info: [1724961061809] CoreMusicLibrary::Adding element Last_100 Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:01 volumio sudo[14050]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:51:01 volumio volumio[13873]: info: [1724961061813] CoreMusicLibrary::Adding element Webradio Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:51:01 volumio volumio[13873]: info: Initializing BBC Radios Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:01 volumio volumio[13873]: info: Creating Spotify config file Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:51:01 volumio volumio[13873]: info: [1724961061967] CoreMusicLibrary::Adding element YouTube Music Aug 29 21:51:01 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:01 volumio volumio[13873]: Cannot find translation for source YouTube Music Aug 29 21:51:01 volumio volumio[13873]: info: [now-playing] ConfigUpdater: config is up to date. Aug 29 21:51:01 volumio volumio[13873]: info: Loading i18n strings for locale en Aug 29 21:51:02 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 21:51:02 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:02 volumio volumio[13873]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 21:51:02 volumio volumio[13873]: info: Loading i18n strings for locale en Aug 29 21:51:02 volumio volumio[13873]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:51:02 volumio volumio[13873]: info: [1724961062051] CoreMusicLibrary::Adding element Radio Paradise Aug 29 21:51:02 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:02 volumio volumio[13873]: Cannot find translation for source YouTube Music Aug 29 21:51:02 volumio volumio[13873]: Cannot find translation for source Radio Paradise Aug 29 21:51:02 volumio volumio[13873]: info: Loading i18n strings for locale en Aug 29 21:51:02 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:51:02 volumio volumio[13873]: info: Volumio Calling Home Aug 29 21:51:02 volumio volumio[13873]: info: Preparing to generate the ALSA configuration file Aug 29 21:51:02 volumio sudo[14074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 21:51:02 volumio sudo[14074]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:02 volumio systemd[1]: Reloading. Aug 29 21:51:02 volumio volumio[13873]: info: [now-playing] Fetching API key... Aug 29 21:51:02 volumio volumio[13873]: info: [now-playing] App is listening on port 4004. Aug 29 21:51:02 volumio volumio[13873]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 29 21:51:02 volumio volumio[13873]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:51:02 volumio volumio[13873]: info: Reading ALSA contributions from plugins. Aug 29 21:51:02 volumio volumio[13873]: info: minidlna: Found miniDLNA version 1.2.1 Aug 29 21:51:02 volumio volumio[13873]: info: minidlna: Starting minidlna.service Aug 29 21:51:02 volumio volumio[13873]: info: MPD Permissions set Aug 29 21:51:02 volumio volumio[13873]: info: MPD Permissions set Aug 29 21:51:02 volumio sudo[14090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 29 21:51:02 volumio sudo[14090]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:02 volumio volumio[13873]: info: Volumio called home Aug 29 21:51:02 volumio volumio[13873]: info: Spotify config file written Aug 29 21:51:02 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 21:51:02 volumio sudo[14093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 21:51:02 volumio sudo[14093]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:02 volumio mpd[14057]: Aug 29 21:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:51:03 volumio systemd[1]: Started Music Player Daemon. Aug 29 21:51:03 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:51:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 175. Aug 29 21:51:03 volumio sudo[14037]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:03 volumio sudo[14047]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:03 volumio sudo[14074]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:03 volumio systemd[1]: Stopping go-librespot Daemon... Aug 29 21:51:03 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:51:03 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:51:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Aug 29 21:51:03 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Aug 29 21:51:03 volumio systemd[1]: Stopped go-librespot Daemon. Aug 29 21:51:03 volumio systemd[1]: Started go-librespot Daemon. Aug 29 21:51:03 volumio sudo[14090]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:03 volumio sudo[14093]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:03 volumio go-librespot[14098]: Librespot-go daemon starting... Aug 29 21:51:03 volumio go-librespot[14098]: time="2024-08-29T21:51:03+02:00" level=info msg="generated new device id: 4ee6da3a220070f19267cb361467f16d9d8b3150" Aug 29 21:51:03 volumio go-librespot[14098]: time="2024-08-29T21:51:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 21:51:03 volumio go-librespot[14098]: time="2024-08-29T21:51:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 21:51:03 volumio go-librespot[14098]: time="2024-08-29T21:51:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 29 21:51:03 volumio go-librespot[14098]: time="2024-08-29T21:51:03+02:00" level=debug msg="zeroconf server listening on port 40101" Aug 29 21:51:03 volumio volumio[13873]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:03 volumio volumio[14097]: Traceback (most recent call last): Aug 29 21:51:03 volumio volumio[14097]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:51:03 volumio volumio[14097]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:51:03 volumio volumio[14097]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:03 volumio volumio[13873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:04 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:51:04 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:51:04 volumio volumio[13873]: info: Starting Uninstall of plugin audio_interface - fusiondsp Aug 29 21:51:04 volumio volumio[13873]: info: Uninstalling plugin fusiondsp Aug 29 21:51:04 volumio volumio[13873]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:51:04 volumio volumio[13873]: TypeError: Cannot read property 'off' of undefined Aug 29 21:51:04 volumio volumio[13873]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:106:15) Aug 29 21:51:04 volumio volumio[13873]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 29 21:51:04 volumio volumio[13873]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Aug 29 21:51:04 volumio volumio[13873]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1465:22) Aug 29 21:51:04 volumio volumio[13873]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1246:45) Aug 29 21:51:04 volumio volumio[13873]: at Socket.emit (events.js:315:20) Aug 29 21:51:04 volumio volumio[13873]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 29 21:51:04 volumio volumio[13873]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 29 21:51:04 volumio volumio[13873]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:51:04 volumio sudo[14123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 21:50 Aug 29 21:51:04 volumio sudo[14123]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:04 volumio sudo[14123]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:05 volumio volumio-remote-updater[647]: [2024-08-29 21:51:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 21:51:05 volumio volumio-remote-updater[647]: [2024-08-29 21:51:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 21:51:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:51:05 volumio ntfs-3g[13964]: Unmounting /dev/sda1 (USB STICK) Aug 29 21:51:05 volumio systemd[984]: media-USB_STICK.mount: Succeeded. Aug 29 21:51:05 volumio systemd[1]: media-USB_STICK.mount: Succeeded. Aug 29 21:51:05 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 21:51:05 volumio systemd[1]: Started dynamicswap service. Aug 29 21:51:05 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 21:51:05 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 21:51:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 43. Aug 29 21:51:05 volumio systemd[1]: Started dynamicswap service. Aug 29 21:51:05 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 21:51:05 volumio systemd[1]: Started Volumio Backend Module. Aug 29 21:51:05 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 21:51:06 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:51:06 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 176. Aug 29 21:51:06 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:51:06 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:51:06 volumio volumio[14139]: info: ------------------------------------------- Aug 29 21:51:06 volumio volumio[14139]: info: ----- Volumio3 ---- Aug 29 21:51:06 volumio volumio[14139]: info: ------------------------------------------- Aug 29 21:51:06 volumio volumio[14139]: info: ----- System startup ---- Aug 29 21:51:06 volumio volumio[14139]: info: ------------------------------------------- Aug 29 21:51:07 volumio volumio[14151]: Traceback (most recent call last): Aug 29 21:51:07 volumio volumio[14151]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:51:07 volumio volumio[14151]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:51:07 volumio volumio[14151]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:51:07 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:51:07 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:51:07 volumio volumio[14139]: info: MYVOLUMIO Environment detected Aug 29 21:51:07 volumio volumio[14139]: info: Plugin folders cleanup Aug 29 21:51:07 volumio volumio[14139]: info: Scanning into folder /volumio/app/plugins/ Aug 29 21:51:07 volumio volumio[14139]: info: Scanning category audio_interface Aug 29 21:51:07 volumio volumio[14139]: info: Scanning category miscellanea Aug 29 21:51:07 volumio volumio[14139]: info: Scanning category music_service Aug 29 21:51:07 volumio volumio[14139]: info: Scanning category plugins.json Aug 29 21:51:07 volumio volumio[14139]: info: Scanning category system_controller Aug 29 21:51:07 volumio volumio[14139]: info: Scanning category user_interface Aug 29 21:51:07 volumio volumio[14139]: info: Scanning into folder /data/plugins/ Aug 29 21:51:07 volumio volumio[14139]: info: Scanning category audio_interface Aug 29 21:51:07 volumio volumio[14139]: info: Scanning category music_service Aug 29 21:51:07 volumio volumio[14139]: info: Scanning category user_interface Aug 29 21:51:07 volumio volumio[14139]: info: Plugin folders cleanup completed Aug 29 21:51:07 volumio volumio[14139]: info: ------------------------------------------- Aug 29 21:51:07 volumio volumio[14139]: info: ----- Core plugins startup ---- Aug 29 21:51:07 volumio volumio[14139]: info: ------------------------------------------- Aug 29 21:51:07 volumio volumio[14139]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 21:51:07 volumio volumio[14139]: info: Adding plugin upnp to MyMusic Plugins Aug 29 21:51:07 volumio volumio[14139]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 21:51:07 volumio volumio[14139]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 21:51:07 volumio volumio[14139]: info: Loading plugins from folder /data/plugins/ Aug 29 21:51:07 volumio volumio[14139]: info: Loading plugin "system"... Aug 29 21:51:07 volumio volumio[14139]: info: Loading plugin "appearance"... Aug 29 21:51:07 volumio volumio[14139]: info: Loading plugin "network"... Aug 29 21:51:07 volumio volumio[14139]: info: Refreshing Cached IP Addresses Aug 29 21:51:07 volumio sudo[14166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 21:51:07 volumio sudo[14166]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:07 volumio sudo[14166]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:07 volumio sudo[14168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:51:07 volumio volumio[14139]: info: Loading plugin "services"... Aug 29 21:51:07 volumio sudo[14168]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:07 volumio volumio[14139]: info: Loading plugin "alsa_controller"... Aug 29 21:51:07 volumio sudo[14168]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:07 volumio sudo[14171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 21:51:07 volumio sudo[14171]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:08 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "wizard"... Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "networkfs"... Aug 29 21:51:08 volumio volumio[14139]: info: Starting Udev Watcher for removable devices Aug 29 21:51:08 volumio volumio[14139]: info: Ignoring mount for partition: boot Aug 29 21:51:08 volumio volumio[14139]: info: Ignoring mount for partition: volumio Aug 29 21:51:08 volumio volumio[14139]: info: Ignoring mount for partition: volumio_data Aug 29 21:51:08 volumio volumio[14139]: info: Mounting Device USB_STICK Aug 29 21:51:08 volumio sudo[14182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/USB_STICK -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 21:51:08 volumio sudo[14182]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:08 volumio ntfs-3g[14185]: Version 2017.3.23AR.3 integrated FUSE 28 Aug 29 21:51:08 volumio ntfs-3g[14185]: Mounted /dev/sda1 (Read-Write, label "USB STICK", NTFS 3.1) Aug 29 21:51:08 volumio ntfs-3g[14185]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 21:51:08 volumio ntfs-3g[14185]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Aug 29 21:51:08 volumio ntfs-3g[14185]: Global ownership and permissions enforced, configuration type 7 Aug 29 21:51:08 volumio sudo[14182]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:08 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "volumio_command_line_client"... Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "upnp"... Aug 29 21:51:08 volumio volumio[14139]: info: [1724961068314] Starting Upmpd Daemon Aug 29 21:51:08 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "my_music"... Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "mpd"... Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "upnp_browser"... Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "alarm-clock"... Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "airplay_emulation"... Aug 29 21:51:08 volumio volumio[14139]: info: Starting Shairport Sync Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "last_100"... Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "webradio"... Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "i2s_dacs"... Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "volumiodiscovery"... Aug 29 21:51:08 volumio volumio[14139]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:51:08 volumio volumio[14139]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:51:08 volumio volumio[14139]: *** WARNING *** For more information see Aug 29 21:51:08 volumio volumio[14139]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:51:08 volumio volumio[14139]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:51:08 volumio volumio[14139]: *** WARNING *** For more information see Aug 29 21:51:08 volumio node[14139]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 21:51:08 volumio node[14139]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:51:08 volumio node[14139]: *** WARNING *** For more information see Aug 29 21:51:08 volumio node[14139]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 21:51:08 volumio node[14139]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 21:51:08 volumio node[14139]: *** WARNING *** For more information see Aug 29 21:51:08 volumio volumio[14139]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 21:51:08 volumio volumio[14139]: info: Discovery: Started advertising with name: Volumio Aug 29 21:51:08 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:51:08 volumio volumio[14139]: info: Loading plugin "spop"... Aug 29 21:51:09 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:51:09 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 177. Aug 29 21:51:09 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:51:09 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:51:09 volumio volumio[14139]: info: Loading plugin "ytcr"... Aug 29 21:51:10 volumio volumio-remote-updater[647]: [2024-08-29 21:51:10] [connect] Successful connection Aug 29 21:51:10 volumio volumio[14188]: Traceback (most recent call last): Aug 29 21:51:10 volumio volumio[14188]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:51:10 volumio volumio[14188]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:51:10 volumio volumio[14188]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:51:10 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:51:10 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:51:11 volumio volumio[14139]: info: Loading plugin "ytmusic"... Aug 29 21:51:11 volumio sudo[14171]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:11 volumio volumio[14139]: info: Loading plugin "now_playing"... Aug 29 21:51:12 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:51:12 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 178. Aug 29 21:51:12 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:51:12 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:51:12 volumio volumio[14139]: info: Loading plugin "outputs"... Aug 29 21:51:12 volumio volumio[14139]: info: Loading plugin "albumart"... Aug 29 21:51:12 volumio volumio[14139]: info: Plugin example_plugin is not enabled Aug 29 21:51:12 volumio volumio[14139]: info: Loading plugin "inputs"... Aug 29 21:51:12 volumio volumio[14139]: info: Loading plugin "updater_comm"... Aug 29 21:51:12 volumio volumio[14139]: info: Plugin mpdemulation is not enabled Aug 29 21:51:12 volumio volumio[14139]: info: Loading plugin "rest_api"... Aug 29 21:51:13 volumio volumio[14139]: info: Loading plugin "websocket"... Aug 29 21:51:13 volumio volumio[14139]: info: Starting Socket.io Server version 2.3.0 Aug 29 21:51:13 volumio volumio[14139]: info: Loading plugin "fusiondsp"... Aug 29 21:51:13 volumio volumio[14139]: Forking 3 albumart workers Aug 29 21:51:13 volumio volumio[14139]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 21:51:13 volumio volumio[14139]: info: Loading plugin "minidlna"... Aug 29 21:51:13 volumio volumio[14189]: Traceback (most recent call last): Aug 29 21:51:13 volumio volumio[14189]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:51:13 volumio volumio[14189]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:51:13 volumio volumio[14189]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:51:13 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:51:13 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:51:13 volumio volumio[14139]: info: Applying required configuration parameters for plugin minidlna Aug 29 21:51:13 volumio volumio[14139]: info: Loading plugin "radio_paradise"... Aug 29 21:51:13 volumio volumio[14139]: info: Applying required configuration parameters for plugin radio_paradise Aug 29 21:51:13 volumio volumio[14139]: info: [1724961073796] [RadioParadise] API delay: 5 Aug 29 21:51:13 volumio volumio[14139]: info: Loading plugin "Systeminfo"... Aug 29 21:51:13 volumio volumio[14139]: Starting albumart workers Aug 29 21:51:13 volumio volumio[14139]: Starting albumart workers Aug 29 21:51:14 volumio volumio[14139]: Starting albumart workers Aug 29 21:51:14 volumio volumio[14139]: info: Loading plugin "touch_display"... Aug 29 21:51:14 volumio volumio[14139]: info: Applying required configuration parameters for plugin touch_display Aug 29 21:51:14 volumio volumio[14139]: info: Loading i18n strings for locale en Aug 29 21:51:14 volumio volumio[14139]: Updating browse sources language Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::initPlayerControls Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:51:14 volumio volumio[14139]: Express server listening on port 3000 Aug 29 21:51:14 volumio volumio[14139]: [Metrics] WebUI: 8s 303.29ms Aug 29 21:51:14 volumio volumio[14139]: info: CoreStateMachine::resetVolumioState Aug 29 21:51:14 volumio volumio[14139]: info: CoreStateMachine::getcurrentVolume Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:51:14 volumio volumio[14139]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 21:51:14 volumio volumio[14139]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 29 21:51:14 volumio volumio-remote-updater[647]: [2024-08-29 21:51:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724961070 101 Aug 29 21:51:14 volumio volumio[14139]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Aug 29 21:51:14 volumio volumio[14139]: verbose: New Socket.io Connection to 192.168.0.12 from 192.168.0.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:51:14 volumio volumio[14139]: info: Discovery: Getting this device information Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:14 volumio volumio[14139]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:51:14 volumio volumio[14139]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:14 volumio volumio[14139]: info: Reloading queue from file Aug 29 21:51:14 volumio volumio[14139]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:51:14 volumio volumio[14139]: info: CoreStateMachine::pushState Aug 29 21:51:14 volumio volumio[14139]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::volumioPushState Aug 29 21:51:14 volumio volumio[14139]: info: CoreStateMachine::updateTrackBlock Aug 29 21:51:14 volumio volumio[14139]: info: CorePlayQueue::getTrackBlock Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:51:14 volumio volumio[14139]: info: CoreStateMachine::setRepeat null single undefined Aug 29 21:51:14 volumio volumio[14139]: info: CoreStateMachine::pushState Aug 29 21:51:14 volumio volumio[14139]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::volumioPushState Aug 29 21:51:14 volumio volumio[14139]: info: CoreStateMachine::setRandom null Aug 29 21:51:14 volumio volumio[14139]: info: CoreStateMachine::pushState Aug 29 21:51:14 volumio volumio[14139]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:14 volumio volumio[14139]: info: CoreCommandRouter::volumioPushState Aug 29 21:51:14 volumio volumio[14139]: info: Setting Device type: Raspberry PI Aug 29 21:51:14 volumio volumio[14139]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 21:51:14 volumio volumio[14139]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Aug 29 21:51:14 volumio volumio[14139]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 21:51:14 volumio volumio[14139]: info: Completed loading Core Plugins Aug 29 21:51:14 volumio volumio[14139]: info: Preparing to generate the ALSA configuration file Aug 29 21:51:14 volumio volumio[14139]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:51:14 volumio volumio[14139]: info: Reading ALSA contributions from plugins. Aug 29 21:51:15 volumio volumio[14139]: verbose: New Socket.io Connection to 192.168.0.12:3000 from 192.168.0.8 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:15 volumio volumio[14139]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:51:15 volumio volumio[14139]: info: VolumeController:: Volume=0 Mute =false Aug 29 21:51:15 volumio volumio[14139]: info: CoreStateMachine::pushState Aug 29 21:51:15 volumio volumio[14139]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::volumioPushState Aug 29 21:51:15 volumio volumio[14139]: info: Discovery: adding a69d09fe-a052-4dd2-a613-4db4de2e2bb3 Aug 29 21:51:15 volumio volumio[14139]: info: Discovery: Found device Volumio Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:15 volumio volumio[14139]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:15 volumio volumio[14139]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:51:15 volumio volumio[14139]: info: Output device has changed, restarting MPD Aug 29 21:51:15 volumio sudo[14255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:51:15 volumio sudo[14255]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:15 volumio sudo[14255]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:15 volumio volumio[14139]: info: Output device has changed, restarting Shairport Sync Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:15 volumio sudo[14258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:51:15 volumio sudo[14258]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:15 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 21:51:15 volumio volumio[14139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:51:15 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:51:15 volumio volumio[14139]: info: ___________ START PLUGINS ___________ Aug 29 21:51:15 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:51:15 volumio volumio[14139]: info: ControllerMpd::onStart: Initializing MPD Aug 29 21:51:15 volumio volumio[14139]: info: Creating MPD Configuration file Aug 29 21:51:15 volumio sudo[14265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:51:15 volumio sudo[14265]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:51:15 volumio volumio[14139]: info: [1724961075266] CoreMusicLibrary::Adding element Media Servers Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:15 volumio sudo[14265]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:15 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:51:15 volumio sudo[14268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:51:15 volumio sudo[14268]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:15 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 21:51:15 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:51:15 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:51:15 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:51:15 volumio volumio[14139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:15 volumio sudo[14271]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:51:15 volumio sudo[14271]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:15 volumio volumio[14139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:51:15 volumio volumio[14139]: info: [1724961075402] CoreMusicLibrary::Adding element Last_100 Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:15 volumio sudo[14271]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:51:15 volumio volumio[14139]: info: [1724961075405] CoreMusicLibrary::Adding element Webradio Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:51:15 volumio volumio[14139]: info: Initializing BBC Radios Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:15 volumio volumio[14139]: info: Creating Spotify config file Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:51:15 volumio volumio[14139]: info: [1724961075571] CoreMusicLibrary::Adding element YouTube Music Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:15 volumio volumio[14139]: Cannot find translation for source YouTube Music Aug 29 21:51:15 volumio volumio[14139]: info: [now-playing] ConfigUpdater: config is up to date. Aug 29 21:51:15 volumio volumio[14139]: info: Loading i18n strings for locale en Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:15 volumio volumio[14139]: info: FusionDsp - mixtype--------------------- Hardware Aug 29 21:51:15 volumio volumio[14139]: info: Loading i18n strings for locale en Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 21:51:15 volumio volumio[14139]: info: [1724961075656] CoreMusicLibrary::Adding element Radio Paradise Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:51:15 volumio volumio[14139]: Cannot find translation for source YouTube Music Aug 29 21:51:15 volumio volumio[14139]: Cannot find translation for source Radio Paradise Aug 29 21:51:15 volumio volumio[14139]: info: Loading i18n strings for locale en Aug 29 21:51:15 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Aug 29 21:51:15 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 179. Aug 29 21:51:15 volumio systemd[1]: Stopped FusionDsp Daemon. Aug 29 21:51:15 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:51:15 volumio volumio[14139]: info: Volumio Calling Home Aug 29 21:51:15 volumio systemd[1]: Started FusionDsp Daemon. Aug 29 21:51:15 volumio volumio[14139]: info: Preparing to generate the ALSA configuration file Aug 29 21:51:15 volumio sudo[14297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 21:51:15 volumio sudo[14297]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:15 volumio systemd[1]: Reloading. Aug 29 21:51:16 volumio volumio[14139]: info: [now-playing] Fetching API key... Aug 29 21:51:16 volumio volumio[14139]: info: [now-playing] App is listening on port 4004. Aug 29 21:51:16 volumio volumio[14139]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 29 21:51:16 volumio volumio[14139]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 21:51:16 volumio volumio[14139]: info: Reading ALSA contributions from plugins. Aug 29 21:51:16 volumio volumio[14139]: info: minidlna: Found miniDLNA version 1.2.1 Aug 29 21:51:16 volumio volumio[14139]: info: minidlna: Starting minidlna.service Aug 29 21:51:16 volumio volumio[14139]: info: MPD Permissions set Aug 29 21:51:16 volumio volumio[14139]: info: MPD Permissions set Aug 29 21:51:16 volumio volumio[14139]: info: Volumio called home Aug 29 21:51:16 volumio volumio[14139]: info: Spotify config file written Aug 29 21:51:16 volumio sudo[14312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 29 21:51:16 volumio sudo[14312]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:16 volumio sudo[14316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 29 21:51:16 volumio sudo[14316]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:16 volumio volumio[14139]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:16 volumio mpd[14279]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:51:16 volumio mpd[14279]: output: No 'audio_output' defined in config file Aug 29 21:51:16 volumio mpd[14279]: output: Successfully detected a sndio audio device Aug 29 21:51:16 volumio mpd[14279]: zeroconf: No global port, disabling zeroconf Aug 29 21:51:16 volumio volumio[14139]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 21:51:16 volumio volumio[14139]: info: Starting Shairport Sync Aug 29 21:51:16 volumio volumio[14139]: info: Starting Shairport Sync Aug 29 21:51:16 volumio sudo[14329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:51:16 volumio sudo[14329]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:16 volumio volumio[14139]: info: Starting Shairport Sync Aug 29 21:51:16 volumio sudo[14331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:51:16 volumio sudo[14331]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:16 volumio volumio[14292]: Traceback (most recent call last): Aug 29 21:51:16 volumio volumio[14292]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Aug 29 21:51:16 volumio volumio[14292]: from camilladsp_plot.validate_config import CamillaValidator Aug 29 21:51:16 volumio volumio[14292]: ModuleNotFoundError: No module named 'camilladsp_plot' Aug 29 21:51:16 volumio sudo[14335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:51:16 volumio sudo[14335]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:16 volumio volumio[14139]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:16 volumio volumio[14139]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:16 volumio systemd[1]: Started Music Player Daemon. Aug 29 21:51:16 volumio sudo[14297]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:16 volumio volumio[14139]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:51:16 volumio sudo[14268]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:16 volumio volumio[14139]: info: Output device has changed, restarting MPD Aug 29 21:51:16 volumio sudo[14258]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:17 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Aug 29 21:51:17 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Aug 29 21:51:17 volumio sudo[14338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:51:17 volumio sudo[14338]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:17 volumio sudo[14338]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:17 volumio volumio[14139]: info: Output device has changed, restarting Shairport Sync Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 21:51:17 volumio systemd[1]: Stopping go-librespot Daemon... Aug 29 21:51:17 volumio sudo[14341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:51:17 volumio sudo[14341]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:17 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 21:51:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 21:51:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Aug 29 21:51:17 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Aug 29 21:51:17 volumio systemd[1]: Stopped go-librespot Daemon. Aug 29 21:51:17 volumio sudo[14312]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:17 volumio volumio[14139]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:51:17 volumio systemd[1]: Started go-librespot Daemon. Aug 29 21:51:17 volumio go-librespot[14346]: Librespot-go daemon starting... Aug 29 21:51:17 volumio volumio[14139]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 21:51:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 21:51:17 volumio sudo[14316]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:17 volumio sudo[14329]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:17 volumio sudo[14335]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:17 volumio go-librespot[14346]: time="2024-08-29T21:51:17+02:00" level=info msg="generated new device id: 33820cef6b8d4808414a73e3bb5b701e4253e1c9" Aug 29 21:51:17 volumio sudo[14331]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:17 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 21:51:17 volumio sudo[14357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 21:51:17 volumio sudo[14357]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:17 volumio systemd[1]: mpd.service: Succeeded. Aug 29 21:51:17 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 21:51:17 volumio volumio[14139]: info: MPD Permissions set Aug 29 21:51:17 volumio volumio[14139]: info: Shairport-Sync Started Aug 29 21:51:17 volumio volumio[14139]: Error adding Membership: Error: addMembership EINVAL Aug 29 21:51:17 volumio volumio[14139]: info: Shairport-Sync Started Aug 29 21:51:17 volumio volumio[14139]: info: Shairport-Sync Started Aug 29 21:51:17 volumio volumio[14139]: info: minidlna: systemctl start minidlna.service succeeded. Aug 29 21:51:17 volumio systemd[1]: Starting Music Player Daemon... Aug 29 21:51:17 volumio sudo[14357]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:17 volumio volumio[14139]: error: Cannot initialize MPD Connection: MPD is not running Aug 29 21:51:17 volumio volumio[14139]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 21:51:17 volumio volumio[14139]: info: touch_display: Volumio Kiosk started Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:17 volumio volumio[14139]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:17 volumio go-librespot[14346]: time="2024-08-29T21:51:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 29 21:51:17 volumio go-librespot[14346]: time="2024-08-29T21:51:17+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 29 21:51:17 volumio go-librespot[14346]: time="2024-08-29T21:51:17+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 29 21:51:17 volumio go-librespot[14346]: time="2024-08-29T21:51:17+02:00" level=debug msg="zeroconf server listening on port 43763" Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:17 volumio sudo[14362]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:51:17 volumio sudo[14362]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:17 volumio sudo[14362]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::volumioGetState Aug 29 21:51:17 volumio volumio[14139]: info: CorePlayQueue::getTrack 0 Aug 29 21:51:17 volumio volumio[14139]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 21:51:17 volumio volumio[14139]: info: Starting Shairport Sync Aug 29 21:51:17 volumio sudo[14374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:51:17 volumio sudo[14374]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 21:51:17 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 21:51:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 21:51:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 21:51:17 volumio sudo[14374]: pam_unix(sudo:session): session closed for user root Aug 29 21:51:17 volumio volumio[14139]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 29 21:51:17 volumio volumio[14139]: info: Shairport-Sync Started Aug 29 21:51:17 volumio volumio[14139]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 21:51:17 volumio volumio[14139]: 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 21:51:17 volumio volumio[14139]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 21:51:17 volumio volumio[14139]: 0+0 records in Aug 29 21:51:17 volumio volumio[14139]: 0+0 records out Aug 29 21:51:17 volumio volumio[14139]: 0 bytes copied, 0.000128313 s, 0.0 kB/s Aug 29 21:51:17 volumio volumio[14139]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 21:51:17 volumio volumio[14139]: info: camilladsp service started and running in background, instance 1 Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:51:17 volumio volumio[14139]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 21:51:17 volumio volumio[14139]: 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 21:51:17 volumio volumio[14139]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 21:51:17 volumio volumio[14139]: info: FusionDsp loaded Aug 29 21:51:17 volumio volumio[14139]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:51:17 volumio sudo[14395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 21:51:17 volumio sudo[14395]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:17 volumio volumio[14139]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 21:51:17 volumio volumio[14139]: info: Adding Signal Path Element [object Object] Aug 29 21:51:17 volumio volumio[14139]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 21:51:17 volumio volumio[14139]: info: FusionDsp - ---- installed callbackRead Aug 29 21:51:17 volumio volumio[14139]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:51:17 volumio volumio[14139]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 21:51:17 volumio volumio[14139]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 21:51:17 volumio volumio[14139]: at onErrorNT (internal/child_process.js:465:16) Aug 29 21:51:17 volumio volumio[14139]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Aug 29 21:51:17 volumio volumio[14139]: errno: -2, Aug 29 21:51:17 volumio volumio[14139]: code: 'ENOENT', Aug 29 21:51:17 volumio volumio[14139]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 21:51:17 volumio volumio[14139]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 21:51:17 volumio volumio[14139]: spawnargs: [ Aug 29 21:51:17 volumio volumio[14139]: '-p', Aug 29 21:51:17 volumio volumio[14139]: 9876, Aug 29 21:51:17 volumio volumio[14139]: '-o', Aug 29 21:51:17 volumio volumio[14139]: '/tmp/camilladsp.log', Aug 29 21:51:17 volumio volumio[14139]: '-l', Aug 29 21:51:17 volumio volumio[14139]: 'warn', Aug 29 21:51:17 volumio volumio[14139]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 21:51:17 volumio volumio[14139]: ] Aug 29 21:51:17 volumio volumio[14139]: } Aug 29 21:51:17 volumio volumio[14139]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:51:18 volumio sudo[14406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 21:50 Aug 29 21:51:18 volumio sudo[14406]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 21:51:18 volumio mpd[14372]: Aug 29 21:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:51:18 volumio systemd[1]: Started Music Player Daemon. Aug 29 21:51:18 volumio sudo[14341]: pam_unix(sudo:session): session closed for user root 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"