-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Fri 2025-10-24 15:50:15 BST. -- Oct 24 15:50:05 volumio systemd-timedated[1030]: Changed local time to Fri Oct 24 15:50:05 2025 Oct 24 15:50:05 volumio sudo[1028]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:05 volumio volumio-time-update[630]: volumio-time-update-util: System time updated successfully. Oct 24 15:50:05 volumio systemd[1]: Started Volumio Time Update Utility. Oct 24 15:50:05 volumio systemd[1]: Reached target Multi-User System. Oct 24 15:50:05 volumio systemd[1]: Reached target Graphical Interface. Oct 24 15:50:05 volumio volumio[985]: info: MYVOLUMIO Environment detected Oct 24 15:50:05 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 24 15:50:05 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 24 15:50:05 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 24 15:50:05 volumio systemd[1]: Startup finished in 25.702s (kernel) + 22.834s (userspace) = 48.537s. Oct 24 15:50:05 volumio volumio[985]: info: Plugin folders cleanup Oct 24 15:50:05 volumio volumio[985]: info: Scanning into folder /volumio/app/plugins/ Oct 24 15:50:05 volumio volumio[985]: info: Scanning category audio_interface Oct 24 15:50:05 volumio volumio[985]: info: Scanning category miscellanea Oct 24 15:50:05 volumio volumio[985]: info: Scanning category music_service Oct 24 15:50:05 volumio volumio[985]: info: Scanning category plugins.json Oct 24 15:50:05 volumio volumio[985]: info: Scanning category system_controller Oct 24 15:50:05 volumio volumio[985]: info: Scanning category user_interface Oct 24 15:50:05 volumio volumio[985]: info: Scanning into folder /data/plugins/ Oct 24 15:50:05 volumio volumio[985]: info: Scanning category audio_interface Oct 24 15:50:05 volumio volumio[985]: info: Scanning category music_service Oct 24 15:50:05 volumio volumio[985]: info: Scanning category system_hardware Oct 24 15:50:05 volumio volumio[985]: info: Scanning category user_interface Oct 24 15:50:05 volumio volumio[985]: info: Plugin folders cleanup completed Oct 24 15:50:05 volumio volumio[985]: info: ------------------------------------------- Oct 24 15:50:05 volumio volumio[985]: info: ----- Core plugins startup ---- Oct 24 15:50:05 volumio volumio[985]: info: ------------------------------------------- Oct 24 15:50:05 volumio volumio[985]: info: Loading plugins from folder /volumio/app/plugins/ Oct 24 15:50:05 volumio volumio[985]: info: Adding plugin upnp to MyMusic Plugins Oct 24 15:50:05 volumio volumio[985]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 24 15:50:05 volumio volumio[985]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 24 15:50:05 volumio volumio[985]: info: Loading plugins from folder /data/plugins/ Oct 24 15:50:05 volumio volumio[985]: info: Loading plugin "system"... Oct 24 15:50:05 volumio volumio[985]: info: Loading plugin "appearance"... Oct 24 15:50:07 volumio volumio[985]: info: Loading plugin "network"... Oct 24 15:50:07 volumio volumio[985]: info: Refreshing Cached IP Addresses Oct 24 15:50:07 volumio sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 15:50:07 volumio sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:07 volumio sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 15:50:07 volumio sudo[1040]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:07 volumio sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:07 volumio sudo[1042]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:07 volumio volumio[985]: info: Loading plugin "services"... Oct 24 15:50:07 volumio volumio[985]: info: Loading plugin "alsa_controller"... Oct 24 15:50:07 volumio sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 24 15:50:07 volumio sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:07 volumio volumio[985]: warn: Unable to locate the audio output device IQaudIO Pi-DigiAMP+. Please configure a valid output device. Oct 24 15:50:07 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 15:50:07 volumio volumio[985]: info: Loading plugin "wizard"... Oct 24 15:50:07 volumio volumio[985]: info: Loading plugin "networkfs"... Oct 24 15:50:07 volumio volumio[985]: info: Starting Udev Watcher for removable devices Oct 24 15:50:07 volumio sudo[1067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.196/Public /mnt/NAS/Public Oct 24 15:50:07 volumio sudo[1067]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:07 volumio volumio[985]: info: Ignoring mount for partition: boot Oct 24 15:50:07 volumio volumio[985]: info: Ignoring mount for partition: volumio Oct 24 15:50:07 volumio volumio[985]: info: Ignoring mount for partition: volumio_data Oct 24 15:50:07 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 15:50:07 volumio volumio[985]: info: Loading plugin "volumio_command_line_client"... Oct 24 15:50:07 volumio volumio[985]: info: Loading plugin "upnp"... Oct 24 15:50:07 volumio volumio[985]: info: [1761317407369] Starting Upmpd Daemon Oct 24 15:50:07 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 15:50:07 volumio volumio[985]: info: Loading plugin "my_music"... Oct 24 15:50:07 volumio volumio[985]: info: Loading plugin "mpd"... Oct 24 15:50:07 volumio kernel: Key type cifs.spnego registered Oct 24 15:50:07 volumio kernel: Key type cifs.idmap registered Oct 24 15:50:07 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 24 15:50:07 volumio kernel: CIFS: Attempting to mount //192.168.50.196/Public Oct 24 15:50:07 volumio volumio[985]: info: Loading plugin "upnp_browser"... Oct 24 15:50:08 volumio sudo[1051]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:08 volumio sudo[1067]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:08 volumio volumio[985]: info: Loading plugin "alarm-clock"... Oct 24 15:50:08 volumio volumio[985]: info: Loading plugin "airplay_emulation"... Oct 24 15:50:08 volumio volumio[985]: info: Starting Shairport Sync Oct 24 15:50:08 volumio volumio[985]: info: Loading plugin "last_100"... Oct 24 15:50:08 volumio volumio[985]: info: Loading plugin "webradio"... Oct 24 15:50:08 volumio volumio[985]: info: Loading plugin "i2s_dacs"... Oct 24 15:50:08 volumio volumio[985]: info: Loading plugin "volumiodiscovery"... Oct 24 15:50:08 volumio volumio[985]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 15:50:08 volumio volumio[985]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 15:50:08 volumio volumio[985]: *** WARNING *** For more information see Oct 24 15:50:08 volumio volumio[985]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 15:50:08 volumio node[985]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 24 15:50:08 volumio volumio[985]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 15:50:08 volumio volumio[985]: *** WARNING *** For more information see Oct 24 15:50:08 volumio node[985]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 15:50:08 volumio node[985]: *** WARNING *** For more information see Oct 24 15:50:08 volumio node[985]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 24 15:50:08 volumio node[985]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 24 15:50:08 volumio node[985]: *** WARNING *** For more information see Oct 24 15:50:08 volumio volumio[985]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 24 15:50:08 volumio volumio[985]: info: Discovery: Started advertising with name: Volumio Oct 24 15:50:08 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 24 15:50:08 volumio volumio[985]: info: Plugin now_playing is not enabled Oct 24 15:50:08 volumio volumio[985]: info: Loading plugin "outputs"... Oct 24 15:50:08 volumio volumio[985]: info: Loading plugin "albumart"... Oct 24 15:50:08 volumio volumio[985]: info: Plugin example_plugin is not enabled Oct 24 15:50:08 volumio volumio[985]: info: Loading plugin "inputs"... Oct 24 15:50:09 volumio volumio[985]: info: Loading plugin "updater_comm"... Oct 24 15:50:09 volumio volumio[985]: info: Plugin mpdemulation is not enabled Oct 24 15:50:09 volumio volumio[985]: info: Loading plugin "rest_api"... Oct 24 15:50:09 volumio volumio[985]: info: Loading plugin "websocket"... Oct 24 15:50:09 volumio volumio[985]: info: Starting Socket.io Server version 2.3.0 Oct 24 15:50:09 volumio volumio[985]: info: Loading plugin "fusiondsp"... Oct 24 15:50:09 volumio volumio[985]: Forking 3 albumart workers Oct 24 15:50:09 volumio volumio[985]: info: Applying required configuration parameters for plugin fusiondsp Oct 24 15:50:10 volumio volumio[985]: info: Plugin 80s80s is not enabled Oct 24 15:50:10 volumio volumio[985]: info: Plugin mpd_oled is not enabled Oct 24 15:50:10 volumio volumio[985]: info: Loading plugin "Systeminfo"... Oct 24 15:50:10 volumio volumio[985]: Starting albumart workers Oct 24 15:50:10 volumio volumio[985]: Starting albumart workers Oct 24 15:50:10 volumio volumio[985]: Starting albumart workers Oct 24 15:50:11 volumio volumio[985]: info: Loading i18n strings for locale en Oct 24 15:50:11 volumio volumio[985]: Updating browse sources language Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::initPlayerControls Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 15:50:11 volumio volumio[985]: Express server listening on port 3000 Oct 24 15:50:11 volumio volumio[985]: [Metrics] WebUI: 8s 813.48ms Oct 24 15:50:11 volumio volumio[985]: info: CoreStateMachine::resetVolumioState Oct 24 15:50:11 volumio volumio[985]: info: CoreStateMachine::getcurrentVolume Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 15:50:11 volumio volumio[985]: info: Volumio Network Manager: Network status updated: 2 Oct 24 15:50:11 volumio volumio[985]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 24 15:50:11 volumio volumio[985]: info: VolumeController:: Volume=undefined Mute =false Oct 24 15:50:11 volumio volumio[985]: info: CoreStateMachine::pushState Oct 24 15:50:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Oct 24 15:50:11 volumio volumio[985]: info: CoreStateMachine::updateTrackBlock Oct 24 15:50:11 volumio volumio[985]: info: CorePlayQueue::getTrackBlock Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ Oct 24 15:50:11 volumio volumio[985]: info: Reloading queue from file Oct 24 15:50:11 volumio volumio[985]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 24 15:50:11 volumio volumio[985]: info: CoreStateMachine::setRepeat false single undefined Oct 24 15:50:11 volumio volumio[985]: info: CoreStateMachine::pushState Oct 24 15:50:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Oct 24 15:50:11 volumio volumio[985]: info: CoreStateMachine::setRandom false Oct 24 15:50:11 volumio volumio[985]: info: CoreStateMachine::pushState Oct 24 15:50:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Oct 24 15:50:11 volumio volumio[985]: info: Setting Device type: Raspberry PI Oct 24 15:50:11 volumio volumio[985]: info: DAC script executed Oct 24 15:50:11 volumio volumio[985]: info: Completed loading Core Plugins Oct 24 15:50:11 volumio volumio[985]: info: Preparing to generate the ALSA configuration file Oct 24 15:50:11 volumio volumio[985]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 15:50:11 volumio volumio[985]: info: Reading ALSA contributions from plugins. Oct 24 15:50:11 volumio volumio[985]: info: VolumeController:: Volume=undefined Mute =false Oct 24 15:50:11 volumio volumio[985]: info: CoreStateMachine::pushState Oct 24 15:50:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::volumioPushState Oct 24 15:50:11 volumio volumio[985]: info: Asound.conf file unchanged, so no further update is needed Oct 24 15:50:11 volumio volumio[985]: info: Output device has changed, restarting MPD Oct 24 15:50:11 volumio volumio[985]: info: Output device has changed, restarting Shairport Sync Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 15:50:11 volumio sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 15:50:11 volumio sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:11 volumio sudo[1163]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:11 volumio sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 15:50:11 volumio sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:11 volumio systemd[1]: Stopping Music Player Daemon... Oct 24 15:50:11 volumio volumio[985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 15:50:11 volumio volumio[985]: info: ___________ START PLUGINS ___________ Oct 24 15:50:11 volumio volumio[985]: info: ControllerMpd::onStart: Initializing MPD Oct 24 15:50:11 volumio volumio[985]: info: Creating MPD Configuration file Oct 24 15:50:11 volumio volumio-remote-updater[667]: [2025-10-24 15:50:11] [connect] Successful connection Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 15:50:11 volumio volumio[985]: info: [1761317411923] CoreMusicLibrary::Adding element Media Servers Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 15:50:11 volumio sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 15:50:11 volumio sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:11 volumio sudo[1172]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:11 volumio sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 15:50:11 volumio sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 15:50:12 volumio systemd[1]: mpd.service: Succeeded. Oct 24 15:50:12 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 15:50:12 volumio volumio[985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 15:50:12 volumio volumio[985]: info: [1761317412078] CoreMusicLibrary::Adding element Last_100 Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 24 15:50:12 volumio volumio[985]: info: [1761317412082] CoreMusicLibrary::Adding element Webradio Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 15:50:12 volumio systemd[1]: Starting Music Player Daemon... Oct 24 15:50:12 volumio volumio[985]: info: Initializing BBC Radios Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: Loading i18n strings for locale en Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: FusionDsp - mixtype--------------------- undefined Oct 24 15:50:12 volumio volumio[985]: info: Volumio Calling Home Oct 24 15:50:12 volumio sudo[1181]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 15:50:12 volumio sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:12 volumio sudo[1181]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:12 volumio volumio[985]: info: Preparing to generate the ALSA configuration file Oct 24 15:50:12 volumio volumio[985]: info: Discovery: adding 7215da8d-286f-461f-adce-4d428f9bad80 Oct 24 15:50:12 volumio volumio[985]: info: Discovery: Found device Volumio Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Oct 24 15:50:12 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Oct 24 15:50:12 volumio volumio[985]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 24 15:50:12 volumio volumio[985]: info: Reading ALSA contributions from plugins. Oct 24 15:50:12 volumio volumio[985]: info: MPD Permissions set Oct 24 15:50:12 volumio volumio[985]: info: MPD Permissions set Oct 24 15:50:12 volumio volumio-remote-updater[667]: [2025-10-24 15:50:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1761317411 101 Oct 24 15:50:12 volumio volumio[985]: 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 Oct 24 15:50:12 volumio volumio[985]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 24 15:50:12 volumio volumio[985]: info: Volumio called home Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: Starting Shairport Sync Oct 24 15:50:12 volumio volumio[985]: info: Starting Shairport Sync Oct 24 15:50:12 volumio volumio[985]: info: Starting Shairport Sync Oct 24 15:50:12 volumio sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 15:50:12 volumio sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:12 volumio sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 15:50:12 volumio sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 15:50:12 volumio sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 15:50:12 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Oct 24 15:50:12 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Oct 24 15:50:12 volumio sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 15:50:12 volumio volumio[985]: info: Asound.conf file unchanged, so no further update is needed Oct 24 15:50:12 volumio volumio[985]: info: Output device has changed, restarting MPD Oct 24 15:50:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 15:50:12 volumio volumio[985]: info: Output device has changed, restarting Shairport Sync Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 15:50:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 15:50:12 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 24 15:50:12 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 24 15:50:12 volumio sudo[1208]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:12 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 15:50:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 15:50:12 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:12 volumio sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 15:50:13 volumio sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:13 volumio sudo[1218]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 15:50:13 volumio sudo[1213]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:13 volumio sudo[1210]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:13 volumio volumio[985]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 15:50:13 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 24 15:50:13 volumio systemd[1]: mpd.service: Succeeded. Oct 24 15:50:13 volumio systemd[1]: Stopped Music Player Daemon. Oct 24 15:50:13 volumio systemd[1]: Starting Music Player Daemon... Oct 24 15:50:13 volumio volumio[985]: info: MPD Permissions set Oct 24 15:50:13 volumio volumio[985]: info: Shairport-Sync Started Oct 24 15:50:13 volumio volumio[985]: Error adding Membership: Error: addMembership EINVAL Oct 24 15:50:13 volumio volumio[985]: info: Shairport-Sync Started Oct 24 15:50:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 15:50:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:13 volumio volumio[985]: info: Starting Shairport Sync Oct 24 15:50:13 volumio sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 15:50:13 volumio sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:13 volumio sudo[1227]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 15:50:13 volumio sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 15:50:13 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 24 15:50:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 15:50:13 volumio sudo[1227]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 15:50:13 volumio sudo[1235]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:13 volumio volumio[985]: info: Shairport-Sync Started Oct 24 15:50:14 volumio volumio[985]: info: camilladsp service started and running in background, instance 1 Oct 24 15:50:14 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 15:50:14 volumio volumio[985]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 15:50:14 volumio volumio[985]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 24 15:50:14 volumio volumio[985]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 24 15:50:14 volumio volumio[985]: info: FusionDsp loaded Oct 24 15:50:14 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 15:50:14 volumio sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 24 15:50:14 volumio sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 15:50:14 volumio volumio[985]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 24 15:50:14 volumio volumio[985]: info: Adding Signal Path Element [object Object] Oct 24 15:50:14 volumio volumio[985]: info: Adding fusiondspeq DSP Signal Path Element Oct 24 15:50:14 volumio volumio[985]: info: FusionDsp - ---- installed callbackRead Oct 24 15:50:14 volumio volumio[985]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 15:50:14 volumio volumio[985]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 24 15:50:14 volumio volumio[985]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 24 15:50:14 volumio volumio[985]: at onErrorNT (internal/child_process.js:465:16) Oct 24 15:50:14 volumio volumio[985]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 24 15:50:14 volumio volumio[985]: errno: -2, Oct 24 15:50:14 volumio volumio[985]: code: 'ENOENT', Oct 24 15:50:14 volumio volumio[985]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 15:50:14 volumio volumio[985]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 24 15:50:14 volumio volumio[985]: spawnargs: [ Oct 24 15:50:14 volumio volumio[985]: '-p', Oct 24 15:50:14 volumio volumio[985]: 9876, Oct 24 15:50:14 volumio volumio[985]: '-o', Oct 24 15:50:14 volumio volumio[985]: '/tmp/camilladsp.log', Oct 24 15:50:14 volumio volumio[985]: '-l', Oct 24 15:50:14 volumio volumio[985]: 'warn', Oct 24 15:50:14 volumio volumio[985]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 24 15:50:14 volumio volumio[985]: ] Oct 24 15:50:14 volumio volumio[985]: } Oct 24 15:50:14 volumio volumio[985]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 15:50:14 volumio systemd[1]: Started FusionDsp Daemon. Oct 24 15:50:14 volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:14 volumio volumio[1254]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 24 15:50:14 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 24 15:50:14 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 24 15:50:14 volumio mpd[1239]: Oct 24 15:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 15:50:14 volumio systemd[1]: Started Music Player Daemon. Oct 24 15:50:14 volumio sudo[1220]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:14 volumio sudo[1174]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:14 volumio sudo[1165]: pam_unix(sudo:session): session closed for user root Oct 24 15:50:15 volumio sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 15:49 Oct 24 15:50:15 volumio sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"