-- Logs begin at Mon 2025-05-12 20:41:04 JST, end at Mon 2025-05-12 21:03:28 JST. -- May 12 21:02:01 volumio volumio[29083]: info: ------------------------------------------- May 12 21:02:01 volumio volumio[29083]: info: ----- Volumio3 ---- May 12 21:02:01 volumio volumio[29083]: info: ------------------------------------------- May 12 21:02:01 volumio volumio[29083]: info: ----- System startup ---- May 12 21:02:01 volumio volumio[29083]: info: ------------------------------------------- May 12 21:02:02 volumio volumio[29083]: info: MYVOLUMIO Environment detected May 12 21:02:02 volumio volumio[29083]: info: Plugin folders cleanup May 12 21:02:02 volumio volumio[29083]: info: Scanning into folder /volumio/app/plugins/ May 12 21:02:02 volumio volumio[29083]: info: Scanning category audio_interface May 12 21:02:02 volumio volumio[29083]: info: Scanning category miscellanea May 12 21:02:02 volumio volumio[29083]: info: Scanning category music_service May 12 21:02:02 volumio volumio[29083]: info: Scanning category plugins.json May 12 21:02:02 volumio volumio[29083]: info: Scanning category system_controller May 12 21:02:02 volumio volumio[29083]: info: Scanning category user_interface May 12 21:02:02 volumio volumio[29083]: info: Scanning into folder /data/plugins/ May 12 21:02:02 volumio volumio[29083]: info: Scanning category audio_interface May 12 21:02:02 volumio volumio[29083]: info: Scanning category music_service May 12 21:02:02 volumio volumio[29083]: info: Plugin folders cleanup completed May 12 21:02:02 volumio volumio[29083]: info: ------------------------------------------- May 12 21:02:02 volumio volumio[29083]: info: ----- Core plugins startup ---- May 12 21:02:02 volumio volumio[29083]: info: ------------------------------------------- May 12 21:02:02 volumio volumio[29083]: info: Loading plugins from folder /volumio/app/plugins/ May 12 21:02:02 volumio volumio[29083]: info: Adding plugin upnp to MyMusic Plugins May 12 21:02:02 volumio volumio[29083]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 21:02:02 volumio volumio[29083]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 21:02:02 volumio volumio[29083]: info: Loading plugins from folder /data/plugins/ May 12 21:02:02 volumio volumio[29083]: info: Loading plugin "system"... May 12 21:02:02 volumio volumio[29083]: info: Loading plugin "appearance"... May 12 21:02:03 volumio volumio[29083]: info: Loading plugin "network"... May 12 21:02:03 volumio volumio[29083]: info: Refreshing Cached IP Addresses May 12 21:02:03 volumio sudo[29109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 21:02:03 volumio sudo[29109]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:03 volumio sudo[29111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 21:02:03 volumio sudo[29111]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:03 volumio sudo[29109]: pam_unix(sudo:session): session closed for user root May 12 21:02:03 volumio sudo[29111]: pam_unix(sudo:session): session closed for user root May 12 21:02:03 volumio volumio[29083]: info: Loading plugin "services"... May 12 21:02:03 volumio volumio[29083]: info: Loading plugin "alsa_controller"... May 12 21:02:03 volumio sudo[29120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 12 21:02:03 volumio sudo[29120]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:03 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:03 volumio volumio[29083]: info: Loading plugin "wizard"... May 12 21:02:03 volumio volumio[29083]: info: Loading plugin "networkfs"... May 12 21:02:03 volumio volumio[29083]: info: Cannot mount NAS NASMusic at system boot, trial number 1 ,retrying in 5 seconds May 12 21:02:03 volumio volumio[29083]: info: Cannot mount NAS fater at system boot, trial number 1 ,retrying in 5 seconds May 12 21:02:03 volumio volumio[29083]: info: Starting Udev Watcher for removable devices May 12 21:02:03 volumio volumio[29083]: info: Ignoring mount for partition: boot May 12 21:02:03 volumio volumio[29083]: info: Ignoring mount for partition: volumio May 12 21:02:03 volumio volumio[29083]: info: Ignoring mount for partition: volumio_data May 12 21:02:03 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:03 volumio volumio[29083]: info: Loading plugin "volumio_command_line_client"... May 12 21:02:03 volumio volumio[29083]: info: Loading plugin "upnp"... May 12 21:02:03 volumio volumio[29083]: info: [1747051323605] Starting Upmpd Daemon May 12 21:02:03 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:03 volumio volumio[29083]: info: Loading plugin "my_music"... May 12 21:02:03 volumio volumio[29083]: info: Loading plugin "mpd"... May 12 21:02:03 volumio volumio[29083]: info: Loading plugin "upnp_browser"... May 12 21:02:04 volumio volumio[29083]: info: Loading plugin "alarm-clock"... May 12 21:02:04 volumio sudo[29120]: pam_unix(sudo:session): session closed for user root May 12 21:02:04 volumio volumio[29083]: info: Loading plugin "airplay_emulation"... May 12 21:02:04 volumio volumio[29083]: info: Starting Shairport Sync May 12 21:02:04 volumio volumio[29083]: info: Loading plugin "last_100"... May 12 21:02:04 volumio volumio[29083]: info: Loading plugin "webradio"... May 12 21:02:04 volumio volumio[29083]: info: Loading plugin "i2s_dacs"... May 12 21:02:04 volumio volumio[29083]: info: I2S DAC not set, start Auto-detection May 12 21:02:04 volumio volumio[29083]: info: Loading plugin "volumiodiscovery"... May 12 21:02:04 volumio volumio-remote-updater[601]: [2025-05-12 21:02:04] [connect] Successful connection May 12 21:02:04 volumio volumio[29083]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:02:04 volumio volumio[29083]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:04 volumio volumio[29083]: *** WARNING *** For more information see May 12 21:02:04 volumio volumio[29083]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:02:04 volumio node[29083]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:02:04 volumio volumio[29083]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:04 volumio volumio[29083]: *** WARNING *** For more information see May 12 21:02:04 volumio node[29083]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:04 volumio node[29083]: *** WARNING *** For more information see May 12 21:02:04 volumio node[29083]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:02:04 volumio node[29083]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:04 volumio node[29083]: *** WARNING *** For more information see May 12 21:02:04 volumio volumio[29083]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 21:02:04 volumio volumio[29083]: info: Discovery: Started advertising with name: Volumio May 12 21:02:04 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:04 volumio volumio[29083]: info: Loading plugin "youtube2"... May 12 21:02:07 volumio volumio[29083]: info: Loading plugin "outputs"... May 12 21:02:07 volumio volumio[29083]: info: Loading plugin "albumart"... May 12 21:02:07 volumio volumio[29083]: info: Plugin example_plugin is not enabled May 12 21:02:07 volumio volumio[29083]: info: Loading plugin "inputs"... May 12 21:02:07 volumio volumio[29083]: info: Loading plugin "updater_comm"... May 12 21:02:08 volumio volumio[29083]: info: Plugin mpdemulation is not enabled May 12 21:02:08 volumio volumio[29083]: info: Loading plugin "rest_api"... May 12 21:02:08 volumio volumio[29083]: info: Loading plugin "websocket"... May 12 21:02:08 volumio volumio[29083]: info: Starting Socket.io Server version 2.3.0 May 12 21:02:08 volumio volumio[29083]: info: Loading plugin "fusiondsp"... May 12 21:02:08 volumio volumio[29083]: Forking 3 albumart workers May 12 21:02:08 volumio volumio[29083]: info: Applying required configuration parameters for plugin fusiondsp May 12 21:02:08 volumio volumio[29083]: info: Loading i18n strings for locale ja May 12 21:02:08 volumio volumio[29083]: Updating browse sources language May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::initPlayerControls May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:08 volumio volumio[29083]: Express server listening on port 3000 May 12 21:02:08 volumio volumio[29083]: [Metrics] WebUI: 7s 853.01ms May 12 21:02:08 volumio volumio[29083]: info: CoreStateMachine::resetVolumioState May 12 21:02:08 volumio volumio[29083]: info: CoreStateMachine::getcurrentVolume May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:02:08 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:08 volumio volumio[29083]: info: Cannot mount NAS NASMusic at system boot, trial number 2 ,retrying in 5 seconds May 12 21:02:08 volumio volumio[29083]: info: Cannot mount NAS fater at system boot, trial number 2 ,retrying in 5 seconds May 12 21:02:08 volumio volumio[29083]: info: Volumio Network Manager: Network status updated: 2 May 12 21:02:09 volumio volumio[29083]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 12 21:02:09 volumio volumio[29083]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 12 21:02:09 volumio volumio-remote-updater[601]: [2025-05-12 21:02:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747051324 101 May 12 21:02:09 volumio volumio[29083]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 12 21:02:09 volumio volumio[29083]: info: Reloading queue from file May 12 21:02:09 volumio volumio[29083]: info: CoreStateMachine::setRepeat null single undefined May 12 21:02:09 volumio volumio[29083]: info: CoreStateMachine::pushState May 12 21:02:09 volumio volumio[29083]: info: CorePlayQueue::getTrack 0 May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::volumioPushState May 12 21:02:09 volumio volumio[29083]: info: CoreStateMachine::setRandom null May 12 21:02:09 volumio volumio[29083]: info: CoreStateMachine::pushState May 12 21:02:09 volumio volumio[29083]: info: CorePlayQueue::getTrack 0 May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::volumioPushState May 12 21:02:09 volumio volumio[29083]: info: Setting Device type: Raspberry PI May 12 21:02:09 volumio volumio[29083]: Starting albumart workers May 12 21:02:09 volumio volumio[29083]: info: VolumeController:: Volume=44 Mute =false May 12 21:02:09 volumio volumio[29083]: info: CoreStateMachine::pushState May 12 21:02:09 volumio volumio[29083]: info: CorePlayQueue::getTrack 0 May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::volumioPushState May 12 21:02:09 volumio volumio[29083]: info: CoreStateMachine::updateTrackBlock May 12 21:02:09 volumio volumio[29083]: info: CorePlayQueue::getTrackBlock May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:02:09 volumio volumio[29083]: Starting albumart workers May 12 21:02:09 volumio volumio[29083]: info: Completed loading Core Plugins May 12 21:02:09 volumio volumio[29083]: info: Preparing to generate the ALSA configuration file May 12 21:02:09 volumio volumio[29083]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:02:09 volumio volumio[29083]: info: Reading ALSA contributions from plugins. May 12 21:02:09 volumio volumio[29083]: Starting albumart workers May 12 21:02:09 volumio volumio[29083]: info: Discovery: adding 4eac4e30-3d36-47e9-8023-9350033c0a5c May 12 21:02:09 volumio volumio[29083]: info: Discovery: Found device Volumio May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::volumioGetState May 12 21:02:09 volumio volumio[29083]: info: CorePlayQueue::getTrack 0 May 12 21:02:09 volumio volumio[29083]: info: VolumeController:: Volume=44 Mute =false May 12 21:02:09 volumio volumio[29083]: info: CoreStateMachine::pushState May 12 21:02:09 volumio volumio[29083]: info: CorePlayQueue::getTrack 0 May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::volumioPushState May 12 21:02:09 volumio volumio[29083]: info: Asound.conf file unchanged, so no further update is needed May 12 21:02:09 volumio volumio[29083]: info: Output device has changed, restarting MPD May 12 21:02:09 volumio volumio[29083]: info: Output device has changed, restarting Shairport Sync May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:09 volumio sudo[29182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:09 volumio sudo[29182]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:09 volumio sudo[29182]: pam_unix(sudo:session): session closed for user root May 12 21:02:09 volumio sudo[29184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:09 volumio sudo[29184]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:09 volumio volumio[29083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:09 volumio volumio[29083]: info: ___________ START PLUGINS ___________ May 12 21:02:09 volumio systemd[1]: Stopping Music Player Daemon... May 12 21:02:09 volumio volumio[29083]: info: ControllerMpd::onStart: Initializing MPD May 12 21:02:09 volumio volumio[29083]: info: Creating MPD Configuration file May 12 21:02:09 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:09 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:09 volumio sudo[29191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:09 volumio sudo[29191]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:09 volumio volumio[29083]: info: [1747051329823] CoreMusicLibrary::Adding element DLNAサーバー May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:09 volumio sudo[29191]: pam_unix(sudo:session): session closed for user root May 12 21:02:09 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:09 volumio sudo[29195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:09 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:09 volumio sudo[29195]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:09 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 12 21:02:09 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:09 volumio volumio[29083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:09 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:10 volumio volumio[29083]: info: [1747051330080] CoreMusicLibrary::Adding element Last_100 May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:10 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:10 volumio volumio[29083]: info: [1747051330096] CoreMusicLibrary::Adding element Webradio May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:10 volumio volumio[29083]: info: Initializing BBC Radios May 12 21:02:10 volumio sudo[29204]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:10 volumio sudo[29204]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:10 volumio sudo[29204]: pam_unix(sudo:session): session closed for user root May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:10 volumio volumio[29083]: info: [1747051330268] CoreMusicLibrary::Adding element YouTube2 May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:10 volumio volumio[29083]: Cannot find translation for source YouTube2 May 12 21:02:10 volumio volumio[29083]: info: Loading i18n strings for locale ja May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: FusionDsp - mixtype--------------------- Hardware May 12 21:02:10 volumio volumio[29083]: info: Volumio Calling Home May 12 21:02:10 volumio volumio[29083]: info: Preparing to generate the ALSA configuration file May 12 21:02:10 volumio volumio[29083]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:02:10 volumio volumio[29083]: info: Reading ALSA contributions from plugins. May 12 21:02:10 volumio volumio[29083]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 12 21:02:10 volumio volumio[29083]: info: MPD Permissions set May 12 21:02:10 volumio volumio[29083]: info: MPD Permissions set May 12 21:02:10 volumio volumio[29083]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:10 volumio volumio[29083]: info: Starting Shairport Sync May 12 21:02:10 volumio volumio[29083]: info: Starting Shairport Sync May 12 21:02:10 volumio sudo[29252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:10 volumio volumio[29083]: info: Starting Shairport Sync May 12 21:02:10 volumio sudo[29252]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:10 volumio sudo[29254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:10 volumio sudo[29254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:10 volumio sudo[29258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:10 volumio volumio[29083]: info: CoreCommandRouter::volumioGetState May 12 21:02:10 volumio sudo[29258]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:10 volumio volumio[29083]: info: CorePlayQueue::getTrack 0 May 12 21:02:10 volumio volumio[29083]: info: Asound.conf file unchanged, so no further update is needed May 12 21:02:10 volumio volumio[29083]: info: Output device has changed, restarting MPD May 12 21:02:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:02:10 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:02:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:02:11 volumio volumio[29083]: info: Output device has changed, restarting Shairport Sync May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:11 volumio sudo[29262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:11 volumio sudo[29262]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:11 volumio sudo[29262]: pam_unix(sudo:session): session closed for user root May 12 21:02:11 volumio sudo[29265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:11 volumio sudo[29265]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:02:11 volumio sudo[29254]: pam_unix(sudo:session): session closed for user root May 12 21:02:11 volumio sudo[29252]: pam_unix(sudo:session): session closed for user root May 12 21:02:11 volumio sudo[29258]: pam_unix(sudo:session): session closed for user root May 12 21:02:11 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 21:02:11 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:11 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:11 volumio volumio[29083]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:11 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:11 volumio volumio[29083]: info: Volumio called home May 12 21:02:11 volumio volumio[29083]: info: MPD Permissions set May 12 21:02:11 volumio volumio[29083]: info: Shairport-Sync Started May 12 21:02:11 volumio volumio[29083]: Error adding Membership: Error: addMembership EINVAL May 12 21:02:11 volumio volumio[29083]: info: Shairport-Sync Started May 12 21:02:11 volumio volumio[29083]: info: Shairport-Sync Started May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:11 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:11 volumio volumio[29083]: info: Starting Shairport Sync May 12 21:02:11 volumio sudo[29279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:11 volumio sudo[29279]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:11 volumio sudo[29272]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:02:11 volumio sudo[29272]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:11 volumio sudo[29272]: pam_unix(sudo:session): session closed for user root May 12 21:02:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:02:11 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:02:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:02:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:02:11 volumio sudo[29279]: pam_unix(sudo:session): session closed for user root May 12 21:02:11 volumio volumio[29083]: info: Shairport-Sync Started May 12 21:02:12 volumio volumio[29083]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 21:02:12 volumio volumio[29083]: 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 May 12 21:02:12 volumio volumio[29083]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 21:02:12 volumio volumio[29083]: info: camilladsp service started and running in background, instance 1 May 12 21:02:12 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:12 volumio volumio[29083]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 21:02:12 volumio volumio[29083]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 12 21:02:12 volumio volumio[29083]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 21:02:12 volumio volumio[29083]: info: FusionDsp loaded May 12 21:02:12 volumio volumio[29083]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 21:02:12 volumio sudo[29298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 12 21:02:12 volumio sudo[29298]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:12 volumio volumio[29083]: info: FusionDsp - Reporting Fusion DSP Enabled May 12 21:02:12 volumio volumio[29083]: info: Adding Signal Path Element [object Object] May 12 21:02:12 volumio volumio[29083]: info: Adding fusiondspeq DSP Signal Path Element May 12 21:02:12 volumio volumio[29083]: info: FusionDsp - ---- installed callbackRead May 12 21:02:12 volumio volumio[29083]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:02:12 volumio sudo[29298]: pam_unix(sudo:session): session closed for user root May 12 21:02:12 volumio volumio[29083]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 12 21:02:12 volumio volumio[29083]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 12 21:02:12 volumio volumio[29083]: at onErrorNT (internal/child_process.js:465:16) May 12 21:02:12 volumio volumio[29083]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 12 21:02:12 volumio volumio[29083]: errno: -2, May 12 21:02:12 volumio volumio[29083]: code: 'ENOENT', May 12 21:02:12 volumio volumio[29083]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 12 21:02:12 volumio volumio[29083]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 12 21:02:12 volumio volumio[29083]: spawnargs: [ May 12 21:02:12 volumio volumio[29083]: '-p', May 12 21:02:12 volumio volumio[29083]: 9876, May 12 21:02:12 volumio volumio[29083]: '-o', May 12 21:02:12 volumio volumio[29083]: '/tmp/camilladsp.log', May 12 21:02:12 volumio volumio[29083]: '-l', May 12 21:02:12 volumio volumio[29083]: 'warn', May 12 21:02:12 volumio volumio[29083]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 12 21:02:12 volumio volumio[29083]: ] May 12 21:02:12 volumio volumio[29083]: } May 12 21:02:12 volumio volumio[29083]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:02:12 volumio mpd[29282]: May 12 21:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 21:02:13 volumio sudo[29309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 21:01 May 12 21:02:13 volumio sudo[29309]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:13 volumio sudo[29309]: pam_unix(sudo:session): session closed for user root May 12 21:02:13 volumio systemd[1]: Started Music Player Daemon. May 12 21:02:13 volumio sudo[29184]: pam_unix(sudo:session): session closed for user root May 12 21:02:13 volumio sudo[29265]: pam_unix(sudo:session): session closed for user root May 12 21:02:13 volumio volumio-remote-updater[601]: [2025-05-12 21:02:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 12 21:02:13 volumio volumio-remote-updater[601]: [2025-05-12 21:02:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 12 21:02:13 volumio sudo[29195]: pam_unix(sudo:session): session closed for user root May 12 21:02:13 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 12 21:02:13 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 12 21:02:13 volumio systemd[1]: Started dynamicswap service. May 12 21:02:13 volumio systemd[1]: dynamicswap.service: Succeeded. May 12 21:02:13 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 12 21:02:13 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 108. May 12 21:02:13 volumio systemd[1]: Started dynamicswap service. May 12 21:02:13 volumio systemd[1]: Stopped Volumio Backend Module. May 12 21:02:13 volumio systemd[1]: Started Volumio Backend Module. May 12 21:02:13 volumio systemd[1]: dynamicswap.service: Succeeded. May 12 21:02:15 volumio volumio[29322]: info: ------------------------------------------- May 12 21:02:15 volumio volumio[29322]: info: ----- Volumio3 ---- May 12 21:02:15 volumio volumio[29322]: info: ------------------------------------------- May 12 21:02:15 volumio volumio[29322]: info: ----- System startup ---- May 12 21:02:15 volumio volumio[29322]: info: ------------------------------------------- May 12 21:02:16 volumio volumio[29322]: info: MYVOLUMIO Environment detected May 12 21:02:16 volumio volumio[29322]: info: Plugin folders cleanup May 12 21:02:16 volumio volumio[29322]: info: Scanning into folder /volumio/app/plugins/ May 12 21:02:16 volumio volumio[29322]: info: Scanning category audio_interface May 12 21:02:16 volumio volumio[29322]: info: Scanning category miscellanea May 12 21:02:16 volumio volumio[29322]: info: Scanning category music_service May 12 21:02:16 volumio volumio[29322]: info: Scanning category plugins.json May 12 21:02:16 volumio volumio[29322]: info: Scanning category system_controller May 12 21:02:16 volumio volumio[29322]: info: Scanning category user_interface May 12 21:02:16 volumio volumio[29322]: info: Scanning into folder /data/plugins/ May 12 21:02:16 volumio volumio[29322]: info: Scanning category audio_interface May 12 21:02:16 volumio volumio[29322]: info: Scanning category music_service May 12 21:02:16 volumio volumio[29322]: info: Plugin folders cleanup completed May 12 21:02:16 volumio volumio[29322]: info: ------------------------------------------- May 12 21:02:16 volumio volumio[29322]: info: ----- Core plugins startup ---- May 12 21:02:16 volumio volumio[29322]: info: ------------------------------------------- May 12 21:02:16 volumio volumio[29322]: info: Loading plugins from folder /volumio/app/plugins/ May 12 21:02:16 volumio volumio[29322]: info: Adding plugin upnp to MyMusic Plugins May 12 21:02:16 volumio volumio[29322]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 21:02:16 volumio volumio[29322]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 21:02:16 volumio volumio[29322]: info: Loading plugins from folder /data/plugins/ May 12 21:02:16 volumio volumio[29322]: info: Loading plugin "system"... May 12 21:02:16 volumio volumio[29322]: info: Loading plugin "appearance"... May 12 21:02:17 volumio volumio[29322]: info: Loading plugin "network"... May 12 21:02:17 volumio volumio[29322]: info: Refreshing Cached IP Addresses May 12 21:02:17 volumio sudo[29348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 21:02:17 volumio sudo[29348]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:17 volumio sudo[29348]: pam_unix(sudo:session): session closed for user root May 12 21:02:17 volumio sudo[29350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 21:02:17 volumio sudo[29350]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:17 volumio sudo[29350]: pam_unix(sudo:session): session closed for user root May 12 21:02:17 volumio volumio[29322]: info: Loading plugin "services"... May 12 21:02:17 volumio volumio[29322]: info: Loading plugin "alsa_controller"... May 12 21:02:17 volumio sudo[29359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 12 21:02:17 volumio sudo[29359]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:17 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:17 volumio volumio[29322]: info: Loading plugin "wizard"... May 12 21:02:17 volumio volumio[29322]: info: Loading plugin "networkfs"... May 12 21:02:17 volumio volumio[29322]: info: Cannot mount NAS NASMusic at system boot, trial number 1 ,retrying in 5 seconds May 12 21:02:17 volumio volumio[29322]: info: Cannot mount NAS fater at system boot, trial number 1 ,retrying in 5 seconds May 12 21:02:17 volumio volumio[29322]: info: Starting Udev Watcher for removable devices May 12 21:02:17 volumio volumio[29322]: info: Ignoring mount for partition: boot May 12 21:02:17 volumio volumio[29322]: info: Ignoring mount for partition: volumio May 12 21:02:17 volumio volumio[29322]: info: Ignoring mount for partition: volumio_data May 12 21:02:17 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:17 volumio volumio[29322]: info: Loading plugin "volumio_command_line_client"... May 12 21:02:17 volumio volumio[29322]: info: Loading plugin "upnp"... May 12 21:02:17 volumio volumio[29322]: info: [1747051337503] Starting Upmpd Daemon May 12 21:02:17 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:17 volumio volumio[29322]: info: Loading plugin "my_music"... May 12 21:02:17 volumio volumio[29322]: info: Loading plugin "mpd"... May 12 21:02:17 volumio volumio[29322]: info: Loading plugin "upnp_browser"... May 12 21:02:18 volumio volumio[29322]: info: Loading plugin "alarm-clock"... May 12 21:02:18 volumio sudo[29359]: pam_unix(sudo:session): session closed for user root May 12 21:02:18 volumio volumio[29322]: info: Loading plugin "airplay_emulation"... May 12 21:02:18 volumio volumio-remote-updater[601]: [2025-05-12 21:02:18] [connect] Successful connection May 12 21:02:18 volumio volumio[29322]: info: Starting Shairport Sync May 12 21:02:18 volumio volumio[29322]: info: Loading plugin "last_100"... May 12 21:02:18 volumio volumio[29322]: info: Loading plugin "webradio"... May 12 21:02:18 volumio volumio[29322]: info: Loading plugin "i2s_dacs"... May 12 21:02:18 volumio volumio[29322]: info: I2S DAC not set, start Auto-detection May 12 21:02:18 volumio volumio[29322]: info: Loading plugin "volumiodiscovery"... May 12 21:02:18 volumio volumio[29322]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:02:18 volumio volumio[29322]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:18 volumio volumio[29322]: *** WARNING *** For more information see May 12 21:02:18 volumio node[29322]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:02:18 volumio volumio[29322]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:02:18 volumio volumio[29322]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:18 volumio volumio[29322]: *** WARNING *** For more information see May 12 21:02:18 volumio node[29322]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:18 volumio node[29322]: *** WARNING *** For more information see May 12 21:02:18 volumio node[29322]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:02:18 volumio node[29322]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:18 volumio node[29322]: *** WARNING *** For more information see May 12 21:02:18 volumio volumio[29322]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 21:02:18 volumio volumio[29322]: info: Discovery: Started advertising with name: Volumio May 12 21:02:18 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:18 volumio volumio[29322]: info: Loading plugin "youtube2"... May 12 21:02:21 volumio volumio[29322]: info: Loading plugin "outputs"... May 12 21:02:21 volumio volumio[29322]: info: Loading plugin "albumart"... May 12 21:02:21 volumio volumio[29322]: info: Plugin example_plugin is not enabled May 12 21:02:21 volumio volumio[29322]: info: Loading plugin "inputs"... May 12 21:02:21 volumio volumio[29322]: info: Loading plugin "updater_comm"... May 12 21:02:21 volumio volumio[29322]: info: Plugin mpdemulation is not enabled May 12 21:02:21 volumio volumio[29322]: info: Loading plugin "rest_api"... May 12 21:02:21 volumio volumio[29322]: info: Loading plugin "websocket"... May 12 21:02:21 volumio volumio[29322]: info: Starting Socket.io Server version 2.3.0 May 12 21:02:21 volumio volumio[29322]: info: Loading plugin "fusiondsp"... May 12 21:02:22 volumio volumio[29322]: Forking 3 albumart workers May 12 21:02:22 volumio volumio[29322]: info: Applying required configuration parameters for plugin fusiondsp May 12 21:02:22 volumio volumio[29322]: info: Loading i18n strings for locale ja May 12 21:02:22 volumio volumio[29322]: Updating browse sources language May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::initPlayerControls May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:22 volumio volumio[29322]: Express server listening on port 3000 May 12 21:02:22 volumio volumio[29322]: [Metrics] WebUI: 7s 933.61ms May 12 21:02:22 volumio volumio[29322]: info: CoreStateMachine::resetVolumioState May 12 21:02:22 volumio volumio[29322]: info: CoreStateMachine::getcurrentVolume May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:02:22 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:22 volumio volumio[29322]: info: Cannot mount NAS NASMusic at system boot, trial number 2 ,retrying in 5 seconds May 12 21:02:22 volumio volumio[29322]: info: Cannot mount NAS fater at system boot, trial number 2 ,retrying in 5 seconds May 12 21:02:22 volumio volumio[29322]: info: Volumio Network Manager: Network status updated: 2 May 12 21:02:23 volumio volumio[29322]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 12 21:02:23 volumio volumio-remote-updater[601]: [2025-05-12 21:02:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747051338 101 May 12 21:02:23 volumio volumio[29322]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 May 12 21:02:23 volumio volumio[29322]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 12 21:02:23 volumio volumio[29322]: info: Reloading queue from file May 12 21:02:23 volumio volumio[29322]: info: CoreStateMachine::setRepeat null single undefined May 12 21:02:23 volumio volumio[29322]: info: CoreStateMachine::pushState May 12 21:02:23 volumio volumio[29322]: info: CorePlayQueue::getTrack 0 May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::volumioPushState May 12 21:02:23 volumio volumio[29322]: info: CoreStateMachine::setRandom null May 12 21:02:23 volumio volumio[29322]: info: CoreStateMachine::pushState May 12 21:02:23 volumio volumio[29322]: info: CorePlayQueue::getTrack 0 May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::volumioPushState May 12 21:02:23 volumio volumio[29322]: info: Setting Device type: Raspberry PI May 12 21:02:23 volumio volumio[29322]: Starting albumart workers May 12 21:02:23 volumio volumio[29322]: info: VolumeController:: Volume=44 Mute =false May 12 21:02:23 volumio volumio[29322]: info: CoreStateMachine::pushState May 12 21:02:23 volumio volumio[29322]: info: CorePlayQueue::getTrack 0 May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::volumioPushState May 12 21:02:23 volumio volumio[29322]: info: CoreStateMachine::updateTrackBlock May 12 21:02:23 volumio volumio[29322]: info: CorePlayQueue::getTrackBlock May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:02:23 volumio volumio[29322]: Starting albumart workers May 12 21:02:23 volumio volumio[29322]: Starting albumart workers May 12 21:02:23 volumio volumio[29322]: info: Completed loading Core Plugins May 12 21:02:23 volumio volumio[29322]: info: Preparing to generate the ALSA configuration file May 12 21:02:23 volumio volumio[29322]: info: Discovery: adding 4eac4e30-3d36-47e9-8023-9350033c0a5c May 12 21:02:23 volumio volumio[29322]: info: Discovery: Found device Volumio May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::volumioGetState May 12 21:02:23 volumio volumio[29322]: info: CorePlayQueue::getTrack 0 May 12 21:02:23 volumio volumio[29322]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:02:23 volumio volumio[29322]: info: Reading ALSA contributions from plugins. May 12 21:02:23 volumio volumio[29322]: info: VolumeController:: Volume=44 Mute =false May 12 21:02:23 volumio volumio[29322]: info: CoreStateMachine::pushState May 12 21:02:23 volumio volumio[29322]: info: CorePlayQueue::getTrack 0 May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::volumioPushState May 12 21:02:23 volumio volumio[29322]: info: Asound.conf file unchanged, so no further update is needed May 12 21:02:23 volumio volumio[29322]: info: Output device has changed, restarting MPD May 12 21:02:23 volumio sudo[29419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:23 volumio sudo[29419]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:23 volumio volumio[29322]: info: Output device has changed, restarting Shairport Sync May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:23 volumio sudo[29419]: pam_unix(sudo:session): session closed for user root May 12 21:02:23 volumio sudo[29422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:23 volumio sudo[29422]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:23 volumio systemd[1]: Stopping Music Player Daemon... May 12 21:02:23 volumio volumio[29322]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:23 volumio volumio[29322]: info: ___________ START PLUGINS ___________ May 12 21:02:23 volumio volumio[29322]: info: ControllerMpd::onStart: Initializing MPD May 12 21:02:23 volumio volumio[29322]: info: Creating MPD Configuration file May 12 21:02:23 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:23 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:23 volumio sudo[29429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:23 volumio sudo[29429]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:23 volumio sudo[29429]: pam_unix(sudo:session): session closed for user root May 12 21:02:23 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:23 volumio volumio[29322]: info: [1747051343861] CoreMusicLibrary::Adding element DLNAサーバー May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:23 volumio sudo[29432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:23 volumio sudo[29432]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:23 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 12 21:02:23 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:23 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:23 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:23 volumio volumio[29322]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:23 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:24 volumio volumio[29322]: info: [1747051344059] CoreMusicLibrary::Adding element Last_100 May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:24 volumio volumio[29322]: info: [1747051344064] CoreMusicLibrary::Adding element Webradio May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:24 volumio volumio[29322]: info: Initializing BBC Radios May 12 21:02:24 volumio sudo[29437]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:02:24 volumio sudo[29437]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:24 volumio sudo[29437]: pam_unix(sudo:session): session closed for user root May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:24 volumio volumio[29322]: info: [1747051344270] CoreMusicLibrary::Adding element YouTube2 May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:24 volumio volumio[29322]: Cannot find translation for source YouTube2 May 12 21:02:24 volumio volumio[29322]: info: Loading i18n strings for locale ja May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: FusionDsp - mixtype--------------------- Hardware May 12 21:02:24 volumio volumio[29322]: info: Volumio Calling Home May 12 21:02:24 volumio volumio[29322]: info: Preparing to generate the ALSA configuration file May 12 21:02:24 volumio volumio[29322]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:02:24 volumio volumio[29322]: info: Reading ALSA contributions from plugins. May 12 21:02:24 volumio volumio[29322]: info: MPD Permissions set May 12 21:02:24 volumio volumio[29322]: info: MPD Permissions set May 12 21:02:24 volumio volumio[29322]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:24 volumio volumio[29322]: info: Starting Shairport Sync May 12 21:02:24 volumio volumio[29322]: info: Starting Shairport Sync May 12 21:02:24 volumio sudo[29489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:24 volumio volumio[29322]: info: Starting Shairport Sync May 12 21:02:24 volumio sudo[29489]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:02:25 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:02:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:02:25 volumio sudo[29491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:25 volumio sudo[29491]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::volumioGetState May 12 21:02:25 volumio volumio[29322]: info: CorePlayQueue::getTrack 0 May 12 21:02:25 volumio sudo[29494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:25 volumio sudo[29494]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:25 volumio volumio[29322]: info: Volumio called home May 12 21:02:25 volumio volumio[29322]: info: Asound.conf file unchanged, so no further update is needed May 12 21:02:25 volumio volumio[29322]: info: Output device has changed, restarting MPD May 12 21:02:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:02:25 volumio sudo[29499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:25 volumio sudo[29499]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:25 volumio sudo[29499]: pam_unix(sudo:session): session closed for user root May 12 21:02:25 volumio sudo[29489]: pam_unix(sudo:session): session closed for user root May 12 21:02:25 volumio volumio[29322]: info: Output device has changed, restarting Shairport Sync May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:25 volumio sudo[29502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:25 volumio sudo[29502]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:02:25 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:02:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:02:25 volumio volumio[29322]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:02:25 volumio sudo[29494]: pam_unix(sudo:session): session closed for user root May 12 21:02:25 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 21:02:25 volumio sudo[29491]: pam_unix(sudo:session): session closed for user root May 12 21:02:25 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:25 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:25 volumio volumio[29322]: info: MPD Permissions set May 12 21:02:25 volumio volumio[29322]: info: Shairport-Sync Started May 12 21:02:25 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:25 volumio volumio[29322]: Error adding Membership: Error: addMembership EINVAL May 12 21:02:25 volumio volumio[29322]: info: Shairport-Sync Started May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:25 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:25 volumio volumio[29322]: info: Starting Shairport Sync May 12 21:02:25 volumio sudo[29517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:25 volumio sudo[29517]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:02:25 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:02:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:02:25 volumio sudo[29509]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:02:25 volumio sudo[29509]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:25 volumio sudo[29509]: pam_unix(sudo:session): session closed for user root May 12 21:02:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:02:25 volumio sudo[29517]: pam_unix(sudo:session): session closed for user root May 12 21:02:25 volumio volumio[29322]: info: Shairport-Sync Started May 12 21:02:26 volumio volumio[29322]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 21:02:26 volumio volumio[29322]: 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 May 12 21:02:26 volumio volumio[29322]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 21:02:26 volumio volumio[29322]: info: camilladsp service started and running in background, instance 1 May 12 21:02:26 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:26 volumio volumio[29322]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 21:02:26 volumio volumio[29322]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 12 21:02:26 volumio volumio[29322]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 21:02:26 volumio volumio[29322]: info: FusionDsp loaded May 12 21:02:26 volumio volumio[29322]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 21:02:26 volumio sudo[29573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 12 21:02:26 volumio sudo[29573]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:26 volumio volumio[29322]: info: FusionDsp - Reporting Fusion DSP Enabled May 12 21:02:26 volumio volumio[29322]: info: Adding Signal Path Element [object Object] May 12 21:02:26 volumio volumio[29322]: info: Adding fusiondspeq DSP Signal Path Element May 12 21:02:26 volumio volumio[29322]: info: FusionDsp - ---- installed callbackRead May 12 21:02:26 volumio volumio[29322]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:02:26 volumio sudo[29573]: pam_unix(sudo:session): session closed for user root May 12 21:02:26 volumio volumio[29322]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 12 21:02:26 volumio volumio[29322]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 12 21:02:26 volumio volumio[29322]: at onErrorNT (internal/child_process.js:465:16) May 12 21:02:26 volumio volumio[29322]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 12 21:02:26 volumio volumio[29322]: errno: -2, May 12 21:02:26 volumio volumio[29322]: code: 'ENOENT', May 12 21:02:26 volumio volumio[29322]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 12 21:02:26 volumio volumio[29322]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 12 21:02:26 volumio volumio[29322]: spawnargs: [ May 12 21:02:26 volumio volumio[29322]: '-p', May 12 21:02:26 volumio volumio[29322]: 9876, May 12 21:02:26 volumio volumio[29322]: '-o', May 12 21:02:26 volumio volumio[29322]: '/tmp/camilladsp.log', May 12 21:02:26 volumio volumio[29322]: '-l', May 12 21:02:26 volumio volumio[29322]: 'warn', May 12 21:02:26 volumio volumio[29322]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 12 21:02:26 volumio volumio[29322]: ] May 12 21:02:26 volumio volumio[29322]: } May 12 21:02:26 volumio volumio[29322]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:02:26 volumio mpd[29522]: May 12 21:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 21:02:27 volumio sudo[29586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 21:01 May 12 21:02:27 volumio sudo[29586]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:27 volumio sudo[29586]: pam_unix(sudo:session): session closed for user root May 12 21:02:27 volumio volumio-remote-updater[601]: [2025-05-12 21:02:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 12 21:02:27 volumio volumio-remote-updater[601]: [2025-05-12 21:02:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 12 21:02:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 12 21:02:27 volumio sudo[29502]: pam_unix(sudo:session): session closed for user root May 12 21:02:27 volumio sudo[29422]: pam_unix(sudo:session): session closed for user root May 12 21:02:27 volumio sudo[29432]: pam_unix(sudo:session): session closed for user root May 12 21:02:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 12 21:02:27 volumio systemd[1]: Started dynamicswap service. May 12 21:02:27 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 12 21:02:27 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 109. May 12 21:02:27 volumio systemd[1]: Stopped Volumio Backend Module. May 12 21:02:27 volumio systemd[1]: Started Volumio Backend Module. May 12 21:02:27 volumio systemd[1]: dynamicswap.service: Succeeded. May 12 21:02:27 volumio systemd[1]: Started Music Player Daemon. May 12 21:02:29 volumio volumio[29595]: info: ------------------------------------------- May 12 21:02:29 volumio volumio[29595]: info: ----- Volumio3 ---- May 12 21:02:29 volumio volumio[29595]: info: ------------------------------------------- May 12 21:02:29 volumio volumio[29595]: info: ----- System startup ---- May 12 21:02:29 volumio volumio[29595]: info: ------------------------------------------- May 12 21:02:30 volumio volumio[29595]: info: MYVOLUMIO Environment detected May 12 21:02:30 volumio volumio[29595]: info: Plugin folders cleanup May 12 21:02:30 volumio volumio[29595]: info: Scanning into folder /volumio/app/plugins/ May 12 21:02:30 volumio volumio[29595]: info: Scanning category audio_interface May 12 21:02:30 volumio volumio[29595]: info: Scanning category miscellanea May 12 21:02:30 volumio volumio[29595]: info: Scanning category music_service May 12 21:02:30 volumio volumio[29595]: info: Scanning category plugins.json May 12 21:02:30 volumio volumio[29595]: info: Scanning category system_controller May 12 21:02:30 volumio volumio[29595]: info: Scanning category user_interface May 12 21:02:30 volumio volumio[29595]: info: Scanning into folder /data/plugins/ May 12 21:02:30 volumio volumio[29595]: info: Scanning category audio_interface May 12 21:02:30 volumio volumio[29595]: info: Scanning category music_service May 12 21:02:30 volumio volumio[29595]: info: Plugin folders cleanup completed May 12 21:02:30 volumio volumio[29595]: info: ------------------------------------------- May 12 21:02:30 volumio volumio[29595]: info: ----- Core plugins startup ---- May 12 21:02:30 volumio volumio[29595]: info: ------------------------------------------- May 12 21:02:30 volumio volumio[29595]: info: Loading plugins from folder /volumio/app/plugins/ May 12 21:02:30 volumio volumio[29595]: info: Adding plugin upnp to MyMusic Plugins May 12 21:02:30 volumio volumio[29595]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 21:02:30 volumio volumio[29595]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 21:02:30 volumio volumio[29595]: info: Loading plugins from folder /data/plugins/ May 12 21:02:30 volumio volumio[29595]: info: Loading plugin "system"... May 12 21:02:30 volumio volumio[29595]: info: Loading plugin "appearance"... May 12 21:02:31 volumio volumio[29595]: info: Loading plugin "network"... May 12 21:02:31 volumio volumio[29595]: info: Refreshing Cached IP Addresses May 12 21:02:31 volumio sudo[29624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 21:02:31 volumio sudo[29624]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:31 volumio sudo[29624]: pam_unix(sudo:session): session closed for user root May 12 21:02:31 volumio sudo[29626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 21:02:31 volumio sudo[29626]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:31 volumio sudo[29626]: pam_unix(sudo:session): session closed for user root May 12 21:02:31 volumio volumio[29595]: info: Loading plugin "services"... May 12 21:02:31 volumio volumio[29595]: info: Loading plugin "alsa_controller"... May 12 21:02:31 volumio sudo[29639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 12 21:02:31 volumio sudo[29639]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:31 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:31 volumio volumio[29595]: info: Loading plugin "wizard"... May 12 21:02:31 volumio volumio[29595]: info: Loading plugin "networkfs"... May 12 21:02:31 volumio volumio[29595]: info: Cannot mount NAS NASMusic at system boot, trial number 1 ,retrying in 5 seconds May 12 21:02:31 volumio volumio[29595]: info: Cannot mount NAS fater at system boot, trial number 1 ,retrying in 5 seconds May 12 21:02:31 volumio volumio[29595]: info: Starting Udev Watcher for removable devices May 12 21:02:31 volumio volumio[29595]: info: Ignoring mount for partition: boot May 12 21:02:31 volumio volumio[29595]: info: Ignoring mount for partition: volumio May 12 21:02:31 volumio volumio[29595]: info: Ignoring mount for partition: volumio_data May 12 21:02:31 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:31 volumio volumio[29595]: info: Loading plugin "volumio_command_line_client"... May 12 21:02:31 volumio volumio[29595]: info: Loading plugin "upnp"... May 12 21:02:31 volumio volumio[29595]: info: [1747051351447] Starting Upmpd Daemon May 12 21:02:31 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:31 volumio volumio[29595]: info: Loading plugin "my_music"... May 12 21:02:31 volumio volumio[29595]: info: Loading plugin "mpd"... May 12 21:02:31 volumio volumio[29595]: info: Loading plugin "upnp_browser"... May 12 21:02:32 volumio volumio[29595]: info: Loading plugin "alarm-clock"... May 12 21:02:32 volumio sudo[29639]: pam_unix(sudo:session): session closed for user root May 12 21:02:32 volumio volumio[29595]: info: Loading plugin "airplay_emulation"... May 12 21:02:32 volumio volumio[29595]: info: Starting Shairport Sync May 12 21:02:32 volumio volumio[29595]: info: Loading plugin "last_100"... May 12 21:02:32 volumio volumio[29595]: info: Loading plugin "webradio"... May 12 21:02:32 volumio volumio-remote-updater[601]: [2025-05-12 21:02:32] [connect] Successful connection May 12 21:02:32 volumio volumio[29595]: info: Loading plugin "i2s_dacs"... May 12 21:02:32 volumio volumio[29595]: info: I2S DAC not set, start Auto-detection May 12 21:02:32 volumio volumio[29595]: info: Loading plugin "volumiodiscovery"... May 12 21:02:32 volumio volumio[29595]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:02:32 volumio volumio[29595]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:32 volumio volumio[29595]: *** WARNING *** For more information see May 12 21:02:32 volumio node[29595]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:02:32 volumio volumio[29595]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:02:32 volumio volumio[29595]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:32 volumio volumio[29595]: *** WARNING *** For more information see May 12 21:02:32 volumio node[29595]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:32 volumio node[29595]: *** WARNING *** For more information see May 12 21:02:32 volumio node[29595]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:02:32 volumio node[29595]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:32 volumio node[29595]: *** WARNING *** For more information see May 12 21:02:32 volumio volumio[29595]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 21:02:32 volumio volumio[29595]: info: Discovery: Started advertising with name: Volumio May 12 21:02:32 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:32 volumio volumio[29595]: info: Loading plugin "youtube2"... May 12 21:02:35 volumio volumio[29595]: info: Loading plugin "outputs"... May 12 21:02:35 volumio volumio[29595]: info: Loading plugin "albumart"... May 12 21:02:35 volumio volumio[29595]: info: Plugin example_plugin is not enabled May 12 21:02:35 volumio volumio[29595]: info: Loading plugin "inputs"... May 12 21:02:35 volumio volumio[29595]: info: Loading plugin "updater_comm"... May 12 21:02:35 volumio volumio[29595]: info: Plugin mpdemulation is not enabled May 12 21:02:35 volumio volumio[29595]: info: Loading plugin "rest_api"... May 12 21:02:35 volumio volumio[29595]: info: Loading plugin "websocket"... May 12 21:02:35 volumio volumio[29595]: info: Starting Socket.io Server version 2.3.0 May 12 21:02:35 volumio volumio[29595]: info: Loading plugin "fusiondsp"... May 12 21:02:36 volumio volumio[29595]: Forking 3 albumart workers May 12 21:02:36 volumio volumio[29595]: info: Applying required configuration parameters for plugin fusiondsp May 12 21:02:36 volumio volumio[29595]: info: Loading i18n strings for locale ja May 12 21:02:36 volumio volumio[29595]: Updating browse sources language May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::initPlayerControls May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:36 volumio volumio[29595]: Express server listening on port 3000 May 12 21:02:36 volumio volumio[29595]: [Metrics] WebUI: 7s 934.96ms May 12 21:02:36 volumio volumio[29595]: info: CoreStateMachine::resetVolumioState May 12 21:02:36 volumio volumio[29595]: info: CoreStateMachine::getcurrentVolume May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:02:36 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:36 volumio volumio[29595]: info: Cannot mount NAS NASMusic at system boot, trial number 2 ,retrying in 5 seconds May 12 21:02:36 volumio volumio[29595]: info: Cannot mount NAS fater at system boot, trial number 2 ,retrying in 5 seconds May 12 21:02:36 volumio volumio[29595]: info: Volumio Network Manager: Network status updated: 2 May 12 21:02:36 volumio volumio[29595]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 12 21:02:36 volumio volumio[29595]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 12 21:02:36 volumio volumio-remote-updater[601]: [2025-05-12 21:02:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747051352 101 May 12 21:02:36 volumio volumio[29595]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 12 21:02:37 volumio volumio[29595]: info: Reloading queue from file May 12 21:02:37 volumio volumio[29595]: info: CoreStateMachine::setRepeat null single undefined May 12 21:02:37 volumio volumio[29595]: info: CoreStateMachine::pushState May 12 21:02:37 volumio volumio[29595]: info: CorePlayQueue::getTrack 0 May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::volumioPushState May 12 21:02:37 volumio volumio[29595]: info: CoreStateMachine::setRandom null May 12 21:02:37 volumio volumio[29595]: info: CoreStateMachine::pushState May 12 21:02:37 volumio volumio[29595]: info: CorePlayQueue::getTrack 0 May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::volumioPushState May 12 21:02:37 volumio volumio[29595]: info: Setting Device type: Raspberry PI May 12 21:02:37 volumio volumio[29595]: info: VolumeController:: Volume=44 Mute =false May 12 21:02:37 volumio volumio[29595]: info: CoreStateMachine::pushState May 12 21:02:37 volumio volumio[29595]: info: CorePlayQueue::getTrack 0 May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::volumioPushState May 12 21:02:37 volumio volumio[29595]: info: CoreStateMachine::updateTrackBlock May 12 21:02:37 volumio volumio[29595]: info: CorePlayQueue::getTrackBlock May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:02:37 volumio volumio[29595]: info: Completed loading Core Plugins May 12 21:02:37 volumio volumio[29595]: info: Preparing to generate the ALSA configuration file May 12 21:02:37 volumio volumio[29595]: Starting albumart workers May 12 21:02:37 volumio volumio[29595]: Starting albumart workers May 12 21:02:37 volumio volumio[29595]: Starting albumart workers May 12 21:02:37 volumio volumio[29595]: info: Discovery: adding 4eac4e30-3d36-47e9-8023-9350033c0a5c May 12 21:02:37 volumio volumio[29595]: info: Discovery: Found device Volumio May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::volumioGetState May 12 21:02:37 volumio volumio[29595]: info: CorePlayQueue::getTrack 0 May 12 21:02:37 volumio volumio[29595]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:02:37 volumio volumio[29595]: info: Reading ALSA contributions from plugins. May 12 21:02:37 volumio volumio[29595]: info: Asound.conf file unchanged, so no further update is needed May 12 21:02:37 volumio volumio[29595]: info: Output device has changed, restarting MPD May 12 21:02:37 volumio volumio[29595]: info: Output device has changed, restarting Shairport Sync May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:37 volumio sudo[29695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:37 volumio sudo[29695]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:37 volumio sudo[29695]: pam_unix(sudo:session): session closed for user root May 12 21:02:37 volumio sudo[29696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:37 volumio sudo[29696]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:37 volumio systemd[1]: Stopping Music Player Daemon... May 12 21:02:37 volumio volumio[29595]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:37 volumio volumio[29595]: info: ___________ START PLUGINS ___________ May 12 21:02:37 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:37 volumio volumio[29595]: info: ControllerMpd::onStart: Initializing MPD May 12 21:02:37 volumio volumio[29595]: info: Creating MPD Configuration file May 12 21:02:37 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:37 volumio sudo[29704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:37 volumio sudo[29704]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:37 volumio volumio[29595]: info: [1747051357630] CoreMusicLibrary::Adding element DLNAサーバー May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:37 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:37 volumio sudo[29704]: pam_unix(sudo:session): session closed for user root May 12 21:02:37 volumio sudo[29706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:37 volumio sudo[29706]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:37 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 12 21:02:37 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:37 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:37 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:37 volumio volumio[29595]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:37 volumio volumio[29595]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:37 volumio volumio[29595]: info: [1747051357880] CoreMusicLibrary::Adding element Last_100 May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:37 volumio volumio[29595]: info: [1747051357895] CoreMusicLibrary::Adding element Webradio May 12 21:02:37 volumio sudo[29710]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:02:37 volumio sudo[29710]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:37 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:37 volumio sudo[29710]: pam_unix(sudo:session): session closed for user root May 12 21:02:37 volumio volumio[29595]: info: Initializing BBC Radios May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:38 volumio volumio[29595]: info: [1747051358172] CoreMusicLibrary::Adding element YouTube2 May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:38 volumio volumio[29595]: Cannot find translation for source YouTube2 May 12 21:02:38 volumio volumio[29595]: info: Loading i18n strings for locale ja May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: FusionDsp - mixtype--------------------- Hardware May 12 21:02:38 volumio volumio[29595]: info: Volumio Calling Home May 12 21:02:38 volumio volumio[29595]: info: Preparing to generate the ALSA configuration file May 12 21:02:38 volumio volumio[29595]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:02:38 volumio volumio[29595]: info: Reading ALSA contributions from plugins. May 12 21:02:38 volumio volumio[29595]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 12 21:02:38 volumio volumio[29595]: info: MPD Permissions set May 12 21:02:38 volumio volumio[29595]: info: MPD Permissions set May 12 21:02:38 volumio volumio[29595]: info: VolumeController:: Volume=44 Mute =false May 12 21:02:38 volumio volumio[29595]: info: CoreStateMachine::pushState May 12 21:02:38 volumio volumio[29595]: info: CorePlayQueue::getTrack 0 May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::volumioPushState May 12 21:02:38 volumio volumio[29595]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:38 volumio volumio[29595]: info: Starting Shairport Sync May 12 21:02:38 volumio volumio[29595]: info: Starting Shairport Sync May 12 21:02:38 volumio sudo[29765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:38 volumio volumio[29595]: info: Starting Shairport Sync May 12 21:02:38 volumio sudo[29765]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:38 volumio sudo[29767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:38 volumio sudo[29767]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:02:38 volumio sudo[29770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:38 volumio sudo[29770]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:38 volumio volumio[29595]: info: CoreCommandRouter::volumioGetState May 12 21:02:38 volumio volumio[29595]: info: CorePlayQueue::getTrack 0 May 12 21:02:38 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:02:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:02:38 volumio volumio[29595]: info: Volumio called home May 12 21:02:38 volumio volumio[29595]: info: Asound.conf file unchanged, so no further update is needed May 12 21:02:38 volumio volumio[29595]: info: Output device has changed, restarting MPD May 12 21:02:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:02:39 volumio sudo[29770]: pam_unix(sudo:session): session closed for user root May 12 21:02:39 volumio sudo[29765]: pam_unix(sudo:session): session closed for user root May 12 21:02:39 volumio sudo[29767]: pam_unix(sudo:session): session closed for user root May 12 21:02:39 volumio volumio[29595]: info: Output device has changed, restarting Shairport Sync May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:39 volumio sudo[29775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:39 volumio sudo[29775]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:39 volumio sudo[29777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:39 volumio sudo[29777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:39 volumio sudo[29775]: pam_unix(sudo:session): session closed for user root May 12 21:02:39 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 21:02:39 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:39 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:39 volumio volumio[29595]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:39 volumio volumio[29595]: info: MPD Permissions set May 12 21:02:39 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:39 volumio volumio[29595]: info: Shairport-Sync Started May 12 21:02:39 volumio volumio[29595]: Error adding Membership: Error: addMembership EINVAL May 12 21:02:39 volumio volumio[29595]: info: Shairport-Sync Started May 12 21:02:39 volumio volumio[29595]: info: Shairport-Sync Started May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:39 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:39 volumio volumio[29595]: info: Starting Shairport Sync May 12 21:02:39 volumio sudo[29791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:39 volumio sudo[29791]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:02:39 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:02:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:02:39 volumio sudo[29785]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:02:39 volumio sudo[29785]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:39 volumio sudo[29785]: pam_unix(sudo:session): session closed for user root May 12 21:02:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:02:39 volumio sudo[29791]: pam_unix(sudo:session): session closed for user root May 12 21:02:39 volumio volumio[29595]: info: Shairport-Sync Started May 12 21:02:40 volumio volumio[29595]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 21:02:40 volumio volumio[29595]: 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 May 12 21:02:40 volumio volumio[29595]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 21:02:40 volumio volumio[29595]: info: camilladsp service started and running in background, instance 1 May 12 21:02:40 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:40 volumio volumio[29595]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 21:02:40 volumio volumio[29595]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 12 21:02:40 volumio volumio[29595]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 21:02:40 volumio volumio[29595]: info: FusionDsp loaded May 12 21:02:40 volumio volumio[29595]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 21:02:40 volumio sudo[29809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 12 21:02:40 volumio sudo[29809]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:40 volumio volumio[29595]: info: FusionDsp - Reporting Fusion DSP Enabled May 12 21:02:40 volumio volumio[29595]: info: Adding Signal Path Element [object Object] May 12 21:02:40 volumio volumio[29595]: info: Adding fusiondspeq DSP Signal Path Element May 12 21:02:40 volumio volumio[29595]: info: FusionDsp - ---- installed callbackRead May 12 21:02:40 volumio volumio[29595]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:02:40 volumio sudo[29809]: pam_unix(sudo:session): session closed for user root May 12 21:02:40 volumio volumio[29595]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 12 21:02:40 volumio volumio[29595]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 12 21:02:40 volumio volumio[29595]: at onErrorNT (internal/child_process.js:465:16) May 12 21:02:40 volumio volumio[29595]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 12 21:02:40 volumio volumio[29595]: errno: -2, May 12 21:02:40 volumio volumio[29595]: code: 'ENOENT', May 12 21:02:40 volumio volumio[29595]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 12 21:02:40 volumio volumio[29595]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 12 21:02:40 volumio volumio[29595]: spawnargs: [ May 12 21:02:40 volumio volumio[29595]: '-p', May 12 21:02:40 volumio volumio[29595]: 9876, May 12 21:02:40 volumio volumio[29595]: '-o', May 12 21:02:40 volumio volumio[29595]: '/tmp/camilladsp.log', May 12 21:02:40 volumio volumio[29595]: '-l', May 12 21:02:40 volumio volumio[29595]: 'warn', May 12 21:02:40 volumio volumio[29595]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 12 21:02:40 volumio volumio[29595]: ] May 12 21:02:40 volumio volumio[29595]: } May 12 21:02:40 volumio volumio[29595]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:02:40 volumio mpd[29796]: May 12 21:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 21:02:41 volumio sudo[29820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 21:01 May 12 21:02:41 volumio sudo[29820]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:41 volumio sudo[29820]: pam_unix(sudo:session): session closed for user root May 12 21:02:41 volumio volumio-remote-updater[601]: [2025-05-12 21:02:41] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 12 21:02:41 volumio volumio-remote-updater[601]: [2025-05-12 21:02:41] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 12 21:02:41 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 12 21:02:41 volumio sudo[29696]: pam_unix(sudo:session): session closed for user root May 12 21:02:41 volumio sudo[29706]: pam_unix(sudo:session): session closed for user root May 12 21:02:41 volumio sudo[29777]: pam_unix(sudo:session): session closed for user root May 12 21:02:41 volumio systemd[1]: Started Music Player Daemon. May 12 21:02:41 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 12 21:02:41 volumio systemd[1]: Started dynamicswap service. May 12 21:02:41 volumio systemd[1]: dynamicswap.service: Succeeded. May 12 21:02:41 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 12 21:02:41 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 110. May 12 21:02:41 volumio systemd[1]: Started dynamicswap service. May 12 21:02:41 volumio systemd[1]: Stopped Volumio Backend Module. May 12 21:02:41 volumio systemd[1]: Started Volumio Backend Module. May 12 21:02:41 volumio systemd[1]: dynamicswap.service: Succeeded. May 12 21:02:43 volumio volumio[29847]: info: ------------------------------------------- May 12 21:02:43 volumio volumio[29847]: info: ----- Volumio3 ---- May 12 21:02:43 volumio volumio[29847]: info: ------------------------------------------- May 12 21:02:43 volumio volumio[29847]: info: ----- System startup ---- May 12 21:02:43 volumio volumio[29847]: info: ------------------------------------------- May 12 21:02:44 volumio volumio[29847]: info: MYVOLUMIO Environment detected May 12 21:02:44 volumio volumio[29847]: info: Plugin folders cleanup May 12 21:02:44 volumio volumio[29847]: info: Scanning into folder /volumio/app/plugins/ May 12 21:02:44 volumio volumio[29847]: info: Scanning category audio_interface May 12 21:02:44 volumio volumio[29847]: info: Scanning category miscellanea May 12 21:02:44 volumio volumio[29847]: info: Scanning category music_service May 12 21:02:44 volumio volumio[29847]: info: Scanning category plugins.json May 12 21:02:44 volumio volumio[29847]: info: Scanning category system_controller May 12 21:02:44 volumio volumio[29847]: info: Scanning category user_interface May 12 21:02:44 volumio volumio[29847]: info: Scanning into folder /data/plugins/ May 12 21:02:44 volumio volumio[29847]: info: Scanning category audio_interface May 12 21:02:44 volumio volumio[29847]: info: Scanning category music_service May 12 21:02:44 volumio volumio[29847]: info: Plugin folders cleanup completed May 12 21:02:44 volumio volumio[29847]: info: ------------------------------------------- May 12 21:02:44 volumio volumio[29847]: info: ----- Core plugins startup ---- May 12 21:02:44 volumio volumio[29847]: info: ------------------------------------------- May 12 21:02:44 volumio volumio[29847]: info: Loading plugins from folder /volumio/app/plugins/ May 12 21:02:44 volumio volumio[29847]: info: Adding plugin upnp to MyMusic Plugins May 12 21:02:44 volumio volumio[29847]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 21:02:44 volumio volumio[29847]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 21:02:44 volumio volumio[29847]: info: Loading plugins from folder /data/plugins/ May 12 21:02:44 volumio volumio[29847]: info: Loading plugin "system"... May 12 21:02:44 volumio volumio[29847]: info: Loading plugin "appearance"... May 12 21:02:45 volumio volumio[29847]: info: Loading plugin "network"... May 12 21:02:45 volumio volumio[29847]: info: Refreshing Cached IP Addresses May 12 21:02:45 volumio sudo[29873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 21:02:45 volumio sudo[29873]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:45 volumio sudo[29873]: pam_unix(sudo:session): session closed for user root May 12 21:02:45 volumio sudo[29875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 21:02:45 volumio sudo[29875]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:45 volumio volumio[29847]: info: Loading plugin "services"... May 12 21:02:45 volumio volumio[29847]: info: Loading plugin "alsa_controller"... May 12 21:02:45 volumio sudo[29875]: pam_unix(sudo:session): session closed for user root May 12 21:02:45 volumio sudo[29886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 12 21:02:45 volumio sudo[29886]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:45 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:45 volumio volumio[29847]: info: Loading plugin "wizard"... May 12 21:02:45 volumio volumio[29847]: info: Loading plugin "networkfs"... May 12 21:02:45 volumio volumio[29847]: info: Cannot mount NAS NASMusic at system boot, trial number 1 ,retrying in 5 seconds May 12 21:02:45 volumio volumio[29847]: info: Cannot mount NAS fater at system boot, trial number 1 ,retrying in 5 seconds May 12 21:02:45 volumio volumio[29847]: info: Starting Udev Watcher for removable devices May 12 21:02:45 volumio volumio[29847]: info: Ignoring mount for partition: boot May 12 21:02:45 volumio volumio[29847]: info: Ignoring mount for partition: volumio May 12 21:02:45 volumio volumio[29847]: info: Ignoring mount for partition: volumio_data May 12 21:02:45 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:45 volumio volumio[29847]: info: Loading plugin "volumio_command_line_client"... May 12 21:02:45 volumio volumio[29847]: info: Loading plugin "upnp"... May 12 21:02:45 volumio volumio[29847]: info: [1747051365401] Starting Upmpd Daemon May 12 21:02:45 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:45 volumio volumio[29847]: info: Loading plugin "my_music"... May 12 21:02:45 volumio volumio[29847]: info: Loading plugin "mpd"... May 12 21:02:45 volumio volumio[29847]: info: Loading plugin "upnp_browser"... May 12 21:02:46 volumio volumio[29847]: info: Loading plugin "alarm-clock"... May 12 21:02:46 volumio sudo[29886]: pam_unix(sudo:session): session closed for user root May 12 21:02:46 volumio volumio[29847]: info: Loading plugin "airplay_emulation"... May 12 21:02:46 volumio volumio[29847]: info: Starting Shairport Sync May 12 21:02:46 volumio volumio[29847]: info: Loading plugin "last_100"... May 12 21:02:46 volumio volumio[29847]: info: Loading plugin "webradio"... May 12 21:02:46 volumio volumio-remote-updater[601]: [2025-05-12 21:02:46] [connect] Successful connection May 12 21:02:46 volumio volumio[29847]: info: Loading plugin "i2s_dacs"... May 12 21:02:46 volumio volumio[29847]: info: I2S DAC not set, start Auto-detection May 12 21:02:46 volumio volumio[29847]: info: Loading plugin "volumiodiscovery"... May 12 21:02:46 volumio volumio[29847]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:02:46 volumio volumio[29847]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:46 volumio volumio[29847]: *** WARNING *** For more information see May 12 21:02:46 volumio node[29847]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:02:46 volumio volumio[29847]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:02:46 volumio volumio[29847]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:46 volumio volumio[29847]: *** WARNING *** For more information see May 12 21:02:46 volumio node[29847]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:46 volumio node[29847]: *** WARNING *** For more information see May 12 21:02:46 volumio node[29847]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:02:46 volumio node[29847]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:02:46 volumio node[29847]: *** WARNING *** For more information see May 12 21:02:46 volumio volumio[29847]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 21:02:46 volumio volumio[29847]: info: Discovery: Started advertising with name: Volumio May 12 21:02:46 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:02:46 volumio volumio[29847]: info: Loading plugin "youtube2"... May 12 21:02:49 volumio volumio[29847]: info: Loading plugin "outputs"... May 12 21:02:49 volumio volumio[29847]: info: Loading plugin "albumart"... May 12 21:02:49 volumio volumio[29847]: info: Plugin example_plugin is not enabled May 12 21:02:49 volumio volumio[29847]: info: Loading plugin "inputs"... May 12 21:02:49 volumio volumio[29847]: info: Loading plugin "updater_comm"... May 12 21:02:49 volumio volumio[29847]: info: Plugin mpdemulation is not enabled May 12 21:02:49 volumio volumio[29847]: info: Loading plugin "rest_api"... May 12 21:02:49 volumio volumio[29847]: info: Loading plugin "websocket"... May 12 21:02:49 volumio volumio[29847]: info: Starting Socket.io Server version 2.3.0 May 12 21:02:49 volumio volumio[29847]: info: Loading plugin "fusiondsp"... May 12 21:02:50 volumio volumio[29847]: Forking 3 albumart workers May 12 21:02:50 volumio volumio[29847]: info: Applying required configuration parameters for plugin fusiondsp May 12 21:02:50 volumio volumio[29847]: info: Loading i18n strings for locale ja May 12 21:02:50 volumio volumio[29847]: Updating browse sources language May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::initPlayerControls May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:50 volumio volumio[29847]: Express server listening on port 3000 May 12 21:02:50 volumio volumio[29847]: [Metrics] WebUI: 7s 851.86ms May 12 21:02:50 volumio volumio[29847]: info: CoreStateMachine::resetVolumioState May 12 21:02:50 volumio volumio[29847]: info: CoreStateMachine::getcurrentVolume May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:02:50 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:50 volumio volumio[29847]: info: Cannot mount NAS NASMusic at system boot, trial number 2 ,retrying in 5 seconds May 12 21:02:50 volumio volumio[29847]: info: Cannot mount NAS fater at system boot, trial number 2 ,retrying in 5 seconds May 12 21:02:50 volumio volumio[29847]: info: Volumio Network Manager: Network status updated: 2 May 12 21:02:51 volumio volumio[29847]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 12 21:02:51 volumio volumio-remote-updater[601]: [2025-05-12 21:02:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747051366 101 May 12 21:02:51 volumio volumio[29847]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 May 12 21:02:51 volumio volumio[29847]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 12 21:02:51 volumio volumio[29847]: info: VolumeController:: Volume=44 Mute =false May 12 21:02:51 volumio volumio[29847]: info: CoreStateMachine::pushState May 12 21:02:51 volumio volumio[29847]: info: CorePlayQueue::getTrack 0 May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::volumioPushState May 12 21:02:51 volumio volumio[29847]: info: CoreStateMachine::updateTrackBlock May 12 21:02:51 volumio volumio[29847]: info: CorePlayQueue::getTrackBlock May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:02:51 volumio volumio[29847]: Starting albumart workers May 12 21:02:51 volumio volumio[29847]: Starting albumart workers May 12 21:02:51 volumio volumio[29847]: Starting albumart workers May 12 21:02:51 volumio volumio[29847]: info: Reloading queue from file May 12 21:02:51 volumio volumio[29847]: info: CoreStateMachine::setRepeat null single undefined May 12 21:02:51 volumio volumio[29847]: info: CoreStateMachine::pushState May 12 21:02:51 volumio volumio[29847]: info: CorePlayQueue::getTrack 0 May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::volumioPushState May 12 21:02:51 volumio volumio[29847]: info: CoreStateMachine::setRandom null May 12 21:02:51 volumio volumio[29847]: info: CoreStateMachine::pushState May 12 21:02:51 volumio volumio[29847]: info: CorePlayQueue::getTrack 0 May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::volumioPushState May 12 21:02:51 volumio volumio[29847]: info: Setting Device type: Raspberry PI May 12 21:02:51 volumio volumio[29847]: info: VolumeController:: Volume=44 Mute =false May 12 21:02:51 volumio volumio[29847]: info: CoreStateMachine::pushState May 12 21:02:51 volumio volumio[29847]: info: CorePlayQueue::getTrack 0 May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::volumioPushState May 12 21:02:51 volumio volumio[29847]: info: Completed loading Core Plugins May 12 21:02:51 volumio volumio[29847]: info: Preparing to generate the ALSA configuration file May 12 21:02:51 volumio volumio[29847]: info: Discovery: adding 4eac4e30-3d36-47e9-8023-9350033c0a5c May 12 21:02:51 volumio volumio[29847]: info: Discovery: Found device Volumio May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::volumioGetState May 12 21:02:51 volumio volumio[29847]: info: CorePlayQueue::getTrack 0 May 12 21:02:51 volumio volumio[29847]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:02:51 volumio volumio[29847]: info: Reading ALSA contributions from plugins. May 12 21:02:51 volumio volumio[29847]: info: Asound.conf file unchanged, so no further update is needed May 12 21:02:51 volumio volumio[29847]: info: Output device has changed, restarting MPD May 12 21:02:51 volumio volumio[29847]: info: Output device has changed, restarting Shairport Sync May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:51 volumio sudo[29946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:51 volumio sudo[29946]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:51 volumio sudo[29946]: pam_unix(sudo:session): session closed for user root May 12 21:02:51 volumio sudo[29948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:51 volumio sudo[29948]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:51 volumio systemd[1]: Stopping Music Player Daemon... May 12 21:02:51 volumio volumio[29847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:51 volumio volumio[29847]: info: ___________ START PLUGINS ___________ May 12 21:02:51 volumio volumio[29847]: info: ControllerMpd::onStart: Initializing MPD May 12 21:02:51 volumio volumio[29847]: info: Creating MPD Configuration file May 12 21:02:51 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:51 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:51 volumio sudo[29955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:51 volumio sudo[29955]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:51 volumio volumio[29847]: info: [1747051371884] CoreMusicLibrary::Adding element DLNAサーバー May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:51 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:51 volumio sudo[29955]: pam_unix(sudo:session): session closed for user root May 12 21:02:51 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:51 volumio sudo[29958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:51 volumio sudo[29958]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:51 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 12 21:02:51 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:51 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:51 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:52 volumio volumio[29847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:52 volumio volumio[29847]: info: [1747051372104] CoreMusicLibrary::Adding element Last_100 May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:52 volumio volumio[29847]: info: [1747051372115] CoreMusicLibrary::Adding element Webradio May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:52 volumio volumio[29847]: info: Initializing BBC Radios May 12 21:02:52 volumio sudo[29964]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:02:52 volumio sudo[29964]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:52 volumio sudo[29964]: pam_unix(sudo:session): session closed for user root May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:02:52 volumio volumio[29847]: info: [1747051372289] CoreMusicLibrary::Adding element YouTube2 May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:52 volumio volumio[29847]: Cannot find translation for source YouTube2 May 12 21:02:52 volumio volumio[29847]: info: Loading i18n strings for locale ja May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: FusionDsp - mixtype--------------------- Hardware May 12 21:02:52 volumio volumio[29847]: info: Volumio Calling Home May 12 21:02:52 volumio volumio[29847]: info: Preparing to generate the ALSA configuration file May 12 21:02:52 volumio volumio[29847]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:02:52 volumio volumio[29847]: info: Reading ALSA contributions from plugins. May 12 21:02:52 volumio volumio[29847]: info: MPD Permissions set May 12 21:02:52 volumio volumio[29847]: info: MPD Permissions set May 12 21:02:52 volumio volumio[29847]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:52 volumio volumio[29847]: info: Starting Shairport Sync May 12 21:02:52 volumio volumio[29847]: info: Starting Shairport Sync May 12 21:02:52 volumio sudo[30016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:52 volumio volumio[29847]: info: Starting Shairport Sync May 12 21:02:52 volumio sudo[30016]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:52 volumio sudo[30018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:52 volumio sudo[30018]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:52 volumio volumio[29847]: info: CoreCommandRouter::volumioGetState May 12 21:02:52 volumio volumio[29847]: info: CorePlayQueue::getTrack 0 May 12 21:02:52 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:02:52 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:02:52 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:02:53 volumio sudo[30021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:53 volumio sudo[30021]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:02:53 volumio volumio[29847]: info: Volumio called home May 12 21:02:53 volumio volumio[29847]: info: Asound.conf file unchanged, so no further update is needed May 12 21:02:53 volumio volumio[29847]: info: Output device has changed, restarting MPD May 12 21:02:53 volumio volumio[29847]: info: Output device has changed, restarting Shairport Sync May 12 21:02:53 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:53 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:53 volumio sudo[30026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:02:53 volumio sudo[30026]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:53 volumio sudo[30026]: pam_unix(sudo:session): session closed for user root May 12 21:02:53 volumio sudo[30028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:02:53 volumio sudo[30028]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:53 volumio sudo[30018]: pam_unix(sudo:session): session closed for user root May 12 21:02:53 volumio sudo[30016]: pam_unix(sudo:session): session closed for user root May 12 21:02:53 volumio volumio[29847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:02:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:02:53 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 21:02:53 volumio systemd[1]: mpd.service: Succeeded. May 12 21:02:53 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:02:53 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:02:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:02:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:02:53 volumio sudo[30021]: pam_unix(sudo:session): session closed for user root May 12 21:02:53 volumio volumio[29847]: info: MPD Permissions set May 12 21:02:53 volumio volumio[29847]: info: Shairport-Sync Started May 12 21:02:53 volumio systemd[1]: Starting Music Player Daemon... May 12 21:02:53 volumio volumio[29847]: Error adding Membership: Error: addMembership EINVAL May 12 21:02:53 volumio volumio[29847]: info: Shairport-Sync Started May 12 21:02:53 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:53 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:02:53 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:53 volumio volumio[29847]: info: Shairport-Sync Started May 12 21:02:53 volumio volumio[29847]: info: Starting Shairport Sync May 12 21:02:53 volumio sudo[30044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:02:53 volumio sudo[30044]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:02:53 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:02:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:02:53 volumio sudo[30036]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:02:53 volumio sudo[30036]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:53 volumio sudo[30036]: pam_unix(sudo:session): session closed for user root May 12 21:02:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:02:53 volumio sudo[30044]: pam_unix(sudo:session): session closed for user root May 12 21:02:53 volumio volumio[29847]: info: Shairport-Sync Started May 12 21:02:53 volumio volumio[29847]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 12 21:02:53 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::volumioGetVisibleSources May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::volumioGetState May 12 21:02:54 volumio volumio[29847]: info: CorePlayQueue::getTrack 0 May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::volumioGetState May 12 21:02:54 volumio volumio[29847]: info: CorePlayQueue::getTrack 0 May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 12 21:02:54 volumio volumio[29847]: info: Received Get System Info May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 21:02:54 volumio volumio[29847]: info: Discovery: Getting this device information May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::volumioGetState May 12 21:02:54 volumio volumio[29847]: info: CorePlayQueue::getTrack 0 May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::volumioGetState May 12 21:02:54 volumio volumio[29847]: info: CorePlayQueue::getTrack 0 May 12 21:02:54 volumio volumio[29847]: info: Listing playlists May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:02:54 volumio volumio[29847]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:02:54 volumio volumio[29847]: info: FusionDsp - no preset used May 12 21:02:54 volumio volumio[29847]: info: FusionDsp - no preset used May 12 21:02:54 volumio volumio[29847]: info: FusionDsp - no preset used May 12 21:02:54 volumio mpd[30049]: May 12 21:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 21:02:54 volumio volumio[29847]: info: FusionDsp - no preset used May 12 21:02:55 volumio volumio[29847]: info: FusionDsp - no preset used May 12 21:02:55 volumio volumio[29847]: info: FusionDsp - no preset used May 12 21:02:55 volumio systemd[1]: Started Music Player Daemon. May 12 21:02:55 volumio sudo[29948]: pam_unix(sudo:session): session closed for user root May 12 21:02:55 volumio sudo[29958]: pam_unix(sudo:session): session closed for user root May 12 21:02:55 volumio sudo[30028]: pam_unix(sudo:session): session closed for user root May 12 21:02:55 volumio volumio[29847]: info: FusionDsp - no preset used May 12 21:02:55 volumio volumio[29847]: info: FusionDsp - no preset used May 12 21:02:56 volumio volumio[29847]: info: FusionDsp - no preset used May 12 21:02:56 volumio volumio[29847]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 21:02:56 volumio volumio[29847]: 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 May 12 21:02:56 volumio volumio[29847]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 21:02:56 volumio volumio[29847]: info: camilladsp service started and running in background, instance 1 May 12 21:02:56 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:02:56 volumio volumio[29847]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 21:02:56 volumio volumio[29847]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 12 21:02:56 volumio volumio[29847]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 21:02:56 volumio volumio[29847]: info: FusionDsp loaded May 12 21:02:56 volumio volumio[29847]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 21:02:56 volumio sudo[30078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 12 21:02:56 volumio sudo[30078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:56 volumio volumio[29847]: info: FusionDsp - Reporting Fusion DSP Enabled May 12 21:02:56 volumio volumio[29847]: info: Adding Signal Path Element [object Object] May 12 21:02:56 volumio volumio[29847]: info: Adding fusiondspeq DSP Signal Path Element May 12 21:02:56 volumio volumio[29847]: info: FusionDsp - ---- installed callbackRead May 12 21:02:56 volumio volumio[29847]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:02:56 volumio sudo[30078]: pam_unix(sudo:session): session closed for user root May 12 21:02:56 volumio volumio[29847]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 12 21:02:56 volumio volumio[29847]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 12 21:02:56 volumio volumio[29847]: at onErrorNT (internal/child_process.js:465:16) May 12 21:02:56 volumio volumio[29847]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 12 21:02:56 volumio volumio[29847]: at runNextTicks (internal/process/task_queues.js:62:3) May 12 21:02:56 volumio volumio[29847]: at listOnTimeout (internal/timers.js:523:9) May 12 21:02:56 volumio volumio[29847]: at processTimers (internal/timers.js:497:7) { May 12 21:02:56 volumio volumio[29847]: errno: -2, May 12 21:02:56 volumio volumio[29847]: code: 'ENOENT', May 12 21:02:56 volumio volumio[29847]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 12 21:02:56 volumio volumio[29847]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 12 21:02:56 volumio volumio[29847]: spawnargs: [ May 12 21:02:56 volumio volumio[29847]: '-p', May 12 21:02:56 volumio volumio[29847]: 9876, May 12 21:02:56 volumio volumio[29847]: '-o', May 12 21:02:56 volumio volumio[29847]: '/tmp/camilladsp.log', May 12 21:02:56 volumio volumio[29847]: '-l', May 12 21:02:56 volumio volumio[29847]: 'warn', May 12 21:02:56 volumio volumio[29847]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 12 21:02:56 volumio volumio[29847]: ] May 12 21:02:56 volumio volumio[29847]: } May 12 21:02:56 volumio volumio[29847]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:02:56 volumio sudo[30089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 21:01 May 12 21:02:56 volumio sudo[30089]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:02:57 volumio sudo[30089]: pam_unix(sudo:session): session closed for user root May 12 21:02:57 volumio volumio-remote-updater[601]: [2025-05-12 21:02:57] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 12 21:02:57 volumio volumio-remote-updater[601]: [2025-05-12 21:02:57] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 12 21:02:57 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 12 21:02:57 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 12 21:02:57 volumio systemd[1]: Started dynamicswap service. May 12 21:02:57 volumio systemd[1]: dynamicswap.service: Succeeded. May 12 21:02:57 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 12 21:02:57 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 111. May 12 21:02:57 volumio systemd[1]: Started dynamicswap service. May 12 21:02:57 volumio systemd[1]: Stopped Volumio Backend Module. May 12 21:02:57 volumio systemd[1]: Started Volumio Backend Module. May 12 21:02:57 volumio systemd[1]: dynamicswap.service: Succeeded. May 12 21:02:59 volumio volumio[30102]: info: ------------------------------------------- May 12 21:02:59 volumio volumio[30102]: info: ----- Volumio3 ---- May 12 21:02:59 volumio volumio[30102]: info: ------------------------------------------- May 12 21:02:59 volumio volumio[30102]: info: ----- System startup ---- May 12 21:02:59 volumio volumio[30102]: info: ------------------------------------------- May 12 21:03:00 volumio volumio[30102]: info: MYVOLUMIO Environment detected May 12 21:03:00 volumio volumio[30102]: info: Plugin folders cleanup May 12 21:03:00 volumio volumio[30102]: info: Scanning into folder /volumio/app/plugins/ May 12 21:03:00 volumio volumio[30102]: info: Scanning category audio_interface May 12 21:03:00 volumio volumio[30102]: info: Scanning category miscellanea May 12 21:03:00 volumio volumio[30102]: info: Scanning category music_service May 12 21:03:00 volumio volumio[30102]: info: Scanning category plugins.json May 12 21:03:00 volumio volumio[30102]: info: Scanning category system_controller May 12 21:03:00 volumio volumio[30102]: info: Scanning category user_interface May 12 21:03:00 volumio volumio[30102]: info: Scanning into folder /data/plugins/ May 12 21:03:00 volumio volumio[30102]: info: Scanning category audio_interface May 12 21:03:00 volumio volumio[30102]: info: Scanning category music_service May 12 21:03:00 volumio volumio[30102]: info: Plugin folders cleanup completed May 12 21:03:00 volumio volumio[30102]: info: ------------------------------------------- May 12 21:03:00 volumio volumio[30102]: info: ----- Core plugins startup ---- May 12 21:03:00 volumio volumio[30102]: info: ------------------------------------------- May 12 21:03:00 volumio volumio[30102]: info: Loading plugins from folder /volumio/app/plugins/ May 12 21:03:00 volumio volumio[30102]: info: Adding plugin upnp to MyMusic Plugins May 12 21:03:00 volumio volumio[30102]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 21:03:00 volumio volumio[30102]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 21:03:00 volumio volumio[30102]: info: Loading plugins from folder /data/plugins/ May 12 21:03:00 volumio volumio[30102]: info: Loading plugin "system"... May 12 21:03:00 volumio volumio[30102]: info: Loading plugin "appearance"... May 12 21:03:01 volumio volumio[30102]: info: Loading plugin "network"... May 12 21:03:01 volumio volumio[30102]: info: Refreshing Cached IP Addresses May 12 21:03:01 volumio sudo[30128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 21:03:01 volumio sudo[30128]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:01 volumio sudo[30128]: pam_unix(sudo:session): session closed for user root May 12 21:03:01 volumio sudo[30130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 21:03:01 volumio sudo[30130]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:01 volumio volumio[30102]: info: Loading plugin "services"... May 12 21:03:01 volumio sudo[30130]: pam_unix(sudo:session): session closed for user root May 12 21:03:01 volumio volumio[30102]: info: Loading plugin "alsa_controller"... May 12 21:03:01 volumio sudo[30143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 12 21:03:01 volumio sudo[30143]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:01 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:03:01 volumio volumio[30102]: info: Loading plugin "wizard"... May 12 21:03:01 volumio volumio[30102]: info: Loading plugin "networkfs"... May 12 21:03:01 volumio volumio[30102]: info: Cannot mount NAS NASMusic at system boot, trial number 1 ,retrying in 5 seconds May 12 21:03:01 volumio volumio[30102]: info: Cannot mount NAS fater at system boot, trial number 1 ,retrying in 5 seconds May 12 21:03:01 volumio volumio[30102]: info: Starting Udev Watcher for removable devices May 12 21:03:01 volumio volumio[30102]: info: Ignoring mount for partition: boot May 12 21:03:01 volumio volumio[30102]: info: Ignoring mount for partition: volumio May 12 21:03:01 volumio volumio[30102]: info: Ignoring mount for partition: volumio_data May 12 21:03:01 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:03:01 volumio volumio[30102]: info: Loading plugin "volumio_command_line_client"... May 12 21:03:01 volumio volumio[30102]: info: Loading plugin "upnp"... May 12 21:03:01 volumio volumio[30102]: info: [1747051381467] Starting Upmpd Daemon May 12 21:03:01 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:03:01 volumio volumio[30102]: info: Loading plugin "my_music"... May 12 21:03:01 volumio volumio[30102]: info: Loading plugin "mpd"... May 12 21:03:01 volumio volumio[30102]: info: Loading plugin "upnp_browser"... May 12 21:03:02 volumio volumio[30102]: info: Loading plugin "alarm-clock"... May 12 21:03:02 volumio sudo[30143]: pam_unix(sudo:session): session closed for user root May 12 21:03:02 volumio volumio-remote-updater[601]: [2025-05-12 21:03:02] [connect] Successful connection May 12 21:03:02 volumio volumio[30102]: info: Loading plugin "airplay_emulation"... May 12 21:03:02 volumio volumio[30102]: info: Starting Shairport Sync May 12 21:03:02 volumio volumio[30102]: info: Loading plugin "last_100"... May 12 21:03:02 volumio volumio[30102]: info: Loading plugin "webradio"... May 12 21:03:02 volumio volumio[30102]: info: Loading plugin "i2s_dacs"... May 12 21:03:02 volumio volumio[30102]: info: I2S DAC not set, start Auto-detection May 12 21:03:02 volumio volumio[30102]: info: Loading plugin "volumiodiscovery"... May 12 21:03:02 volumio volumio[30102]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:03:02 volumio volumio[30102]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:03:02 volumio volumio[30102]: *** WARNING *** For more information see May 12 21:03:02 volumio volumio[30102]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:03:02 volumio node[30102]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:03:02 volumio volumio[30102]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:03:02 volumio volumio[30102]: *** WARNING *** For more information see May 12 21:03:02 volumio node[30102]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:03:02 volumio node[30102]: *** WARNING *** For more information see May 12 21:03:02 volumio node[30102]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:03:02 volumio node[30102]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:03:02 volumio node[30102]: *** WARNING *** For more information see May 12 21:03:02 volumio volumio[30102]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 21:03:02 volumio volumio[30102]: info: Discovery: Started advertising with name: Volumio May 12 21:03:02 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:03:02 volumio volumio[30102]: info: Loading plugin "youtube2"... May 12 21:03:05 volumio volumio[30102]: info: Loading plugin "outputs"... May 12 21:03:05 volumio volumio[30102]: info: Loading plugin "albumart"... May 12 21:03:05 volumio volumio[30102]: info: Plugin example_plugin is not enabled May 12 21:03:05 volumio volumio[30102]: info: Loading plugin "inputs"... May 12 21:03:05 volumio volumio[30102]: info: Loading plugin "updater_comm"... May 12 21:03:05 volumio volumio[30102]: info: Plugin mpdemulation is not enabled May 12 21:03:05 volumio volumio[30102]: info: Loading plugin "rest_api"... May 12 21:03:05 volumio volumio[30102]: info: Loading plugin "websocket"... May 12 21:03:05 volumio volumio[30102]: info: Starting Socket.io Server version 2.3.0 May 12 21:03:05 volumio volumio[30102]: info: Loading plugin "fusiondsp"... May 12 21:03:06 volumio volumio[30102]: Forking 3 albumart workers May 12 21:03:06 volumio volumio[30102]: info: Applying required configuration parameters for plugin fusiondsp May 12 21:03:06 volumio volumio[30102]: info: Loading i18n strings for locale ja May 12 21:03:06 volumio volumio[30102]: Updating browse sources language May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::initPlayerControls May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:03:06 volumio volumio[30102]: Express server listening on port 3000 May 12 21:03:06 volumio volumio[30102]: [Metrics] WebUI: 7s 876.28ms May 12 21:03:06 volumio volumio[30102]: info: CoreStateMachine::resetVolumioState May 12 21:03:06 volumio volumio[30102]: info: CoreStateMachine::getcurrentVolume May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:03:06 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:06 volumio volumio[30102]: info: Cannot mount NAS NASMusic at system boot, trial number 2 ,retrying in 5 seconds May 12 21:03:06 volumio volumio[30102]: info: Cannot mount NAS fater at system boot, trial number 2 ,retrying in 5 seconds May 12 21:03:06 volumio volumio[30102]: info: Volumio Network Manager: Network status updated: 2 May 12 21:03:06 volumio volumio[30102]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 12 21:03:06 volumio volumio[30102]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 12 21:03:06 volumio volumio-remote-updater[601]: [2025-05-12 21:03:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747051382 101 May 12 21:03:06 volumio volumio[30102]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 12 21:03:07 volumio volumio[30102]: info: Reloading queue from file May 12 21:03:07 volumio volumio[30102]: info: CoreStateMachine::setRepeat null single undefined May 12 21:03:07 volumio volumio[30102]: info: CoreStateMachine::pushState May 12 21:03:07 volumio volumio[30102]: info: CorePlayQueue::getTrack 0 May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::volumioPushState May 12 21:03:07 volumio volumio[30102]: info: CoreStateMachine::setRandom null May 12 21:03:07 volumio volumio[30102]: info: CoreStateMachine::pushState May 12 21:03:07 volumio volumio[30102]: info: CorePlayQueue::getTrack 0 May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::volumioPushState May 12 21:03:07 volumio volumio[30102]: info: Setting Device type: Raspberry PI May 12 21:03:07 volumio volumio[30102]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 12 21:03:07 volumio volumio[30102]: info: VolumeController:: Volume=44 Mute =false May 12 21:03:07 volumio volumio[30102]: info: CoreStateMachine::pushState May 12 21:03:07 volumio volumio[30102]: info: CorePlayQueue::getTrack 0 May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::volumioPushState May 12 21:03:07 volumio volumio[30102]: info: CoreStateMachine::updateTrackBlock May 12 21:03:07 volumio volumio[30102]: info: CorePlayQueue::getTrackBlock May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 12 21:03:07 volumio volumio[30102]: Starting albumart workers May 12 21:03:07 volumio volumio[30102]: Starting albumart workers May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::volumioGetVisibleSources May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::volumioGetState May 12 21:03:07 volumio volumio[30102]: info: CorePlayQueue::getTrack 0 May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::volumioGetState May 12 21:03:07 volumio volumio[30102]: info: CorePlayQueue::getTrack 0 May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 12 21:03:07 volumio volumio[30102]: info: Received Get System Info May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 21:03:07 volumio volumio[30102]: info: Discovery: Getting this device information May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::volumioGetState May 12 21:03:07 volumio volumio[30102]: info: CorePlayQueue::getTrack 0 May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::volumioGetState May 12 21:03:07 volumio volumio[30102]: info: CorePlayQueue::getTrack 0 May 12 21:03:07 volumio volumio[30102]: Starting albumart workers May 12 21:03:07 volumio volumio[30102]: info: Listing playlists May 12 21:03:07 volumio volumio[30102]: info: Discovery: adding 4eac4e30-3d36-47e9-8023-9350033c0a5c May 12 21:03:07 volumio volumio[30102]: info: Discovery: Found device Volumio May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::volumioGetState May 12 21:03:07 volumio volumio[30102]: info: CorePlayQueue::getTrack 0 May 12 21:03:07 volumio volumio[30102]: info: Completed loading Core Plugins May 12 21:03:07 volumio volumio[30102]: info: Preparing to generate the ALSA configuration file May 12 21:03:07 volumio volumio[30102]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:03:07 volumio volumio[30102]: info: Reading ALSA contributions from plugins. May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::getUIConfigOnPlugin May 12 21:03:07 volumio volumio[30102]: info: VolumeController:: Volume=44 Mute =false May 12 21:03:07 volumio volumio[30102]: info: CoreStateMachine::pushState May 12 21:03:07 volumio volumio[30102]: info: CorePlayQueue::getTrack 0 May 12 21:03:07 volumio volumio[30102]: info: CoreCommandRouter::volumioPushState May 12 21:03:07 volumio volumio[30102]: info: FusionDsp - no preset used May 12 21:03:07 volumio volumio[30102]: info: FusionDsp - no preset used May 12 21:03:08 volumio volumio[30102]: info: FusionDsp - no preset used May 12 21:03:08 volumio volumio[30102]: info: FusionDsp - no preset used May 12 21:03:08 volumio volumio[30102]: info: FusionDsp - no preset used May 12 21:03:08 volumio volumio[30102]: info: FusionDsp - no preset used May 12 21:03:09 volumio volumio[30102]: info: FusionDsp - no preset used May 12 21:03:09 volumio volumio[30102]: info: FusionDsp - no preset used May 12 21:03:09 volumio volumio[30102]: info: FusionDsp - no preset used May 12 21:03:09 volumio volumio[30102]: info: FusionDsp - no preset used May 12 21:03:09 volumio volumio[30102]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 12 21:03:09 volumio volumio[30102]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 12 21:03:09 volumio volumio[30102]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 12 21:03:10 volumio volumio[30102]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 12 21:03:10 volumio volumio[30102]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 12 21:03:10 volumio volumio[30102]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 12 21:03:10 volumio volumio[30102]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 12 21:03:10 volumio volumio[30102]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 12 21:03:10 volumio volumio[30102]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 12 21:03:10 volumio volumio[30102]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 12 21:03:10 volumio volumio[30102]: info: Asound.conf file unchanged, so no further update is needed May 12 21:03:10 volumio volumio[30102]: info: Output device has changed, restarting MPD May 12 21:03:10 volumio sudo[30227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:03:10 volumio sudo[30227]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:10 volumio volumio[30102]: info: Output device has changed, restarting Shairport Sync May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:10 volumio sudo[30227]: pam_unix(sudo:session): session closed for user root May 12 21:03:10 volumio sudo[30229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:03:10 volumio sudo[30229]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:10 volumio systemd[1]: Stopping Music Player Daemon... May 12 21:03:10 volumio volumio[30102]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:03:10 volumio volumio[30102]: info: ___________ START PLUGINS ___________ May 12 21:03:10 volumio volumio[30102]: info: ControllerMpd::onStart: Initializing MPD May 12 21:03:10 volumio volumio[30102]: info: Creating MPD Configuration file May 12 21:03:10 volumio sudo[30236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:03:10 volumio sudo[30236]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:10 volumio systemd[1]: mpd.service: Succeeded. May 12 21:03:10 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:03:10 volumio volumio[30102]: info: [1747051390585] CoreMusicLibrary::Adding element DLNAサーバー May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:03:10 volumio sudo[30236]: pam_unix(sudo:session): session closed for user root May 12 21:03:10 volumio sudo[30239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:03:10 volumio sudo[30239]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:10 volumio systemd[1]: Starting Music Player Daemon... May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:10 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 12 21:03:10 volumio systemd[1]: mpd.service: Succeeded. May 12 21:03:10 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:03:10 volumio systemd[1]: Starting Music Player Daemon... May 12 21:03:10 volumio volumio[30102]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:10 volumio volumio[30102]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:03:10 volumio volumio[30102]: info: [1747051390743] CoreMusicLibrary::Adding element Last_100 May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:03:10 volumio volumio[30102]: info: [1747051390747] CoreMusicLibrary::Adding element Webradio May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:03:10 volumio volumio[30102]: info: Initializing BBC Radios May 12 21:03:10 volumio sudo[30245]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:03:10 volumio sudo[30245]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:10 volumio sudo[30245]: pam_unix(sudo:session): session closed for user root May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:03:10 volumio volumio[30102]: info: [1747051390854] CoreMusicLibrary::Adding element YouTube2 May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:03:10 volumio volumio[30102]: Cannot find translation for source YouTube2 May 12 21:03:10 volumio volumio[30102]: info: Loading i18n strings for locale ja May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 21:03:10 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:10 volumio volumio[30102]: info: FusionDsp - mixtype--------------------- Hardware May 12 21:03:10 volumio volumio[30102]: info: Volumio Calling Home May 12 21:03:11 volumio volumio[30102]: info: Preparing to generate the ALSA configuration file May 12 21:03:11 volumio volumio[30102]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:03:11 volumio volumio[30102]: info: Reading ALSA contributions from plugins. May 12 21:03:11 volumio volumio[30102]: info: MPD Permissions set May 12 21:03:11 volumio volumio[30102]: info: MPD Permissions set May 12 21:03:11 volumio volumio[30102]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio sudo[30287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 21:03:11 volumio sudo[30287]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:11 volumio sudo[30287]: pam_unix(sudo:session): session closed for user root May 12 21:03:11 volumio sudo[30290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 21:03:11 volumio sudo[30290]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:11 volumio sudo[30290]: pam_unix(sudo:session): session closed for user root May 12 21:03:11 volumio volumio[30102]: info: Volumio called home May 12 21:03:11 volumio volumio[30102]: info: Starting Shairport Sync May 12 21:03:11 volumio volumio[30102]: info: Starting Shairport Sync May 12 21:03:11 volumio sudo[30293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:03:11 volumio sudo[30293]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:11 volumio volumio[30102]: info: Starting Shairport Sync May 12 21:03:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:03:11 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:03:11 volumio sudo[30296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:03:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:03:11 volumio sudo[30296]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:11 volumio sudo[30298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:03:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:03:11 volumio sudo[30298]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::volumioGetState May 12 21:03:11 volumio volumio[30102]: info: CorePlayQueue::getTrack 0 May 12 21:03:11 volumio sudo[30293]: pam_unix(sudo:session): session closed for user root May 12 21:03:11 volumio volumio[30102]: info: Cannot mount NAS NASMusic at system boot, trial number 3 ,retrying in 5 seconds May 12 21:03:11 volumio volumio[30102]: info: Cannot mount NAS fater at system boot, trial number 3 ,retrying in 5 seconds May 12 21:03:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:03:11 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 12 21:03:11 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:03:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:03:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:03:11 volumio volumio[30102]: info: Asound.conf file unchanged, so no further update is needed May 12 21:03:11 volumio volumio[30102]: info: Output device has changed, restarting MPD May 12 21:03:11 volumio sudo[30296]: pam_unix(sudo:session): session closed for user root May 12 21:03:11 volumio sudo[30298]: pam_unix(sudo:session): session closed for user root May 12 21:03:11 volumio sudo[30304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:03:11 volumio sudo[30304]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:11 volumio volumio[30102]: info: Output device has changed, restarting Shairport Sync May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:11 volumio volumio[30102]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:11 volumio sudo[30304]: pam_unix(sudo:session): session closed for user root May 12 21:03:11 volumio sudo[30307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:03:11 volumio sudo[30307]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:11 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 21:03:11 volumio systemd[1]: mpd.service: Succeeded. May 12 21:03:11 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:03:11 volumio volumio[30102]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:03:11 volumio volumio[30102]: info: Shairport-Sync Started May 12 21:03:11 volumio volumio[30102]: Error adding Membership: Error: addMembership EINVAL May 12 21:03:12 volumio volumio[30102]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:03:12 volumio volumio[30102]: TypeError: Cannot read property 'off' of undefined May 12 21:03:12 volumio volumio[30102]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) May 12 21:03:12 volumio volumio[30102]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 12 21:03:12 volumio volumio[30102]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 12 21:03:12 volumio volumio[30102]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 12 21:03:12 volumio volumio[30102]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 12 21:03:12 volumio volumio[30102]: at Socket.emit (events.js:315:20) May 12 21:03:12 volumio volumio[30102]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 12 21:03:12 volumio volumio[30102]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 12 21:03:12 volumio volumio[30102]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:03:12 volumio systemd[1]: Starting Music Player Daemon... May 12 21:03:12 volumio sudo[30314]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:03:12 volumio sudo[30314]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:12 volumio sudo[30314]: pam_unix(sudo:session): session closed for user root May 12 21:03:12 volumio sudo[30326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 21:02 May 12 21:03:12 volumio sudo[30326]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:12 volumio sudo[30326]: pam_unix(sudo:session): session closed for user root May 12 21:03:13 volumio mpd[30324]: May 12 21:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 21:03:14 volumio systemd[1]: Started Music Player Daemon. May 12 21:03:14 volumio sudo[30307]: pam_unix(sudo:session): session closed for user root May 12 21:03:14 volumio sudo[30229]: pam_unix(sudo:session): session closed for user root May 12 21:03:14 volumio sudo[30239]: pam_unix(sudo:session): session closed for user root May 12 21:03:14 volumio volumio-remote-updater[601]: [2025-05-12 21:03:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 12 21:03:14 volumio volumio-remote-updater[601]: [2025-05-12 21:03:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 12 21:03:14 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 12 21:03:14 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 12 21:03:14 volumio systemd[1]: Started dynamicswap service. May 12 21:03:14 volumio systemd[1]: dynamicswap.service: Succeeded. May 12 21:03:14 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 12 21:03:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 112. May 12 21:03:14 volumio systemd[1]: Started dynamicswap service. May 12 21:03:14 volumio systemd[1]: Stopped Volumio Backend Module. May 12 21:03:14 volumio systemd[1]: Started Volumio Backend Module. May 12 21:03:14 volumio systemd[1]: dynamicswap.service: Succeeded. May 12 21:03:16 volumio volumio[30342]: info: ------------------------------------------- May 12 21:03:16 volumio volumio[30342]: info: ----- Volumio3 ---- May 12 21:03:16 volumio volumio[30342]: info: ------------------------------------------- May 12 21:03:16 volumio volumio[30342]: info: ----- System startup ---- May 12 21:03:16 volumio volumio[30342]: info: ------------------------------------------- May 12 21:03:17 volumio volumio[30342]: info: MYVOLUMIO Environment detected May 12 21:03:17 volumio volumio[30342]: info: Plugin folders cleanup May 12 21:03:17 volumio volumio[30342]: info: Scanning into folder /volumio/app/plugins/ May 12 21:03:17 volumio volumio[30342]: info: Scanning category audio_interface May 12 21:03:17 volumio volumio[30342]: info: Scanning category miscellanea May 12 21:03:17 volumio volumio[30342]: info: Scanning category music_service May 12 21:03:17 volumio volumio[30342]: info: Scanning category plugins.json May 12 21:03:17 volumio volumio[30342]: info: Scanning category system_controller May 12 21:03:17 volumio volumio[30342]: info: Scanning category user_interface May 12 21:03:17 volumio volumio[30342]: info: Scanning into folder /data/plugins/ May 12 21:03:17 volumio volumio[30342]: info: Scanning category audio_interface May 12 21:03:17 volumio volumio[30342]: info: Scanning category music_service May 12 21:03:17 volumio volumio[30342]: info: Plugin folders cleanup completed May 12 21:03:17 volumio volumio[30342]: info: ------------------------------------------- May 12 21:03:17 volumio volumio[30342]: info: ----- Core plugins startup ---- May 12 21:03:17 volumio volumio[30342]: info: ------------------------------------------- May 12 21:03:17 volumio volumio[30342]: info: Loading plugins from folder /volumio/app/plugins/ May 12 21:03:17 volumio volumio[30342]: info: Adding plugin upnp to MyMusic Plugins May 12 21:03:17 volumio volumio[30342]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 21:03:17 volumio volumio[30342]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 21:03:17 volumio volumio[30342]: info: Loading plugins from folder /data/plugins/ May 12 21:03:17 volumio volumio[30342]: info: Loading plugin "system"... May 12 21:03:17 volumio volumio[30342]: info: Loading plugin "appearance"... May 12 21:03:18 volumio volumio[30342]: info: Loading plugin "network"... May 12 21:03:18 volumio volumio[30342]: info: Refreshing Cached IP Addresses May 12 21:03:18 volumio sudo[30368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 21:03:18 volumio sudo[30368]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:18 volumio sudo[30368]: pam_unix(sudo:session): session closed for user root May 12 21:03:18 volumio sudo[30370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 21:03:18 volumio sudo[30370]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:18 volumio sudo[30370]: pam_unix(sudo:session): session closed for user root May 12 21:03:18 volumio volumio[30342]: info: Loading plugin "services"... May 12 21:03:18 volumio volumio[30342]: info: Loading plugin "alsa_controller"... May 12 21:03:18 volumio sudo[30382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 12 21:03:18 volumio sudo[30382]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:18 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:03:18 volumio volumio[30342]: info: Loading plugin "wizard"... May 12 21:03:18 volumio volumio[30342]: info: Loading plugin "networkfs"... May 12 21:03:18 volumio volumio[30342]: info: Cannot mount NAS NASMusic at system boot, trial number 1 ,retrying in 5 seconds May 12 21:03:18 volumio volumio[30342]: info: Cannot mount NAS fater at system boot, trial number 1 ,retrying in 5 seconds May 12 21:03:18 volumio volumio[30342]: info: Starting Udev Watcher for removable devices May 12 21:03:18 volumio volumio[30342]: info: Ignoring mount for partition: boot May 12 21:03:18 volumio volumio[30342]: info: Ignoring mount for partition: volumio May 12 21:03:18 volumio volumio[30342]: info: Ignoring mount for partition: volumio_data May 12 21:03:18 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:03:18 volumio volumio[30342]: info: Loading plugin "volumio_command_line_client"... May 12 21:03:18 volumio volumio[30342]: info: Loading plugin "upnp"... May 12 21:03:18 volumio volumio[30342]: info: [1747051398782] Starting Upmpd Daemon May 12 21:03:18 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:03:18 volumio volumio[30342]: info: Loading plugin "my_music"... May 12 21:03:18 volumio volumio[30342]: info: Loading plugin "mpd"... May 12 21:03:19 volumio volumio[30342]: info: Loading plugin "upnp_browser"... May 12 21:03:19 volumio volumio[30342]: info: Loading plugin "alarm-clock"... May 12 21:03:19 volumio sudo[30382]: pam_unix(sudo:session): session closed for user root May 12 21:03:19 volumio volumio-remote-updater[601]: [2025-05-12 21:03:19] [connect] Successful connection May 12 21:03:19 volumio volumio[30342]: info: Loading plugin "airplay_emulation"... May 12 21:03:19 volumio volumio[30342]: info: Starting Shairport Sync May 12 21:03:19 volumio volumio[30342]: info: Loading plugin "last_100"... May 12 21:03:19 volumio volumio[30342]: info: Loading plugin "webradio"... May 12 21:03:19 volumio volumio[30342]: info: Loading plugin "i2s_dacs"... May 12 21:03:19 volumio volumio[30342]: info: I2S DAC not set, start Auto-detection May 12 21:03:19 volumio volumio[30342]: info: Loading plugin "volumiodiscovery"... May 12 21:03:19 volumio volumio[30342]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:03:19 volumio volumio[30342]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:03:19 volumio volumio[30342]: *** WARNING *** For more information see May 12 21:03:19 volumio node[30342]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 21:03:19 volumio volumio[30342]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:03:19 volumio volumio[30342]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:03:19 volumio volumio[30342]: *** WARNING *** For more information see May 12 21:03:19 volumio node[30342]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:03:19 volumio node[30342]: *** WARNING *** For more information see May 12 21:03:19 volumio node[30342]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 21:03:19 volumio node[30342]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 21:03:19 volumio node[30342]: *** WARNING *** For more information see May 12 21:03:19 volumio volumio[30342]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 21:03:19 volumio volumio[30342]: info: Discovery: Started advertising with name: Volumio May 12 21:03:19 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 21:03:19 volumio volumio[30342]: info: Loading plugin "youtube2"... May 12 21:03:23 volumio volumio[30342]: info: Loading plugin "outputs"... May 12 21:03:23 volumio volumio[30342]: info: Loading plugin "albumart"... May 12 21:03:23 volumio volumio[30342]: info: Plugin example_plugin is not enabled May 12 21:03:23 volumio volumio[30342]: info: Loading plugin "inputs"... May 12 21:03:23 volumio volumio[30342]: info: Loading plugin "updater_comm"... May 12 21:03:23 volumio volumio[30342]: info: Plugin mpdemulation is not enabled May 12 21:03:23 volumio volumio[30342]: info: Loading plugin "rest_api"... May 12 21:03:23 volumio volumio[30342]: info: Loading plugin "websocket"... May 12 21:03:23 volumio volumio[30342]: info: Starting Socket.io Server version 2.3.0 May 12 21:03:23 volumio volumio[30342]: info: Loading plugin "fusiondsp"... May 12 21:03:23 volumio volumio[30342]: Forking 3 albumart workers May 12 21:03:23 volumio volumio[30342]: info: Applying required configuration parameters for plugin fusiondsp May 12 21:03:23 volumio volumio[30342]: info: Loading i18n strings for locale ja May 12 21:03:23 volumio volumio[30342]: Updating browse sources language May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::initPlayerControls May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:23 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:03:23 volumio volumio[30342]: Express server listening on port 3000 May 12 21:03:23 volumio volumio[30342]: [Metrics] WebUI: 7s 927.78ms May 12 21:03:23 volumio volumio[30342]: info: CoreStateMachine::resetVolumioState May 12 21:03:23 volumio volumio[30342]: info: CoreStateMachine::getcurrentVolume May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:24 volumio volumio[30342]: info: Cannot mount NAS NASMusic at system boot, trial number 2 ,retrying in 5 seconds May 12 21:03:24 volumio volumio[30342]: info: Cannot mount NAS fater at system boot, trial number 2 ,retrying in 5 seconds May 12 21:03:24 volumio volumio[30342]: info: Volumio Network Manager: Network status updated: 2 May 12 21:03:24 volumio volumio[30342]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 12 21:03:24 volumio volumio[30342]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 12 21:03:24 volumio volumio-remote-updater[601]: [2025-05-12 21:03:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747051399 101 May 12 21:03:24 volumio volumio[30342]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 12 21:03:24 volumio volumio[30342]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 12 21:03:24 volumio volumio[30342]: info: Reloading queue from file May 12 21:03:24 volumio volumio[30342]: info: CoreStateMachine::setRepeat null single undefined May 12 21:03:24 volumio volumio[30342]: info: CoreStateMachine::pushState May 12 21:03:24 volumio volumio[30342]: info: CorePlayQueue::getTrack 0 May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::volumioPushState May 12 21:03:24 volumio volumio[30342]: info: CoreStateMachine::setRandom null May 12 21:03:24 volumio volumio[30342]: info: CoreStateMachine::pushState May 12 21:03:24 volumio volumio[30342]: info: CorePlayQueue::getTrack 0 May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::volumioPushState May 12 21:03:24 volumio volumio[30342]: info: Setting Device type: Raspberry PI May 12 21:03:24 volumio volumio[30342]: info: VolumeController:: Volume=44 Mute =false May 12 21:03:24 volumio volumio[30342]: info: CoreStateMachine::pushState May 12 21:03:24 volumio volumio[30342]: info: CorePlayQueue::getTrack 0 May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::volumioPushState May 12 21:03:24 volumio volumio[30342]: info: CoreStateMachine::updateTrackBlock May 12 21:03:24 volumio volumio[30342]: info: CorePlayQueue::getTrackBlock May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::volumioRetrievevolume May 12 21:03:24 volumio volumio[30342]: Starting albumart workers May 12 21:03:24 volumio volumio[30342]: info: Completed loading Core Plugins May 12 21:03:24 volumio volumio[30342]: info: Preparing to generate the ALSA configuration file May 12 21:03:24 volumio volumio[30342]: info: Discovery: adding 4eac4e30-3d36-47e9-8023-9350033c0a5c May 12 21:03:24 volumio volumio[30342]: info: Discovery: Found device Volumio May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::volumioGetState May 12 21:03:24 volumio volumio[30342]: info: CorePlayQueue::getTrack 0 May 12 21:03:24 volumio volumio[30342]: Starting albumart workers May 12 21:03:24 volumio volumio[30342]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:03:24 volumio volumio[30342]: info: Reading ALSA contributions from plugins. May 12 21:03:24 volumio volumio[30342]: Starting albumart workers May 12 21:03:24 volumio volumio[30342]: info: Asound.conf file unchanged, so no further update is needed May 12 21:03:24 volumio volumio[30342]: info: Output device has changed, restarting MPD May 12 21:03:24 volumio sudo[30438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:03:24 volumio volumio[30342]: info: Output device has changed, restarting Shairport Sync May 12 21:03:24 volumio sudo[30438]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:24 volumio sudo[30438]: pam_unix(sudo:session): session closed for user root May 12 21:03:24 volumio sudo[30448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:03:24 volumio sudo[30448]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:24 volumio systemd[1]: Stopping Music Player Daemon... May 12 21:03:24 volumio volumio[30342]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:03:24 volumio volumio[30342]: info: ___________ START PLUGINS ___________ May 12 21:03:24 volumio volumio[30342]: info: ControllerMpd::onStart: Initializing MPD May 12 21:03:24 volumio volumio[30342]: info: Creating MPD Configuration file May 12 21:03:24 volumio systemd[1]: mpd.service: Succeeded. May 12 21:03:24 volumio sudo[30459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:03:24 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:03:24 volumio sudo[30459]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:24 volumio sudo[30459]: pam_unix(sudo:session): session closed for user root May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:03:24 volumio volumio[30342]: info: [1747051404946] CoreMusicLibrary::Adding element DLNAサーバー May 12 21:03:24 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:03:24 volumio systemd[1]: Starting Music Player Daemon... May 12 21:03:24 volumio sudo[30463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:03:24 volumio sudo[30463]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:25 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 12 21:03:25 volumio systemd[1]: mpd.service: Succeeded. May 12 21:03:25 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:03:25 volumio systemd[1]: Starting Music Player Daemon... May 12 21:03:25 volumio volumio[30342]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:03:25 volumio volumio[30342]: info: [1747051405211] CoreMusicLibrary::Adding element Last_100 May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:03:25 volumio sudo[30465]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:03:25 volumio volumio[30342]: info: [1747051405221] CoreMusicLibrary::Adding element Webradio May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:03:25 volumio sudo[30465]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:03:25 volumio sudo[30465]: pam_unix(sudo:session): session closed for user root May 12 21:03:25 volumio volumio[30342]: info: Initializing BBC Radios May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 21:03:25 volumio volumio[30342]: info: [1747051405411] CoreMusicLibrary::Adding element YouTube2 May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 21:03:25 volumio volumio[30342]: Cannot find translation for source YouTube2 May 12 21:03:25 volumio volumio[30342]: info: Loading i18n strings for locale ja May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: FusionDsp - mixtype--------------------- Hardware May 12 21:03:25 volumio volumio[30342]: info: Volumio Calling Home May 12 21:03:25 volumio volumio[30342]: info: Preparing to generate the ALSA configuration file May 12 21:03:25 volumio volumio[30342]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 21:03:25 volumio volumio[30342]: info: Reading ALSA contributions from plugins. May 12 21:03:25 volumio volumio[30342]: info: MPD Permissions set May 12 21:03:25 volumio volumio[30342]: info: MPD Permissions set May 12 21:03:25 volumio volumio[30342]: info: VolumeController:: Volume=44 Mute =false May 12 21:03:25 volumio volumio[30342]: info: CoreStateMachine::pushState May 12 21:03:25 volumio volumio[30342]: info: CorePlayQueue::getTrack 0 May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::volumioPushState May 12 21:03:25 volumio volumio[30342]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:25 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: Starting Shairport Sync May 12 21:03:26 volumio volumio[30342]: info: Starting Shairport Sync May 12 21:03:26 volumio sudo[30558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:03:26 volumio volumio[30342]: info: Starting Shairport Sync May 12 21:03:26 volumio sudo[30558]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:26 volumio sudo[30560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:03:26 volumio sudo[30560]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:26 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:03:26 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:03:26 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:03:26 volumio sudo[30563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:03:26 volumio sudo[30563]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::volumioGetState May 12 21:03:26 volumio volumio[30342]: info: CorePlayQueue::getTrack 0 May 12 21:03:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:03:26 volumio volumio[30342]: info: Volumio called home May 12 21:03:26 volumio volumio[30342]: info: Asound.conf file unchanged, so no further update is needed May 12 21:03:26 volumio volumio[30342]: info: Output device has changed, restarting MPD May 12 21:03:26 volumio sudo[30558]: pam_unix(sudo:session): session closed for user root May 12 21:03:26 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:03:26 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 12 21:03:26 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:03:26 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:03:26 volumio volumio[30342]: info: Output device has changed, restarting Shairport Sync May 12 21:03:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:03:26 volumio sudo[30568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 21:03:26 volumio sudo[30560]: pam_unix(sudo:session): session closed for user root May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:26 volumio sudo[30568]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:26 volumio sudo[30563]: pam_unix(sudo:session): session closed for user root May 12 21:03:26 volumio sudo[30568]: pam_unix(sudo:session): session closed for user root May 12 21:03:26 volumio sudo[30571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 21:03:26 volumio sudo[30571]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:26 volumio volumio[30342]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 21:03:26 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 21:03:26 volumio systemd[1]: mpd.service: Succeeded. May 12 21:03:26 volumio systemd[1]: Stopped Music Player Daemon. May 12 21:03:26 volumio volumio[30342]: info: MPD Permissions set May 12 21:03:26 volumio volumio[30342]: info: Shairport-Sync Started May 12 21:03:26 volumio volumio[30342]: Error adding Membership: Error: addMembership EINVAL May 12 21:03:26 volumio volumio[30342]: info: Shairport-Sync Started May 12 21:03:26 volumio volumio[30342]: info: Shairport-Sync Started May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:26 volumio volumio[30342]: info: Starting Shairport Sync May 12 21:03:26 volumio systemd[1]: Starting Music Player Daemon... May 12 21:03:26 volumio sudo[30585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 21:03:26 volumio sudo[30585]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:26 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 21:03:26 volumio systemd[1]: shairport-sync.service: Succeeded. May 12 21:03:26 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 21:03:26 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 21:03:26 volumio sudo[30585]: pam_unix(sudo:session): session closed for user root May 12 21:03:26 volumio volumio[30342]: info: Shairport-Sync Started May 12 21:03:26 volumio sudo[30579]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 12 21:03:26 volumio sudo[30579]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:26 volumio sudo[30579]: pam_unix(sudo:session): session closed for user root May 12 21:03:27 volumio volumio[30342]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 21:03:27 volumio volumio[30342]: 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 May 12 21:03:27 volumio volumio[30342]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 21:03:27 volumio volumio[30342]: info: camilladsp service started and running in background, instance 1 May 12 21:03:27 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 21:03:27 volumio volumio[30342]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 21:03:27 volumio volumio[30342]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 12 21:03:27 volumio volumio[30342]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 21:03:27 volumio volumio[30342]: info: FusionDsp loaded May 12 21:03:27 volumio volumio[30342]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 21:03:27 volumio sudo[30604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 12 21:03:27 volumio sudo[30604]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 21:03:27 volumio volumio[30342]: info: FusionDsp - Reporting Fusion DSP Enabled May 12 21:03:27 volumio sudo[30604]: pam_unix(sudo:session): session closed for user root May 12 21:03:27 volumio volumio[30342]: info: Adding Signal Path Element [object Object] May 12 21:03:27 volumio volumio[30342]: info: Adding fusiondspeq DSP Signal Path Element May 12 21:03:27 volumio volumio[30342]: info: FusionDsp - ---- installed callbackRead May 12 21:03:27 volumio volumio[30342]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:03:27 volumio volumio[30342]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 12 21:03:27 volumio volumio[30342]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 12 21:03:27 volumio volumio[30342]: at onErrorNT (internal/child_process.js:465:16) May 12 21:03:27 volumio volumio[30342]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 12 21:03:27 volumio volumio[30342]: errno: -2, May 12 21:03:27 volumio volumio[30342]: code: 'ENOENT', May 12 21:03:27 volumio volumio[30342]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 12 21:03:27 volumio volumio[30342]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 12 21:03:27 volumio volumio[30342]: spawnargs: [ May 12 21:03:27 volumio volumio[30342]: '-p', May 12 21:03:27 volumio volumio[30342]: 9876, May 12 21:03:27 volumio volumio[30342]: '-o', May 12 21:03:27 volumio volumio[30342]: '/tmp/camilladsp.log', May 12 21:03:27 volumio volumio[30342]: '-l', May 12 21:03:27 volumio volumio[30342]: 'warn', May 12 21:03:27 volumio volumio[30342]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 12 21:03:27 volumio volumio[30342]: ] May 12 21:03:27 volumio volumio[30342]: } May 12 21:03:27 volumio volumio[30342]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 21:03:28 volumio mpd[30592]: May 12 21:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 21:03:28 volumio sudo[30617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 21:02 May 12 21:03:28 volumio sudo[30617]: 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="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"