-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Tue 2025-04-22 15:49:38 EDT. -- Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "upnp_browser"... Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "alarm-clock"... Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "airplay_emulation"... Apr 22 15:48:00 volumio volumio[879]: info: Starting Shairport Sync Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "last_100"... Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "webradio"... Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "i2s_dacs"... Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "volumiodiscovery"... Apr 22 15:48:00 volumio volumio[879]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:00 volumio volumio[879]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:00 volumio node[879]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:00 volumio volumio[879]: *** WARNING *** For more information see Apr 22 15:48:00 volumio volumio[879]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:00 volumio volumio[879]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:00 volumio volumio[879]: *** WARNING *** For more information see Apr 22 15:48:00 volumio node[879]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:00 volumio node[879]: *** WARNING *** For more information see Apr 22 15:48:00 volumio node[879]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:00 volumio node[879]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:00 volumio node[879]: *** WARNING *** For more information see Apr 22 15:48:00 volumio volumio[879]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 22 15:48:00 volumio volumio[879]: info: Discovery: Started advertising with name: Volumio Apr 22 15:48:00 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "outputs"... Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "albumart"... Apr 22 15:48:00 volumio volumio[879]: info: Plugin example_plugin is not enabled Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "inputs"... Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "updater_comm"... Apr 22 15:48:00 volumio volumio[879]: info: Plugin mpdemulation is not enabled Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "rest_api"... Apr 22 15:48:00 volumio volumio[879]: info: Loading plugin "websocket"... Apr 22 15:48:01 volumio volumio[879]: info: Starting Socket.io Server version 2.3.0 Apr 22 15:48:01 volumio volumio[879]: info: Loading plugin "fusiondsp"... Apr 22 15:48:01 volumio volumio[879]: Forking 3 albumart workers Apr 22 15:48:01 volumio volumio[879]: info: Applying required configuration parameters for plugin fusiondsp Apr 22 15:48:01 volumio volumio[879]: Starting albumart workers Apr 22 15:48:01 volumio volumio[879]: info: Loading plugin "onkyo_control"... Apr 22 15:48:01 volumio volumio[879]: Starting albumart workers Apr 22 15:48:01 volumio volumio[879]: Starting albumart workers Apr 22 15:48:02 volumio volumio[879]: info: Applying required configuration parameters for plugin onkyo_control Apr 22 15:48:02 volumio volumio[879]: info: Loading plugin "randomizer"... Apr 22 15:48:02 volumio volumio[879]: info: Applying required configuration parameters for plugin randomizer Apr 22 15:48:02 volumio volumio[879]: info: Loading i18n strings for locale en Apr 22 15:48:02 volumio volumio[879]: Updating browse sources language Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:48:02 volumio sh[575]: eth0: probing for an IPv4LL address Apr 22 15:48:02 volumio dhcpcd[624]: eth0: probing for an IPv4LL address Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::initPlayerControls Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 15:48:02 volumio volumio[879]: Express server listening on port 3000 Apr 22 15:48:02 volumio volumio[879]: [Metrics] WebUI: 6s 115.85ms Apr 22 15:48:02 volumio volumio[879]: info: CoreStateMachine::resetVolumioState Apr 22 15:48:02 volumio volumio[879]: info: CoreStateMachine::getcurrentVolume Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::volumioRetrievevolume Apr 22 15:48:02 volumio volumio[879]: info: Volumio Network Manager: Network status updated: 0 Apr 22 15:48:02 volumio volumio[879]: info: Cannot mount NAS public at system boot, trial number 1 ,retrying in 5 seconds Apr 22 15:48:02 volumio volumio-remote-updater[654]: [2025-04-22 15:48:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745351277 101 Apr 22 15:48:02 volumio volumio[879]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 22 15:48:02 volumio volumio[879]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 22 15:48:02 volumio volumio[879]: info: Scanning new location : "USB/writable" Apr 22 15:48:02 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:02 volumio volumio[879]: info: Reloading queue from file Apr 22 15:48:03 volumio volumio[879]: info: VolumeController:: Volume=100 Mute =false Apr 22 15:48:03 volumio volumio[879]: info: CoreStateMachine::pushState Apr 22 15:48:03 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Apr 22 15:48:03 volumio volumio[879]: info: CoreStateMachine::updateTrackBlock Apr 22 15:48:03 volumio volumio[879]: info: CorePlayQueue::getTrackBlock Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::volumioRetrievevolume Apr 22 15:48:03 volumio volumio-time-update[652]: volumio-time-update-util: Fetching time from Volumio... Apr 22 15:48:03 volumio volumio[879]: info: CoreStateMachine::setRepeat false single undefined Apr 22 15:48:03 volumio volumio[879]: info: CoreStateMachine::pushState Apr 22 15:48:03 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Apr 22 15:48:03 volumio volumio[879]: info: CoreStateMachine::setRandom undefined Apr 22 15:48:03 volumio volumio[879]: info: CoreStateMachine::pushState Apr 22 15:48:03 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Apr 22 15:48:03 volumio volumio[879]: info: Setting Device type: Raspberry PI Apr 22 15:48:03 volumio volumio-time-update[652]: volumio-time-update-util: Date not found in response Apr 22 15:48:03 volumio volumio-time-update[652]: volumio-time-update-util: Retrying in 5 seconds... Apr 22 15:48:03 volumio volumio[879]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 22 15:48:03 volumio volumio[879]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Apr 22 15:48:03 volumio volumio[879]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 22 15:48:03 volumio volumio[879]: info: VolumeController:: Volume=100 Mute =false Apr 22 15:48:03 volumio volumio[879]: info: CoreStateMachine::pushState Apr 22 15:48:03 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Apr 22 15:48:03 volumio volumio[879]: info: Completed loading Core Plugins Apr 22 15:48:03 volumio volumio[879]: info: Preparing to generate the ALSA configuration file Apr 22 15:48:03 volumio volumio[879]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 22 15:48:03 volumio volumio[879]: info: Reading ALSA contributions from plugins. Apr 22 15:48:03 volumio volumio[879]: info: Asound.conf file unchanged, so no further update is needed Apr 22 15:48:03 volumio volumio[879]: info: Output device has changed, restarting MPD Apr 22 15:48:03 volumio volumio[879]: info: Output device has changed, restarting Shairport Sync Apr 22 15:48:03 volumio sudo[1067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 15:48:03 volumio sudo[1067]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:03 volumio sudo[1067]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:03 volumio sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 15:48:03 volumio sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:03 volumio systemd[1]: Stopping Music Player Daemon... Apr 22 15:48:03 volumio volumio[879]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:48:03 volumio volumio[879]: info: ___________ START PLUGINS ___________ Apr 22 15:48:03 volumio volumio[879]: info: ControllerMpd::onStart: Initializing MPD Apr 22 15:48:03 volumio volumio[879]: info: Creating MPD Configuration file Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:48:03 volumio volumio[879]: info: [1745351283288] CoreMusicLibrary::Adding element Media Servers Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:48:03 volumio sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 15:48:03 volumio sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:03 volumio sudo[1087]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:03 volumio sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 15:48:03 volumio sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:03 volumio systemd[1]: mpd.service: Succeeded. Apr 22 15:48:03 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 15:48:03 volumio volumio[879]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:48:03 volumio volumio[879]: info: [1745351283370] CoreMusicLibrary::Adding element Last_100 Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:48:03 volumio volumio[879]: info: [1745351283373] CoreMusicLibrary::Adding element Webradio Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 15:48:03 volumio volumio[879]: info: Initializing BBC Radios Apr 22 15:48:03 volumio systemd[1]: Starting Music Player Daemon... Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: Loading i18n strings for locale en Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 22 15:48:03 volumio sudo[1095]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: FusionDsp - mixtype--------------------- Hardware Apr 22 15:48:03 volumio sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:03 volumio sudo[1095]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:48:03 volumio volumio[879]: info: [1745351283470] CoreMusicLibrary::Adding element Randomizer Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:48:03 volumio volumio[879]: Cannot find translation for source Randomizer Apr 22 15:48:03 volumio volumio[879]: info: Volumio Calling Home Apr 22 15:48:03 volumio volumio[879]: info: Preparing to generate the ALSA configuration file Apr 22 15:48:03 volumio volumio[879]: Unhandled rejection Error: No sockets available, cannot start. Apr 22 15:48:03 volumio volumio[879]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Apr 22 15:48:03 volumio volumio[879]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Apr 22 15:48:03 volumio volumio[879]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Apr 22 15:48:03 volumio volumio[879]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Apr 22 15:48:03 volumio volumio[879]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Apr 22 15:48:03 volumio volumio[879]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Apr 22 15:48:03 volumio volumio[879]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Apr 22 15:48:03 volumio volumio[879]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Apr 22 15:48:03 volumio volumio[879]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Apr 22 15:48:03 volumio volumio[879]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Apr 22 15:48:03 volumio volumio[879]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Apr 22 15:48:03 volumio volumio[879]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 22 15:48:03 volumio volumio[879]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 22 15:48:03 volumio volumio[879]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Apr 22 15:48:03 volumio volumio[879]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Apr 22 15:48:03 volumio volumio[879]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 22 15:48:03 volumio volumio[879]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 22 15:48:03 volumio volumio[879]: info: Reading ALSA contributions from plugins. Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Apr 22 15:48:03 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Apr 22 15:48:03 volumio volumio[879]: info: [ASDebug] CurState: stop PrevState: na Apr 22 15:48:03 volumio volumio[879]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 22 15:48:03 volumio volumio[879]: info: [ASDebug] CurState: stop PrevState: na Apr 22 15:48:03 volumio volumio[879]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 22 15:48:03 volumio volumio[879]: info: MPD Permissions set Apr 22 15:48:03 volumio volumio[879]: info: MPD Permissions set Apr 22 15:48:03 volumio volumio[879]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 22 15:48:03 volumio volumio[879]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 22 15:48:03 volumio volumio[879]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio volumio[879]: info: Starting Shairport Sync Apr 22 15:48:03 volumio volumio[879]: info: Starting Shairport Sync Apr 22 15:48:03 volumio volumio[879]: info: Starting Shairport Sync Apr 22 15:48:03 volumio sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:48:03 volumio sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:03 volumio sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Apr 22 15:48:03 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Apr 22 15:48:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 15:48:03 volumio sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:03 volumio sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:48:03 volumio sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:03 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 15:48:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 15:48:03 volumio volumio[879]: info: Asound.conf file unchanged, so no further update is needed Apr 22 15:48:03 volumio volumio[879]: info: Output device has changed, restarting MPD Apr 22 15:48:03 volumio volumio[879]: info: Output device has changed, restarting Shairport Sync Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:03 volumio sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 15:48:03 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 15:48:03 volumio sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:03 volumio sudo[1123]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:03 volumio sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 15:48:03 volumio sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:03 volumio sudo[1125]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:03 volumio sudo[1128]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:03 volumio sudo[1133]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:03 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 22 15:48:03 volumio systemd[1]: mpd.service: Succeeded. Apr 22 15:48:03 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 15:48:03 volumio systemd[1]: Starting Music Player Daemon... Apr 22 15:48:03 volumio volumio[879]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:48:04 volumio volumio[879]: info: MPD Permissions set Apr 22 15:48:04 volumio volumio[879]: info: Shairport-Sync Started Apr 22 15:48:04 volumio volumio[879]: Error adding Membership: Error: addMembership EINVAL Apr 22 15:48:04 volumio volumio[879]: info: Shairport-Sync Started Apr 22 15:48:04 volumio volumio[879]: info: Shairport-Sync Started Apr 22 15:48:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:04 volumio sudo[1143]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 15:48:04 volumio volumio[879]: info: Starting Shairport Sync Apr 22 15:48:04 volumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:04 volumio sudo[1143]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:04 volumio sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:48:04 volumio sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 15:48:04 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 15:48:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 15:48:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 15:48:04 volumio sudo[1151]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:04 volumio volumio[879]: info: Shairport-Sync Started Apr 22 15:48:04 volumio volumio[879]: info: [ASDebug] Togle GPIO: OFF Apr 22 15:48:04 volumio mpd[1150]: Apr 22 15:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 22 15:48:04 volumio systemd[1]: Started Music Player Daemon. Apr 22 15:48:04 volumio sudo[1069]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:04 volumio sudo[1089]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:04 volumio sudo[1135]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:04 volumio volumio[879]: error: MPD error: The expression evaluated to a falsy value: Apr 22 15:48:04 volumio volumio[879]: assert.ok(self.idling) Apr 22 15:48:04 volumio volumio[879]: error: The expression evaluated to a falsy value: Apr 22 15:48:04 volumio volumio[879]: assert.ok(self.idling) Apr 22 15:48:04 volumio volumio[879]: error: MPD error: The expression evaluated to a falsy value: Apr 22 15:48:04 volumio volumio[879]: assert.ok(self.idling) Apr 22 15:48:04 volumio volumio[879]: error: The expression evaluated to a falsy value: Apr 22 15:48:04 volumio volumio[879]: assert.ok(self.idling) Apr 22 15:48:04 volumio volumio[879]: info: MPD running with PID1150 Apr 22 15:48:04 volumio volumio[879]: ,establishing connection Apr 22 15:48:04 volumio volumio[879]: error: updateQueue error: null Apr 22 15:48:04 volumio volumio[879]: error: updateQueue error: null Apr 22 15:48:05 volumio volumio[879]: info: camilladsp service started and running in background, instance 1 Apr 22 15:48:05 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:05 volumio volumio[879]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 22 15:48:05 volumio volumio[879]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 22 15:48:05 volumio volumio[879]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 22 15:48:05 volumio volumio[879]: info: FusionDsp loaded Apr 22 15:48:05 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 22 15:48:05 volumio sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 22 15:48:05 volumio sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:05 volumio volumio[879]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 22 15:48:05 volumio volumio[879]: info: Adding Signal Path Element [object Object] Apr 22 15:48:05 volumio volumio[879]: info: Adding fusiondspeq DSP Signal Path Element Apr 22 15:48:05 volumio volumio[879]: info: FusionDsp - ---- installed callbackRead Apr 22 15:48:05 volumio volumio[879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 15:48:05 volumio volumio[879]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 22 15:48:05 volumio volumio[879]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 22 15:48:05 volumio volumio[879]: at onErrorNT (internal/child_process.js:465:16) Apr 22 15:48:05 volumio volumio[879]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Apr 22 15:48:05 volumio volumio[879]: errno: -2, Apr 22 15:48:05 volumio volumio[879]: code: 'ENOENT', Apr 22 15:48:05 volumio volumio[879]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 22 15:48:05 volumio volumio[879]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 22 15:48:05 volumio volumio[879]: spawnargs: [ Apr 22 15:48:05 volumio volumio[879]: '-p', Apr 22 15:48:05 volumio volumio[879]: 9876, Apr 22 15:48:05 volumio volumio[879]: '-o', Apr 22 15:48:05 volumio volumio[879]: '/tmp/camilladsp.log', Apr 22 15:48:05 volumio volumio[879]: '-l', Apr 22 15:48:05 volumio volumio[879]: 'warn', Apr 22 15:48:05 volumio volumio[879]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 22 15:48:05 volumio volumio[879]: ] Apr 22 15:48:05 volumio volumio[879]: } Apr 22 15:48:05 volumio volumio[879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 15:48:05 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:05 volumio sudo[1170]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:05 volumio volumio[1172]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:05 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:05 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:05 volumio sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 15:47 Apr 22 15:48:05 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:05 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:05 volumio volumio-remote-updater[654]: [2025-04-22 15:48:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 22 15:48:05 volumio volumio-remote-updater[654]: [2025-04-22 15:48:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 22 15:48:06 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 22 15:48:06 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 22 15:48:06 volumio systemd[1]: Started dynamicswap service. Apr 22 15:48:06 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 22 15:48:06 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 22 15:48:06 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Apr 22 15:48:06 volumio systemd[1]: Started dynamicswap service. Apr 22 15:48:06 volumio systemd[1]: Stopped Volumio Backend Module. Apr 22 15:48:06 volumio systemd[1]: Started Volumio Backend Module. Apr 22 15:48:06 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 22 15:48:07 volumio volumio[1196]: info: ------------------------------------------- Apr 22 15:48:07 volumio volumio[1196]: info: ----- Volumio3 ---- Apr 22 15:48:07 volumio volumio[1196]: info: ------------------------------------------- Apr 22 15:48:07 volumio volumio[1196]: info: ----- System startup ---- Apr 22 15:48:07 volumio volumio[1196]: info: ------------------------------------------- Apr 22 15:48:07 volumio volumio[1196]: info: MYVOLUMIO Environment detected Apr 22 15:48:07 volumio volumio[1196]: info: Plugin folders cleanup Apr 22 15:48:07 volumio volumio[1196]: info: Scanning into folder /volumio/app/plugins/ Apr 22 15:48:07 volumio volumio[1196]: info: Scanning category audio_interface Apr 22 15:48:07 volumio volumio[1196]: info: Scanning category miscellanea Apr 22 15:48:07 volumio volumio[1196]: info: Scanning category music_service Apr 22 15:48:07 volumio volumio[1196]: info: Scanning category plugins.json Apr 22 15:48:07 volumio volumio[1196]: info: Scanning category system_controller Apr 22 15:48:07 volumio volumio[1196]: info: Scanning category user_interface Apr 22 15:48:07 volumio volumio[1196]: info: Scanning into folder /data/plugins/ Apr 22 15:48:07 volumio volumio[1196]: info: Scanning category audio_interface Apr 22 15:48:07 volumio volumio[1196]: info: Scanning category system_controller Apr 22 15:48:07 volumio volumio[1196]: info: Scanning category system_hardware Apr 22 15:48:07 volumio volumio[1196]: info: Scanning category user_interface Apr 22 15:48:07 volumio volumio[1196]: info: Plugin folders cleanup completed Apr 22 15:48:07 volumio volumio[1196]: info: ------------------------------------------- Apr 22 15:48:07 volumio volumio[1196]: info: ----- Core plugins startup ---- Apr 22 15:48:07 volumio volumio[1196]: info: ------------------------------------------- Apr 22 15:48:07 volumio volumio[1196]: info: Loading plugins from folder /volumio/app/plugins/ Apr 22 15:48:07 volumio volumio[1196]: info: Adding plugin upnp to MyMusic Plugins Apr 22 15:48:07 volumio volumio[1196]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 22 15:48:07 volumio volumio[1196]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 22 15:48:07 volumio volumio[1196]: info: Loading plugins from folder /data/plugins/ Apr 22 15:48:07 volumio volumio[1196]: info: Loading plugin "system"... Apr 22 15:48:07 volumio volumio[1196]: info: Loading plugin "ampswitch"... Apr 22 15:48:08 volumio volumio[1196]: info: Applying required configuration parameters for plugin ampswitch Apr 22 15:48:08 volumio volumio-time-update[652]: volumio-time-update-util: Fetching time from Volumio... Apr 22 15:48:08 volumio volumio-time-update[652]: volumio-time-update-util: Date not found in response Apr 22 15:48:08 volumio volumio-time-update[652]: volumio-time-update-util: Retrying in 5 seconds... Apr 22 15:48:08 volumio volumio[1196]: info: Loading plugin "appearance"... Apr 22 15:48:08 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:08 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. Apr 22 15:48:08 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:08 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:08 volumio volumio[1227]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:08 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:08 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:08 volumio sh[575]: eth0: using IPv4LL address 169.254.50.164 Apr 22 15:48:08 volumio sh[575]: eth0: adding route to 169.254.0.0/16 Apr 22 15:48:08 volumio sh[575]: eth0: adding default route Apr 22 15:48:08 volumio dhcpcd[624]: eth0: using IPv4LL address 169.254.50.164 Apr 22 15:48:08 volumio dhcpcd[624]: eth0: adding route to 169.254.0.0/16 Apr 22 15:48:08 volumio avahi-daemon[664]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.50.164. Apr 22 15:48:08 volumio avahi-daemon[664]: New relevant interface eth0.IPv4 for mDNS. Apr 22 15:48:08 volumio dhcpcd[624]: eth0: adding default route Apr 22 15:48:08 volumio avahi-daemon[664]: Registering new address record for 169.254.50.164 on eth0.IPv4. Apr 22 15:48:08 volumio sh[575]: forked to background, child pid 1239 Apr 22 15:48:08 volumio dhcpcd[624]: forked to background, child pid 1239 Apr 22 15:48:08 volumio volumio[1196]: info: Loading plugin "network"... Apr 22 15:48:08 volumio volumio[1196]: info: Refreshing Cached IP Addresses Apr 22 15:48:08 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 22 15:48:08 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:08 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:08 volumio volumio[1196]: info: Loading plugin "services"... Apr 22 15:48:08 volumio sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 22 15:48:08 volumio sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:08 volumio volumio[1196]: info: Loading plugin "alsa_controller"... Apr 22 15:48:08 volumio sudo[1261]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:08 volumio ifplugd(eth0)[840]: client: ifup: interface eth0 already configured Apr 22 15:48:08 volumio sh[575]: eth0=eth0 Apr 22 15:48:08 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 15:48:08 volumio volumio[1196]: info: Loading plugin "wizard"... Apr 22 15:48:08 volumio volumio[1196]: info: Loading plugin "networkfs"... Apr 22 15:48:08 volumio volumio[1196]: info: Starting Udev Watcher for removable devices Apr 22 15:48:08 volumio sudo[1291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=Kev82069_,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.62/public/Music /mnt/NAS/public Apr 22 15:48:08 volumio sudo[1291]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:08 volumio ifplugd(eth0)[840]: Program executed successfully. Apr 22 15:48:08 volumio volumio[1196]: info: Ignoring mount for partition: boot Apr 22 15:48:08 volumio volumio[1196]: info: Ignoring mount for partition: volumio Apr 22 15:48:08 volumio volumio[1196]: info: Ignoring mount for partition: volumio_data Apr 22 15:48:08 volumio kernel: CIFS: Attempting to mount //192.168.1.62/public/Music Apr 22 15:48:08 volumio volumio[1196]: info: Mounting Device KEVIN_S_IPO Apr 22 15:48:08 volumio sudo[1299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/KEVIN_S_IPO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 22 15:48:08 volumio sudo[1299]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:08 volumio sudo[1299]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:08 volumio volumio[1196]: mount: /media/KEVIN_S_IPO: /dev/sdb1 already mounted on /media/KEVIN_S_IPO. Apr 22 15:48:08 volumio volumio[1196]: error: Failed to mount KEVIN_S_IPO: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/KEVIN_S_IPO" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 22 15:48:08 volumio volumio[1196]: mount: /media/KEVIN_S_IPO: /dev/sdb1 already mounted on /media/KEVIN_S_IPO. Apr 22 15:48:08 volumio volumio[1196]: info: Mounting Device system-boot Apr 22 15:48:08 volumio sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/system-boot -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 22 15:48:08 volumio sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:08 volumio sudo[1305]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:08 volumio volumio[1196]: mount: /media/system-boot: /dev/sda1 already mounted on /media/system-boot. Apr 22 15:48:08 volumio volumio[1196]: error: Failed to mount system-boot: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/system-boot" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 22 15:48:08 volumio volumio[1196]: mount: /media/system-boot: /dev/sda1 already mounted on /media/system-boot. Apr 22 15:48:08 volumio volumio[1196]: info: Mounting Device writable Apr 22 15:48:09 volumio sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/writable -o noatime Apr 22 15:48:09 volumio sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:09 volumio sudo[1311]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:09 volumio volumio[1196]: mount: /media/writable: /dev/sda2 already mounted on /media/writable. Apr 22 15:48:09 volumio volumio[1196]: error: Failed to mount writable: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda2" "/mnt/USB/writable" -o noatime Apr 22 15:48:09 volumio volumio[1196]: mount: /media/writable: /dev/sda2 already mounted on /media/writable. Apr 22 15:48:09 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "volumio_command_line_client"... Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "upnp"... Apr 22 15:48:09 volumio volumio[1196]: info: [1745351289039] Starting Upmpd Daemon Apr 22 15:48:09 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "my_music"... Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "mpd"... Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "upnp_browser"... Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "alarm-clock"... Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "airplay_emulation"... Apr 22 15:48:09 volumio volumio[1196]: info: Starting Shairport Sync Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "last_100"... Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "webradio"... Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "i2s_dacs"... Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "volumiodiscovery"... Apr 22 15:48:09 volumio systemd[1]: Started Samba NMB Daemon. Apr 22 15:48:09 volumio nmbd[818]: [2025/04/22 15:48:09.596200, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 22 15:48:09 volumio nmbd[818]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 22 15:48:09 volumio volumio[1196]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:09 volumio volumio[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:09 volumio volumio[1196]: *** WARNING *** For more information see Apr 22 15:48:09 volumio volumio[1196]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:09 volumio volumio[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:09 volumio volumio[1196]: *** WARNING *** For more information see Apr 22 15:48:09 volumio volumio[1196]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 22 15:48:09 volumio volumio[1196]: info: Discovery: Started advertising with name: Volumio Apr 22 15:48:09 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "outputs"... Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "albumart"... Apr 22 15:48:09 volumio node[1196]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:09 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 22 15:48:09 volumio node[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:09 volumio node[1196]: *** WARNING *** For more information see Apr 22 15:48:09 volumio node[1196]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:09 volumio node[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:09 volumio node[1196]: *** WARNING *** For more information see Apr 22 15:48:09 volumio volumio[1196]: info: Plugin example_plugin is not enabled Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "inputs"... Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "updater_comm"... Apr 22 15:48:09 volumio volumio[1196]: info: Plugin mpdemulation is not enabled Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "rest_api"... Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "websocket"... Apr 22 15:48:09 volumio volumio[1196]: info: Starting Socket.io Server version 2.3.0 Apr 22 15:48:09 volumio volumio[1196]: info: Loading plugin "fusiondsp"... Apr 22 15:48:09 volumio winbindd[1313]: [2025/04/22 15:48:09.832550, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 22 15:48:09 volumio winbindd[1313]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 22 15:48:09 volumio ntpd[852]: Listen normally on 3 eth0 169.254.50.164:123 Apr 22 15:48:09 volumio ntpd[852]: new interface(s) found: waking up resolver Apr 22 15:48:09 volumio winbindd[1313]: [2025/04/22 15:48:09.849374, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 22 15:48:09 volumio winbindd[1313]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 22 15:48:09 volumio systemd[1]: Started Samba Winbind Daemon. Apr 22 15:48:09 volumio volumio[1196]: Forking 3 albumart workers Apr 22 15:48:09 volumio systemd[1]: Starting Samba SMB Daemon... Apr 22 15:48:10 volumio volumio[1196]: info: Applying required configuration parameters for plugin fusiondsp Apr 22 15:48:10 volumio smbd[1340]: [2025/04/22 15:48:10.322165, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 22 15:48:10 volumio systemd[1]: Started Samba SMB Daemon. Apr 22 15:48:10 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:10 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 2. Apr 22 15:48:10 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:10 volumio smbd[1340]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 22 15:48:10 volumio volumio[1196]: info: Loading plugin "onkyo_control"... Apr 22 15:48:10 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:10 volumio volumio[1354]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:10 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:10 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:10 volumio volumio[1196]: Starting albumart workers Apr 22 15:48:10 volumio volumio[1196]: Starting albumart workers Apr 22 15:48:10 volumio volumio[1196]: info: Applying required configuration parameters for plugin onkyo_control Apr 22 15:48:10 volumio volumio[1196]: Starting albumart workers Apr 22 15:48:10 volumio volumio[1196]: info: Loading plugin "randomizer"... Apr 22 15:48:10 volumio volumio-remote-updater[654]: [2025-04-22 15:48:10] [connect] Successful connection Apr 22 15:48:11 volumio volumio[1196]: info: Applying required configuration parameters for plugin randomizer Apr 22 15:48:11 volumio volumio[1196]: info: Loading i18n strings for locale en Apr 22 15:48:11 volumio volumio[1196]: Updating browse sources language Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::initPlayerControls Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 15:48:11 volumio volumio[1196]: Express server listening on port 3000 Apr 22 15:48:11 volumio volumio[1196]: [Metrics] WebUI: 4s 72.52ms Apr 22 15:48:11 volumio volumio[1196]: info: CoreStateMachine::resetVolumioState Apr 22 15:48:11 volumio volumio[1196]: info: CoreStateMachine::getcurrentVolume Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::volumioRetrievevolume Apr 22 15:48:11 volumio volumio[1196]: info: Volumio Network Manager: Network status updated: 1 Apr 22 15:48:11 volumio volumio-remote-updater[654]: [2025-04-22 15:48:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745351290 101 Apr 22 15:48:11 volumio volumio[1196]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 22 15:48:11 volumio volumio[1196]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:11 volumio volumio[1196]: info: Reloading queue from file Apr 22 15:48:11 volumio volumio[1196]: info: VolumeController:: Volume=100 Mute =false Apr 22 15:48:11 volumio volumio[1196]: info: CoreStateMachine::pushState Apr 22 15:48:11 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Apr 22 15:48:11 volumio volumio[1196]: info: CoreStateMachine::updateTrackBlock Apr 22 15:48:11 volumio volumio[1196]: info: CorePlayQueue::getTrackBlock Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::volumioRetrievevolume Apr 22 15:48:11 volumio volumio[1196]: info: CoreStateMachine::setRepeat false single undefined Apr 22 15:48:11 volumio volumio[1196]: info: CoreStateMachine::pushState Apr 22 15:48:11 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Apr 22 15:48:11 volumio volumio[1196]: info: CoreStateMachine::setRandom undefined Apr 22 15:48:11 volumio volumio[1196]: info: CoreStateMachine::pushState Apr 22 15:48:11 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Apr 22 15:48:11 volumio volumio[1196]: info: Setting Device type: Raspberry PI Apr 22 15:48:11 volumio volumio[1196]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 22 15:48:11 volumio volumio[1196]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Apr 22 15:48:11 volumio volumio[1196]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 22 15:48:11 volumio volumio[1196]: info: VolumeController:: Volume=100 Mute =false Apr 22 15:48:11 volumio volumio[1196]: info: CoreStateMachine::pushState Apr 22 15:48:11 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Apr 22 15:48:11 volumio volumio[1196]: info: CoreCommandRouter::volumioPushState Apr 22 15:48:12 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:12 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 3. Apr 22 15:48:12 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:12 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:12 volumio volumio[1393]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:12 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:12 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:13 volumio volumio-time-update[652]: volumio-time-update-util: Fetching time from Volumio... Apr 22 15:48:15 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:15 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 4. Apr 22 15:48:15 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:15 volumio sudo[1291]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:15 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 22 15:48:15 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Apr 22 15:48:15 volumio volumio[1196]: info: Cannot mount NAS public at system boot, trial number 1 ,retrying in 5 seconds Apr 22 15:48:15 volumio volumio[1196]: info: Completed loading Core Plugins Apr 22 15:48:15 volumio volumio[1196]: info: Preparing to generate the ALSA configuration file Apr 22 15:48:15 volumio volumio[1196]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 22 15:48:15 volumio volumio[1196]: info: Reading ALSA contributions from plugins. Apr 22 15:48:15 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:15 volumio volumio[1196]: info: Asound.conf file unchanged, so no further update is needed Apr 22 15:48:15 volumio volumio[1196]: info: Output device has changed, restarting MPD Apr 22 15:48:15 volumio sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 15:48:15 volumio volumio[1196]: info: Output device has changed, restarting Shairport Sync Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:15 volumio sudo[1400]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:15 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 15:48:15 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio volumio[1398]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:15 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:15 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:15 volumio systemd[1]: Stopping Music Player Daemon... Apr 22 15:48:15 volumio volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:48:15 volumio volumio[1196]: info: ___________ START PLUGINS ___________ Apr 22 15:48:15 volumio systemd[1]: mpd.service: Succeeded. Apr 22 15:48:15 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 15:48:15 volumio systemd[1]: Starting Music Player Daemon... Apr 22 15:48:15 volumio volumio[1196]: info: ControllerMpd::onStart: Initializing MPD Apr 22 15:48:15 volumio volumio[1196]: info: Creating MPD Configuration file Apr 22 15:48:15 volumio sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 15:48:15 volumio sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:48:15 volumio volumio[1196]: info: [1745351295236] CoreMusicLibrary::Adding element Media Servers Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:48:15 volumio sudo[1422]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:15 volumio sudo[1409]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 15:48:15 volumio sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:15 volumio sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 15:48:15 volumio sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:48:15 volumio volumio[1196]: info: [1745351295320] CoreMusicLibrary::Adding element Last_100 Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:48:15 volumio volumio[1196]: info: [1745351295322] CoreMusicLibrary::Adding element Webradio Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 15:48:15 volumio volumio[1196]: info: Initializing BBC Radios Apr 22 15:48:15 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 22 15:48:15 volumio systemd[1]: mpd.service: Succeeded. Apr 22 15:48:15 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 15:48:15 volumio systemd[1]: Starting Music Player Daemon... Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: Loading i18n strings for locale en Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: FusionDsp - mixtype--------------------- Hardware Apr 22 15:48:15 volumio sudo[1437]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 15:48:15 volumio sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:48:15 volumio volumio[1196]: info: [1745351295420] CoreMusicLibrary::Adding element Randomizer Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:48:15 volumio sudo[1437]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:15 volumio volumio[1196]: Cannot find translation for source Randomizer Apr 22 15:48:15 volumio volumio[1196]: info: Volumio Calling Home Apr 22 15:48:15 volumio volumio[1196]: info: Preparing to generate the ALSA configuration file Apr 22 15:48:15 volumio volumio[1196]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 22 15:48:15 volumio volumio[1196]: info: Reading ALSA contributions from plugins. Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::volumioGetState Apr 22 15:48:15 volumio volumio[1196]: info: CorePlayQueue::getTrack 0 Apr 22 15:48:15 volumio volumio[1196]: info: [ASDebug] CurState: stop PrevState: na Apr 22 15:48:15 volumio volumio[1196]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 22 15:48:15 volumio volumio[1196]: info: [ASDebug] CurState: stop PrevState: na Apr 22 15:48:15 volumio volumio[1196]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 22 15:48:15 volumio volumio[1196]: info: MPD Permissions set Apr 22 15:48:15 volumio volumio[1196]: info: MPD Permissions set Apr 22 15:48:15 volumio volumio[1196]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 22 15:48:15 volumio volumio[1196]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 22 15:48:15 volumio volumio[1196]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: Starting Shairport Sync Apr 22 15:48:15 volumio volumio[1196]: info: Starting Shairport Sync Apr 22 15:48:15 volumio sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:48:15 volumio volumio[1196]: info: Starting Shairport Sync Apr 22 15:48:15 volumio sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:48:15 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio volumio[1196]: info: Asound.conf file unchanged, so no further update is needed Apr 22 15:48:15 volumio volumio[1196]: info: Output device has changed, restarting MPD Apr 22 15:48:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 15:48:15 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 15:48:15 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 15:48:15 volumio sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:48:15 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio volumio[1196]: info: Output device has changed, restarting Shairport Sync Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:15 volumio sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 15:48:15 volumio sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:48:15 volumio volumio[1196]: info: MPD Permissions set Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio sudo[1470]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:15 volumio sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 15:48:15 volumio sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 15:48:15 volumio sudo[1460]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:15 volumio volumio[1196]: info: Shairport-Sync Started Apr 22 15:48:15 volumio volumio[1196]: Error adding Membership: Error: addMembership EINVAL Apr 22 15:48:15 volumio volumio[1196]: info: Starting Shairport Sync Apr 22 15:48:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 15:48:15 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 22 15:48:15 volumio systemd[1]: mpd.service: Succeeded. Apr 22 15:48:15 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 15:48:15 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 15:48:15 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 15:48:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 15:48:15 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:15 volumio sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:48:15 volumio systemd[1]: Starting Music Player Daemon... Apr 22 15:48:15 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:15 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio volumio[1196]: info: Shairport-Sync Started Apr 22 15:48:15 volumio volumio[1196]: info: Shairport-Sync Started Apr 22 15:48:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 15:48:15 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 15:48:15 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 15:48:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 15:48:15 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:15 volumio volumio[1196]: info: Shairport-Sync Started Apr 22 15:48:15 volumio sudo[1487]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 15:48:15 volumio sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:15 volumio sudo[1487]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:16 volumio volumio[1196]: info: [ASDebug] Togle GPIO: OFF Apr 22 15:48:16 volumio mpd[1494]: Apr 22 15:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 22 15:48:16 volumio systemd[1]: Started Music Player Daemon. Apr 22 15:48:16 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:16 volumio sudo[1472]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:16 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:16 volumio volumio[1196]: error: MPD error: The expression evaluated to a falsy value: Apr 22 15:48:16 volumio volumio[1196]: assert.ok(self.idling) Apr 22 15:48:16 volumio volumio[1196]: error: The expression evaluated to a falsy value: Apr 22 15:48:16 volumio volumio[1196]: assert.ok(self.idling) Apr 22 15:48:16 volumio volumio[1196]: error: MPD error: The expression evaluated to a falsy value: Apr 22 15:48:16 volumio volumio[1196]: assert.ok(self.idling) Apr 22 15:48:16 volumio volumio[1196]: error: The expression evaluated to a falsy value: Apr 22 15:48:16 volumio volumio[1196]: assert.ok(self.idling) Apr 22 15:48:16 volumio volumio[1196]: info: MPD running with PID1494 Apr 22 15:48:16 volumio volumio[1196]: ,establishing connection Apr 22 15:48:16 volumio volumio[1196]: error: updateQueue error: null Apr 22 15:48:16 volumio volumio[1196]: error: updateQueue error: null Apr 22 15:48:17 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:17 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 5. Apr 22 15:48:17 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:17 volumio volumio[1196]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 22 15:48:17 volumio volumio[1196]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Apr 22 15:48:17 volumio volumio[1196]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 22 15:48:17 volumio volumio[1196]: info: camilladsp service started and running in background, instance 1 Apr 22 15:48:17 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:48:17 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:17 volumio volumio[1196]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 22 15:48:17 volumio volumio[1196]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 22 15:48:17 volumio volumio[1196]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 22 15:48:17 volumio volumio[1196]: info: FusionDsp loaded Apr 22 15:48:17 volumio volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 22 15:48:17 volumio sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 22 15:48:17 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:17 volumio volumio[1196]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 22 15:48:17 volumio volumio[1196]: info: Adding Signal Path Element [object Object] Apr 22 15:48:17 volumio volumio[1196]: info: Adding fusiondspeq DSP Signal Path Element Apr 22 15:48:17 volumio volumio[1503]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:17 volumio volumio[1196]: info: FusionDsp - ---- installed callbackRead Apr 22 15:48:17 volumio volumio[1196]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 15:48:17 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:17 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:17 volumio volumio[1196]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 22 15:48:17 volumio volumio[1196]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 22 15:48:17 volumio volumio[1196]: at onErrorNT (internal/child_process.js:465:16) Apr 22 15:48:17 volumio volumio[1196]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Apr 22 15:48:17 volumio volumio[1196]: errno: -2, Apr 22 15:48:17 volumio volumio[1196]: code: 'ENOENT', Apr 22 15:48:17 volumio volumio[1196]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 22 15:48:17 volumio volumio[1196]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 22 15:48:17 volumio volumio[1196]: spawnargs: [ Apr 22 15:48:17 volumio volumio[1196]: '-p', Apr 22 15:48:17 volumio volumio[1196]: 9876, Apr 22 15:48:17 volumio volumio[1196]: '-o', Apr 22 15:48:17 volumio volumio[1196]: '/tmp/camilladsp.log', Apr 22 15:48:17 volumio volumio[1196]: '-l', Apr 22 15:48:17 volumio volumio[1196]: 'warn', Apr 22 15:48:17 volumio volumio[1196]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 22 15:48:17 volumio volumio[1196]: ] Apr 22 15:48:17 volumio volumio[1196]: } Apr 22 15:48:17 volumio volumio[1196]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 15:48:17 volumio sudo[1524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 15:47 Apr 22 15:48:17 volumio sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:17 volumio sudo[1524]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:19 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:19 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 6. Apr 22 15:48:19 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:19 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:19 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:19 volumio volumio[1545]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:19 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:19 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:21 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:21 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 7. Apr 22 15:48:21 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:21 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:21 volumio volumio[1546]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:21 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:21 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:22 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 22 15:48:24 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:24 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 8. Apr 22 15:48:24 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:24 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:24 volumio volumio[1547]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:24 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:24 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:24 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 22 15:48:26 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:26 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 9. Apr 22 15:48:26 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:26 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:26 volumio volumio[1552]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:26 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:26 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:28 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:28 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 10. Apr 22 15:48:28 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:28 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:28 volumio volumio[1553]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:28 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:28 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:30 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:30 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 11. Apr 22 15:48:30 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:30 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:30 volumio volumio[1568]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:30 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:30 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:33 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:33 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 12. Apr 22 15:48:33 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:33 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:33 volumio volumio-time-update[652]: volumio-time-update-util: Date not found in response Apr 22 15:48:33 volumio volumio-time-update[652]: volumio-time-update-util: Retrying in 5 seconds... Apr 22 15:48:33 volumio volumio[1569]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:33 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:33 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:35 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:35 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 13. Apr 22 15:48:35 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:35 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:35 volumio volumio[1571]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:35 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:35 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:37 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:37 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 14. Apr 22 15:48:37 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:37 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:37 volumio volumio[1575]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:37 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:37 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:38 volumio volumio-time-update[652]: volumio-time-update-util: Fetching time from Volumio... Apr 22 15:48:39 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:39 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 15. Apr 22 15:48:39 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:39 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:39 volumio volumio[1594]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:39 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:39 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:41 volumio volumio-remote-updater[654]: [2025-04-22 15:48:41] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Apr 22 15:48:42 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:42 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 16. Apr 22 15:48:42 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:42 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:42 volumio volumio[1595]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:42 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:42 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:44 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:44 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 17. Apr 22 15:48:44 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:44 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:44 volumio volumio[1596]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:44 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:44 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:46 volumio volumio-remote-updater[654]: [2025-04-22 15:48:46] [connect] Successful connection Apr 22 15:48:46 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:46 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 18. Apr 22 15:48:46 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:46 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:46 volumio volumio[1597]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:46 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:46 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:48 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:48 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 19. Apr 22 15:48:48 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:48 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:48 volumio volumio[1602]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:48 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:48 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:51 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:51 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 20. Apr 22 15:48:51 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:51 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:51 volumio volumio[1613]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:51 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:51 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:53 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:53 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 21. Apr 22 15:48:53 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:53 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:53 volumio volumio[1652]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:53 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:53 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:55 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:55 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 22. Apr 22 15:48:55 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:55 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:55 volumio volumio[1653]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:55 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 22 15:48:55 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 22 15:48:55 volumio systemd[1]: Started dynamicswap service. Apr 22 15:48:55 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:55 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:55 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 22 15:48:55 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 22 15:48:55 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Apr 22 15:48:55 volumio systemd[1]: Stopped Volumio Backend Module. Apr 22 15:48:55 volumio systemd[1]: Started Volumio Backend Module. Apr 22 15:48:55 volumio systemd[1]: Started dynamicswap service. Apr 22 15:48:55 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 22 15:48:56 volumio volumio[1660]: info: ------------------------------------------- Apr 22 15:48:56 volumio volumio[1660]: info: ----- Volumio3 ---- Apr 22 15:48:56 volumio volumio[1660]: info: ------------------------------------------- Apr 22 15:48:56 volumio volumio[1660]: info: ----- System startup ---- Apr 22 15:48:56 volumio volumio[1660]: info: ------------------------------------------- Apr 22 15:48:57 volumio volumio[1660]: info: MYVOLUMIO Environment detected Apr 22 15:48:57 volumio volumio[1660]: info: Plugin folders cleanup Apr 22 15:48:57 volumio volumio[1660]: info: Scanning into folder /volumio/app/plugins/ Apr 22 15:48:57 volumio volumio[1660]: info: Scanning category audio_interface Apr 22 15:48:57 volumio volumio[1660]: info: Scanning category miscellanea Apr 22 15:48:57 volumio volumio[1660]: info: Scanning category music_service Apr 22 15:48:57 volumio volumio[1660]: info: Scanning category plugins.json Apr 22 15:48:57 volumio volumio[1660]: info: Scanning category system_controller Apr 22 15:48:57 volumio volumio[1660]: info: Scanning category user_interface Apr 22 15:48:57 volumio volumio[1660]: info: Scanning into folder /data/plugins/ Apr 22 15:48:57 volumio volumio[1660]: info: Scanning category audio_interface Apr 22 15:48:57 volumio volumio[1660]: info: Scanning category system_controller Apr 22 15:48:57 volumio volumio[1660]: info: Scanning category system_hardware Apr 22 15:48:57 volumio volumio[1660]: info: Scanning category user_interface Apr 22 15:48:57 volumio volumio[1660]: info: Plugin folders cleanup completed Apr 22 15:48:57 volumio volumio[1660]: info: ------------------------------------------- Apr 22 15:48:57 volumio volumio[1660]: info: ----- Core plugins startup ---- Apr 22 15:48:57 volumio volumio[1660]: info: ------------------------------------------- Apr 22 15:48:57 volumio volumio[1660]: info: Loading plugins from folder /volumio/app/plugins/ Apr 22 15:48:57 volumio volumio[1660]: info: Adding plugin upnp to MyMusic Plugins Apr 22 15:48:57 volumio volumio[1660]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 22 15:48:57 volumio volumio[1660]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 22 15:48:57 volumio volumio[1660]: info: Loading plugins from folder /data/plugins/ Apr 22 15:48:57 volumio volumio[1660]: info: Loading plugin "system"... Apr 22 15:48:57 volumio volumio[1660]: info: Loading plugin "ampswitch"... Apr 22 15:48:57 volumio volumio[1660]: info: Applying required configuration parameters for plugin ampswitch Apr 22 15:48:57 volumio volumio[1660]: info: Loading plugin "appearance"... Apr 22 15:48:57 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:48:57 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 23. Apr 22 15:48:57 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:48:57 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:48:57 volumio volumio[1690]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:48:57 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:48:57 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:48:57 volumio volumio[1660]: info: Loading plugin "network"... Apr 22 15:48:57 volumio volumio[1660]: info: Refreshing Cached IP Addresses Apr 22 15:48:58 volumio sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 22 15:48:58 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:58 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:58 volumio sudo[1694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "services"... Apr 22 15:48:58 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "alsa_controller"... Apr 22 15:48:58 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:58 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "wizard"... Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "networkfs"... Apr 22 15:48:58 volumio volumio[1660]: info: Starting Udev Watcher for removable devices Apr 22 15:48:58 volumio sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=pi,password=Kev82069_,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.62/public/Music /mnt/NAS/public Apr 22 15:48:58 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:58 volumio volumio-time-update[652]: volumio-time-update-util: Date not found in response Apr 22 15:48:58 volumio volumio-time-update[652]: volumio-time-update-util: Retrying in 5 seconds... Apr 22 15:48:58 volumio volumio[1660]: info: Ignoring mount for partition: boot Apr 22 15:48:58 volumio volumio[1660]: info: Ignoring mount for partition: volumio Apr 22 15:48:58 volumio volumio[1660]: info: Ignoring mount for partition: volumio_data Apr 22 15:48:58 volumio kernel: CIFS: Attempting to mount //192.168.1.62/public/Music Apr 22 15:48:58 volumio volumio[1660]: info: Mounting Device KEVIN_S_IPO Apr 22 15:48:58 volumio sudo[1726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/KEVIN_S_IPO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 22 15:48:58 volumio sudo[1726]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:58 volumio sudo[1726]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:58 volumio volumio[1660]: mount: /media/KEVIN_S_IPO: /dev/sdb1 already mounted on /media/KEVIN_S_IPO. Apr 22 15:48:58 volumio volumio[1660]: error: Failed to mount KEVIN_S_IPO: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/KEVIN_S_IPO" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 22 15:48:58 volumio volumio[1660]: mount: /media/KEVIN_S_IPO: /dev/sdb1 already mounted on /media/KEVIN_S_IPO. Apr 22 15:48:58 volumio volumio[1660]: info: Mounting Device system-boot Apr 22 15:48:58 volumio sudo[1732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/system-boot -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 22 15:48:58 volumio sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:58 volumio sudo[1732]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:58 volumio volumio[1660]: mount: /media/system-boot: /dev/sda1 already mounted on /media/system-boot. Apr 22 15:48:58 volumio volumio[1660]: error: Failed to mount system-boot: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/system-boot" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 22 15:48:58 volumio volumio[1660]: mount: /media/system-boot: /dev/sda1 already mounted on /media/system-boot. Apr 22 15:48:58 volumio volumio[1660]: info: Mounting Device writable Apr 22 15:48:58 volumio sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/writable -o noatime Apr 22 15:48:58 volumio sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:48:58 volumio sudo[1738]: pam_unix(sudo:session): session closed for user root Apr 22 15:48:58 volumio volumio[1660]: mount: /media/writable: /dev/sda2 already mounted on /media/writable. Apr 22 15:48:58 volumio volumio[1660]: error: Failed to mount writable: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda2" "/mnt/USB/writable" -o noatime Apr 22 15:48:58 volumio volumio[1660]: mount: /media/writable: /dev/sda2 already mounted on /media/writable. Apr 22 15:48:58 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "volumio_command_line_client"... Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "upnp"... Apr 22 15:48:58 volumio volumio[1660]: info: [1745351338331] Starting Upmpd Daemon Apr 22 15:48:58 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "my_music"... Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "mpd"... Apr 22 15:48:58 volumio dhcpcd[1239]: eth0: offered 192.168.1.180 from 192.168.1.1 Apr 22 15:48:58 volumio dhcpcd[1239]: eth0: probing address 192.168.1.180/24 Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "upnp_browser"... Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "alarm-clock"... Apr 22 15:48:58 volumio volumio-remote-updater[654]: [2025-04-22 15:48:58] [connect] Successful connection Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "airplay_emulation"... Apr 22 15:48:58 volumio volumio[1660]: info: Starting Shairport Sync Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "last_100"... Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "webradio"... Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "i2s_dacs"... Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "volumiodiscovery"... Apr 22 15:48:58 volumio volumio[1660]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:58 volumio volumio[1660]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:58 volumio node[1660]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:58 volumio volumio[1660]: *** WARNING *** For more information see Apr 22 15:48:58 volumio volumio[1660]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:58 volumio volumio[1660]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:58 volumio volumio[1660]: *** WARNING *** For more information see Apr 22 15:48:58 volumio node[1660]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:58 volumio node[1660]: *** WARNING *** For more information see Apr 22 15:48:58 volumio node[1660]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 22 15:48:58 volumio node[1660]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 22 15:48:58 volumio node[1660]: *** WARNING *** For more information see Apr 22 15:48:58 volumio volumio[1660]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 22 15:48:58 volumio volumio[1660]: info: Discovery: Started advertising with name: Volumio Apr 22 15:48:58 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "outputs"... Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "albumart"... Apr 22 15:48:58 volumio volumio[1660]: info: Plugin example_plugin is not enabled Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "inputs"... Apr 22 15:48:58 volumio volumio[1660]: info: Loading plugin "updater_comm"... Apr 22 15:48:59 volumio volumio[1660]: info: Plugin mpdemulation is not enabled Apr 22 15:48:59 volumio volumio[1660]: info: Loading plugin "rest_api"... Apr 22 15:48:59 volumio volumio[1660]: info: Loading plugin "websocket"... Apr 22 15:48:59 volumio volumio[1660]: info: Starting Socket.io Server version 2.3.0 Apr 22 15:48:59 volumio volumio[1660]: info: Loading plugin "fusiondsp"... Apr 22 15:48:59 volumio volumio[1660]: Forking 3 albumart workers Apr 22 15:48:59 volumio volumio[1660]: info: Applying required configuration parameters for plugin fusiondsp Apr 22 15:48:59 volumio volumio[1660]: info: Loading plugin "onkyo_control"... Apr 22 15:48:59 volumio volumio[1660]: info: Applying required configuration parameters for plugin onkyo_control Apr 22 15:48:59 volumio volumio[1660]: Starting albumart workers Apr 22 15:48:59 volumio volumio[1660]: info: Loading plugin "randomizer"... Apr 22 15:48:59 volumio volumio[1660]: Starting albumart workers Apr 22 15:48:59 volumio volumio[1660]: Starting albumart workers Apr 22 15:48:59 volumio volumio[1660]: info: Applying required configuration parameters for plugin randomizer Apr 22 15:49:00 volumio volumio[1660]: info: Loading i18n strings for locale en Apr 22 15:49:00 volumio volumio[1660]: Updating browse sources language Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:49:00 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:49:00 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 24. Apr 22 15:49:00 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:49:00 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::initPlayerControls Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 15:49:00 volumio volumio[1770]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:49:00 volumio volumio[1660]: Express server listening on port 3000 Apr 22 15:49:00 volumio volumio[1660]: [Metrics] WebUI: 3s 683.11ms Apr 22 15:49:00 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:49:00 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:49:00 volumio volumio[1660]: info: CoreStateMachine::resetVolumioState Apr 22 15:49:00 volumio volumio[1660]: info: CoreStateMachine::getcurrentVolume Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::volumioRetrievevolume Apr 22 15:49:00 volumio volumio[1660]: info: Volumio Network Manager: Network status updated: 1 Apr 22 15:49:00 volumio volumio-remote-updater[654]: [2025-04-22 15:49:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745351338 101 Apr 22 15:49:00 volumio volumio[1660]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 22 15:49:00 volumio volumio[1660]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:49:00 volumio volumio[1660]: info: Reloading queue from file Apr 22 15:49:00 volumio volumio[1660]: info: VolumeController:: Volume=100 Mute =false Apr 22 15:49:00 volumio volumio[1660]: info: CoreStateMachine::pushState Apr 22 15:49:00 volumio volumio[1660]: info: CorePlayQueue::getTrack 0 Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::volumioPushState Apr 22 15:49:00 volumio volumio[1660]: info: CoreStateMachine::updateTrackBlock Apr 22 15:49:00 volumio volumio[1660]: info: CorePlayQueue::getTrackBlock Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::volumioRetrievevolume Apr 22 15:49:00 volumio volumio[1660]: info: CoreStateMachine::setRepeat false single undefined Apr 22 15:49:00 volumio volumio[1660]: info: CoreStateMachine::pushState Apr 22 15:49:00 volumio volumio[1660]: info: CorePlayQueue::getTrack 0 Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::volumioPushState Apr 22 15:49:00 volumio volumio[1660]: info: CoreStateMachine::setRandom undefined Apr 22 15:49:00 volumio volumio[1660]: info: CoreStateMachine::pushState Apr 22 15:49:00 volumio volumio[1660]: info: CorePlayQueue::getTrack 0 Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::volumioPushState Apr 22 15:49:00 volumio volumio[1660]: info: Setting Device type: Raspberry PI Apr 22 15:49:00 volumio volumio[1660]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 22 15:49:00 volumio volumio[1660]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Apr 22 15:49:00 volumio volumio[1660]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 22 15:49:00 volumio volumio[1660]: info: VolumeController:: Volume=100 Mute =false Apr 22 15:49:00 volumio volumio[1660]: info: CoreStateMachine::pushState Apr 22 15:49:00 volumio volumio[1660]: info: CorePlayQueue::getTrack 0 Apr 22 15:49:00 volumio volumio[1660]: info: CoreCommandRouter::volumioPushState Apr 22 15:49:02 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:49:02 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 25. Apr 22 15:49:02 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:49:02 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:49:02 volumio volumio[1807]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:49:02 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:49:02 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:49:03 volumio volumio-time-update[652]: volumio-time-update-util: Fetching time from Volumio... Apr 22 15:49:04 volumio dhcpcd[1239]: eth0: leased 192.168.1.180 for 604800 seconds Apr 22 15:49:04 volumio dhcpcd[1239]: eth0: adding route to 192.168.1.0/24 Apr 22 15:49:04 volumio dhcpcd[1239]: eth0: changing default route via 192.168.1.1 Apr 22 15:49:04 volumio avahi-daemon[664]: Registering new address record for 192.168.1.180 on eth0.IPv4. Apr 22 15:49:04 volumio volumio[1660]: verbose: New Socket.io Connection to 192.168.1.180 from 192.168.1.70 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Apr 22 15:49:04 volumio dhcpcd[1239]: eth0: deleting route to 169.254.0.0/16 Apr 22 15:49:04 volumio avahi-daemon[664]: Withdrawing address record for 169.254.50.164 on eth0. Apr 22 15:49:04 volumio avahi-daemon[664]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.50.164. Apr 22 15:49:04 volumio avahi-daemon[664]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.180. Apr 22 15:49:04 volumio dhcpcd[1239]: eth0: pid 1239 deleted default route via 192.168.1.1 Apr 22 15:49:04 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:49:04 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 26. Apr 22 15:49:04 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:49:04 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:49:04 volumio volumio[1851]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:49:04 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:49:04 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:49:04 volumio ntpd[852]: Soliciting pool server 198.12.95.197 Apr 22 15:49:04 volumio ntpd[852]: Soliciting pool server 206.82.28.3 Apr 22 15:49:04 volumio ntpd[852]: Soliciting pool server 23.142.248.9 Apr 22 15:49:04 volumio ntpd[852]: Soliciting pool server 23.150.40.242 Apr 22 15:49:05 volumio volumio[1660]: info: Discovery: adding b734c1e4-25b9-4893-b238-753f12ad9ae7 Apr 22 15:49:05 volumio volumio[1660]: info: Discovery: Found device Volumio Apr 22 15:49:05 volumio volumio[1660]: info: CoreCommandRouter::volumioGetState Apr 22 15:49:05 volumio volumio[1660]: info: CorePlayQueue::getTrack 0 Apr 22 15:49:05 volumio ntpd[852]: Listen normally on 4 eth0 192.168.1.180:123 Apr 22 15:49:05 volumio ntpd[852]: Deleting interface #3 eth0, 169.254.50.164#123, interface stats: received=0, sent=0, dropped=4, active_time=56 secs Apr 22 15:49:05 volumio ntpd[852]: new interface(s) found: waking up resolver Apr 22 15:49:06 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:49:06 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 27. Apr 22 15:49:06 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:49:06 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:49:06 volumio volumio[1852]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:49:06 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:49:06 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:49:08 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:08 volumio volumio[1660]: info: Cannot mount NAS public at system boot, trial number 1 ,retrying in 5 seconds Apr 22 15:49:08 volumio volumio[1660]: info: Completed loading Core Plugins Apr 22 15:49:08 volumio volumio[1660]: info: Preparing to generate the ALSA configuration file Apr 22 15:49:08 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 22 15:49:08 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Apr 22 15:49:08 volumio volumio[1660]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 22 15:49:08 volumio volumio[1660]: info: Reading ALSA contributions from plugins. Apr 22 15:49:08 volumio volumio[1660]: info: Asound.conf file unchanged, so no further update is needed Apr 22 15:49:08 volumio volumio[1660]: info: Output device has changed, restarting MPD Apr 22 15:49:08 volumio sudo[1868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 15:49:08 volumio sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:08 volumio sudo[1868]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:08 volumio volumio[1660]: info: Output device has changed, restarting Shairport Sync Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:49:08 volumio sudo[1870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 15:49:08 volumio sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:08 volumio systemd[1]: Stopping Music Player Daemon... Apr 22 15:49:08 volumio volumio[1660]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:49:08 volumio volumio[1660]: info: ___________ START PLUGINS ___________ Apr 22 15:49:08 volumio systemd[1]: mpd.service: Succeeded. Apr 22 15:49:08 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 15:49:08 volumio volumio[1660]: info: ControllerMpd::onStart: Initializing MPD Apr 22 15:49:08 volumio volumio[1660]: info: Creating MPD Configuration file Apr 22 15:49:08 volumio sudo[1883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 15:49:08 volumio systemd[1]: Starting Music Player Daemon... Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:49:08 volumio volumio[1660]: info: [1745351348424] CoreMusicLibrary::Adding element Media Servers Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:49:08 volumio sudo[1883]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:08 volumio sudo[1883]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:08 volumio sudo[1885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 15:49:08 volumio sudo[1885]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:49:08 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 22 15:49:08 volumio systemd[1]: mpd.service: Succeeded. Apr 22 15:49:08 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 15:49:08 volumio systemd[1]: Starting Music Player Daemon... Apr 22 15:49:08 volumio volumio[1660]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:49:08 volumio volumio[1660]: info: [1745351348519] CoreMusicLibrary::Adding element Last_100 Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:49:08 volumio volumio-time-update[652]: volumio-time-update-util: Setting system time to: 2025-04-22 15:49:37 Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:49:08 volumio volumio[1660]: info: [1745351348523] CoreMusicLibrary::Adding element Webradio Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 15:49:08 volumio sudo[1891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 15:49:08 volumio sudo[1891]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:08 volumio volumio[1660]: info: Initializing BBC Radios Apr 22 15:49:08 volumio sudo[1891]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:08 volumio sudo[1896]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-04-22 15:49:37 Apr 22 15:49:08 volumio sudo[1896]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:08 volumio dbus-daemon[669]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.23' (uid=0 pid=1898 comm="timedatectl set-time 2025-04-22 15:49:37 ") Apr 22 15:49:08 volumio systemd[1]: Starting Time & Date Service... Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: Loading i18n strings for locale en Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: FusionDsp - mixtype--------------------- Hardware Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 22 15:49:08 volumio volumio[1660]: info: [1745351348644] CoreMusicLibrary::Adding element Randomizer Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 15:49:08 volumio volumio[1660]: Cannot find translation for source Randomizer Apr 22 15:49:08 volumio volumio[1660]: info: Volumio Calling Home Apr 22 15:49:08 volumio volumio[1660]: info: Preparing to generate the ALSA configuration file Apr 22 15:49:08 volumio sudo[1915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 22 15:49:08 volumio sudo[1915]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:08 volumio sudo[1915]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:08 volumio volumio[1660]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 22 15:49:08 volumio volumio[1660]: info: Reading ALSA contributions from plugins. Apr 22 15:49:08 volumio sudo[1917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 22 15:49:08 volumio sudo[1917]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::volumioGetState Apr 22 15:49:08 volumio volumio[1660]: info: CorePlayQueue::getTrack 0 Apr 22 15:49:08 volumio sudo[1917]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:08 volumio volumio[1660]: info: [ASDebug] CurState: stop PrevState: na Apr 22 15:49:08 volumio volumio[1660]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 22 15:49:08 volumio volumio[1660]: info: [ASDebug] CurState: stop PrevState: na Apr 22 15:49:08 volumio volumio[1660]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 22 15:49:08 volumio volumio[1660]: info: ONKYO-CONTROL: *********** ONKYO PLUGIN STARTED ******** Apr 22 15:49:08 volumio volumio[1660]: info: MPD Permissions set Apr 22 15:49:08 volumio volumio[1660]: info: MPD Permissions set Apr 22 15:49:08 volumio volumio[1660]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 22 15:49:08 volumio volumio[1660]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 22 15:49:08 volumio volumio[1660]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:49:08 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:09 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Apr 22 15:49:09 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 28. Apr 22 15:49:09 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 15:49:09 volumio systemd[1]: Started FusionDsp Daemon. Apr 22 15:49:09 volumio volumio[1660]: info: Volumio called home Apr 22 15:49:09 volumio volumio[1660]: info: Starting Shairport Sync Apr 22 15:49:09 volumio volumio[1928]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Apr 22 15:49:09 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Apr 22 15:49:09 volumio volumio[1660]: info: Starting Shairport Sync Apr 22 15:49:09 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Apr 22 15:49:09 volumio volumio[1660]: info: Starting Shairport Sync Apr 22 15:49:09 volumio sudo[1930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:49:09 volumio sudo[1930]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:09 volumio sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:49:09 volumio sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::volumioGetState Apr 22 15:49:09 volumio volumio[1660]: info: CorePlayQueue::getTrack 0 Apr 22 15:49:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 15:49:09 volumio sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:49:09 volumio sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::volumioGetState Apr 22 15:49:09 volumio volumio[1660]: info: CorePlayQueue::getTrack 0 Apr 22 15:49:09 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 15:49:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 15:49:09 volumio sudo[1940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 22 15:49:09 volumio sudo[1940]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 15:49:09 volumio sudo[1930]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:09 volumio volumio[1660]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"House of Pain","artist":"Van Halen","album":"1984","albumart":"/albumart?cacheid=754&web=Van%20Halen/1984/extralarge&path=%2FNAS%2Fpublic%2FVan%20Halen%2F1984&metadata=false","uri":"mnt/NAS/public/Van Halen/1984/09 House of Pain.m4a","trackType":"m4a","seek":0,"duration":200,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"m4a","updatedb":false,"volatile":false,"service":"mpd"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.1.202","model":"VSX-LX101"} Apr 22 15:49:09 volumio volumio[1660]: info: Asound.conf file unchanged, so no further update is needed Apr 22 15:49:09 volumio volumio[1660]: info: Output device has changed, restarting MPD Apr 22 15:49:09 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 22 15:49:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 15:49:09 volumio sudo[1944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 15:49:09 volumio sudo[1944]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:09 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 15:49:09 volumio sudo[1944]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:09 volumio sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 15:49:09 volumio sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 15:49:09 volumio sudo[1940]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:09 volumio volumio[1660]: info: Output device has changed, restarting Shairport Sync Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:49:09 volumio dbus-daemon[669]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 22 15:49:09 volumio volumio[1660]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 15:49:09 volumio mpd[1899]: Apr 22 15:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 22 15:49:09 volumio volumio[1660]: info: Shairport-Sync Started Apr 22 15:49:09 volumio volumio[1660]: Error adding Membership: Error: addMembership EINVAL Apr 22 15:49:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 15:49:09 volumio sudo[1935]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:09 volumio systemd[1]: Started Time & Date Service. Apr 22 15:49:09 volumio volumio[1660]: info: MPD Permissions set Apr 22 15:49:09 volumio volumio[1660]: info: Upmpdcli Daemon Started Apr 22 15:49:09 volumio sudo[1932]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:09 volumio volumio[1660]: info: Shairport-Sync Started Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:09 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:09 volumio systemd[1]: Started Music Player Daemon. Apr 22 15:49:09 volumio sudo[1885]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:09 volumio sudo[1870]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:37 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 15:49:37 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:37 volumio systemd-timedated[1910]: Changed local time to Tue Apr 22 15:49:37 2025 Apr 22 15:49:37 volumio sudo[1896]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:37 volumio volumio-time-update[652]: volumio-time-update-util: System time updated successfully. Apr 22 15:49:37 volumio systemd[1]: Stopping Music Player Daemon... Apr 22 15:49:37 volumio systemd[1]: Started Volumio Time Update Utility. Apr 22 15:49:37 volumio systemd[1]: Reached target Multi-User System. Apr 22 15:49:37 volumio systemd[1]: Reached target Graphical Interface. Apr 22 15:49:37 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 22 15:49:37 volumio systemd[1]: mpd.service: Succeeded. Apr 22 15:49:37 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 15:49:37 volumio systemd[1]: Starting Music Player Daemon... Apr 22 15:49:37 volumio volumio[1660]: info: Completed starting Core Plugins Apr 22 15:49:37 volumio volumio[1660]: info: ------------------------------------------- Apr 22 15:49:37 volumio volumio[1660]: info: ----- MyVolumio plugins startup ---- Apr 22 15:49:37 volumio volumio[1660]: info: ------------------------------------------- Apr 22 15:49:37 volumio volumio[1660]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 22 15:49:37 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 22 15:49:37 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 22 15:49:37 volumio volumio[1660]: info: Shairport-Sync Started Apr 22 15:49:37 volumio volumio[1660]: error: Upnp client error: Error: This socket has been ended by the other party Apr 22 15:49:37 volumio volumio[1660]: error: Cannot initialize MPD Connection: MPD is not running Apr 22 15:49:37 volumio volumio[1660]: info: [ASDebug] Togle GPIO: OFF Apr 22 15:49:37 volumio volumio[1660]: info: Starting Shairport Sync Apr 22 15:49:37 volumio sudo[1966]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 15:49:37 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:37 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:37 volumio volumio[1660]: error: MPD error: Error: write EPIPE Apr 22 15:49:37 volumio volumio[1660]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 22 15:49:37 volumio sudo[1971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 15:49:37 volumio sudo[1971]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 15:49:37 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 15:49:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 15:49:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 15:49:37 volumio sudo[1971]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:37 volumio volumio[1660]: error: MPD error: The expression evaluated to a falsy value: Apr 22 15:49:37 volumio volumio[1660]: assert.ok(self.idling) Apr 22 15:49:37 volumio volumio[1660]: error: The expression evaluated to a falsy value: Apr 22 15:49:37 volumio volumio[1660]: assert.ok(self.idling) Apr 22 15:49:37 volumio volumio[1660]: info: Shairport-Sync Started Apr 22 15:49:37 volumio mpd[1972]: Apr 22 15:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 22 15:49:37 volumio systemd[1]: Started Music Player Daemon. Apr 22 15:49:37 volumio sudo[1948]: pam_unix(sudo:session): session closed for user root Apr 22 15:49:37 volumio volumio[1660]: error: updateQueue error: null Apr 22 15:49:37 volumio systemd[1]: Startup finished in 12.511s (kernel) + 1min 21.202s (userspace) = 1min 33.713s. Apr 22 15:49:37 volumio volumio[1660]: error: updateQueue error: null Apr 22 15:49:38 volumio volumio[1660]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 22 15:49:38 volumio volumio[1660]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Apr 22 15:49:38 volumio volumio[1660]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Apr 22 15:49:38 volumio volumio[1660]: info: camilladsp service started and running in background, instance 1 Apr 22 15:49:38 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 15:49:38 volumio volumio[1660]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 22 15:49:38 volumio volumio[1660]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 22 15:49:38 volumio volumio[1660]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 22 15:49:38 volumio volumio[1660]: info: FusionDsp loaded Apr 22 15:49:38 volumio volumio[1660]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 22 15:49:38 volumio sudo[1989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 22 15:49:38 volumio sudo[1989]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 15:49:38 volumio volumio[1660]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 22 15:49:38 volumio volumio[1660]: info: Adding Signal Path Element [object Object] Apr 22 15:49:38 volumio volumio[1660]: info: Adding fusiondspeq DSP Signal Path Element Apr 22 15:49:38 volumio volumio[1660]: info: FusionDsp - ---- installed callbackRead Apr 22 15:49:38 volumio volumio[1660]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 15:49:38 volumio volumio[1660]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 22 15:49:38 volumio volumio[1660]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 22 15:49:38 volumio volumio[1660]: at onErrorNT (internal/child_process.js:465:16) Apr 22 15:49:38 volumio volumio[1660]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Apr 22 15:49:38 volumio volumio[1660]: errno: -2, Apr 22 15:49:38 volumio volumio[1660]: code: 'ENOENT', Apr 22 15:49:38 volumio volumio[1660]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 22 15:49:38 volumio volumio[1660]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 22 15:49:38 volumio volumio[1660]: spawnargs: [ Apr 22 15:49:38 volumio volumio[1660]: '-p', Apr 22 15:49:38 volumio volumio[1660]: 9876, Apr 22 15:49:38 volumio volumio[1660]: '-o', Apr 22 15:49:38 volumio volumio[1660]: '/tmp/camilladsp.log', Apr 22 15:49:38 volumio volumio[1660]: '-l', Apr 22 15:49:38 volumio volumio[1660]: 'warn', Apr 22 15:49:38 volumio volumio[1660]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 22 15:49:38 volumio volumio[1660]: ] Apr 22 15:49:38 volumio volumio[1660]: } Apr 22 15:49:38 volumio volumio[1660]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 15:49:38 volumio sudo[2000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 15:48 Apr 22 15:49:38 volumio sudo[2000]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"