-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Sat 2025-08-30 07:47:01 KST. -- Aug 30 07:46:02 volumio volumio-remote-updater[593]: [2025-08-30 07:46:02] [info] asio async_connect error: system:111 (Connection refused) Aug 30 07:46:02 volumio volumio-remote-updater[593]: [2025-08-30 07:46:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 30 07:46:02 volumio volumio-remote-updater[593]: [2025-08-30 07:46:02] [error] handle_connect error: Underlying Transport Error Aug 30 07:46:10 volumio systemd[1]: volumio.service: State 'stop-sigterm' timed out. Killing. Aug 30 07:46:10 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 30 07:46:10 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:10 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:10 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 30 07:46:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 39. Aug 30 07:46:10 volumio systemd[1]: Stopped Volumio Backend Module. Aug 30 07:46:10 volumio systemd[1]: Started Volumio Backend Module. Aug 30 07:46:10 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:10 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:11 volumio volumio[10756]: info: ------------------------------------------- Aug 30 07:46:11 volumio volumio[10756]: info: ----- Volumio3 ---- Aug 30 07:46:11 volumio volumio[10756]: info: ------------------------------------------- Aug 30 07:46:11 volumio volumio[10756]: info: ----- System startup ---- Aug 30 07:46:11 volumio volumio[10756]: info: ------------------------------------------- Aug 30 07:46:12 volumio volumio[10756]: info: MYVOLUMIO Environment detected Aug 30 07:46:12 volumio volumio[10756]: info: Plugin folders cleanup Aug 30 07:46:12 volumio volumio[10756]: info: Scanning into folder /volumio/app/plugins/ Aug 30 07:46:12 volumio volumio[10756]: info: Scanning category audio_interface Aug 30 07:46:12 volumio volumio[10756]: info: Scanning category miscellanea Aug 30 07:46:12 volumio volumio[10756]: info: Scanning category music_service Aug 30 07:46:12 volumio volumio[10756]: info: Scanning category plugins.json Aug 30 07:46:12 volumio volumio[10756]: info: Scanning category system_controller Aug 30 07:46:12 volumio volumio[10756]: info: Scanning category user_interface Aug 30 07:46:12 volumio volumio[10756]: info: Scanning into folder /data/plugins/ Aug 30 07:46:12 volumio volumio[10756]: info: Scanning category audio_interface Aug 30 07:46:12 volumio volumio[10756]: info: Plugin folders cleanup completed Aug 30 07:46:12 volumio volumio[10756]: info: ------------------------------------------- Aug 30 07:46:12 volumio volumio[10756]: info: ----- Core plugins startup ---- Aug 30 07:46:12 volumio volumio[10756]: info: ------------------------------------------- Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugins from folder /volumio/app/plugins/ Aug 30 07:46:12 volumio volumio[10756]: info: Adding plugin upnp to MyMusic Plugins Aug 30 07:46:12 volumio volumio[10756]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 30 07:46:12 volumio volumio[10756]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugins from folder /data/plugins/ Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugin "system"... Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugin "appearance"... Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugin "network"... Aug 30 07:46:12 volumio volumio[10756]: info: Refreshing Cached IP Addresses Aug 30 07:46:12 volumio sudo[10786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 07:46:12 volumio sudo[10786]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:12 volumio sudo[10786]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:12 volumio sudo[10788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 07:46:12 volumio sudo[10788]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:12 volumio volumio-remote-updater[593]: [2025-08-30 07:46:12] [connect] Successful connection Aug 30 07:46:12 volumio sudo[10788]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugin "services"... Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugin "alsa_controller"... Aug 30 07:46:12 volumio sudo[10799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 30 07:46:12 volumio sudo[10799]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:12 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugin "wizard"... Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugin "networkfs"... Aug 30 07:46:12 volumio volumio[10756]: info: Starting Udev Watcher for removable devices Aug 30 07:46:12 volumio volumio[10756]: info: Ignoring mount for partition: boot Aug 30 07:46:12 volumio volumio[10756]: info: Ignoring mount for partition: volumio Aug 30 07:46:12 volumio volumio[10756]: info: Ignoring mount for partition: volumio_data Aug 30 07:46:12 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugin "volumio_command_line_client"... Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugin "upnp"... Aug 30 07:46:12 volumio volumio[10756]: info: [1756507572944] Starting Upmpd Daemon Aug 30 07:46:12 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugin "my_music"... Aug 30 07:46:12 volumio volumio[10756]: info: Loading plugin "mpd"... Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "upnp_browser"... Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "alarm-clock"... Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "airplay_emulation"... Aug 30 07:46:13 volumio volumio[10756]: info: Starting Shairport Sync Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "last_100"... Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "webradio"... Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "i2s_dacs"... Aug 30 07:46:13 volumio volumio[10756]: info: I2S DAC not set, start Auto-detection Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "volumiodiscovery"... Aug 30 07:46:13 volumio volumio[10756]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:13 volumio volumio[10756]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:13 volumio volumio[10756]: *** WARNING *** For more information see Aug 30 07:46:13 volumio node[10756]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:13 volumio volumio[10756]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:13 volumio volumio[10756]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:13 volumio volumio[10756]: *** WARNING *** For more information see Aug 30 07:46:13 volumio node[10756]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:13 volumio node[10756]: *** WARNING *** For more information see Aug 30 07:46:13 volumio node[10756]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:13 volumio node[10756]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:13 volumio node[10756]: *** WARNING *** For more information see Aug 30 07:46:13 volumio volumio[10756]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 07:46:13 volumio volumio[10756]: info: Discovery: Started advertising with name: Volumio Aug 30 07:46:13 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "outputs"... Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "albumart"... Aug 30 07:46:13 volumio volumio[10756]: info: Plugin example_plugin is not enabled Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "inputs"... Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "updater_comm"... Aug 30 07:46:13 volumio volumio[10756]: info: Plugin mpdemulation is not enabled Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "rest_api"... Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "websocket"... Aug 30 07:46:13 volumio volumio[10756]: info: Starting Socket.io Server version 2.3.0 Aug 30 07:46:13 volumio volumio[10756]: info: Loading plugin "fusiondsp"... Aug 30 07:46:13 volumio volumio[10756]: Forking 3 albumart workers Aug 30 07:46:13 volumio volumio[10756]: info: Applying required configuration parameters for plugin fusiondsp Aug 30 07:46:14 volumio volumio[10756]: info: Loading i18n strings for locale ko Aug 30 07:46:14 volumio volumio[10756]: Updating browse sources language Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::initPlayerControls Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: Express server listening on port 3000 Aug 30 07:46:14 volumio volumio[10756]: [Metrics] WebUI: 2s 953.07ms Aug 30 07:46:14 volumio volumio[10756]: info: CoreStateMachine::resetVolumioState Aug 30 07:46:14 volumio volumio[10756]: info: CoreStateMachine::getcurrentVolume Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: info: Cannot read play queue from file Aug 30 07:46:14 volumio volumio[10756]: info: Volumio Network Manager: Network status updated: 2 Aug 30 07:46:14 volumio volumio[10756]: Starting albumart workers Aug 30 07:46:14 volumio volumio[10756]: Starting albumart workers Aug 30 07:46:14 volumio volumio[10756]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Aug 30 07:46:14 volumio volumio[10756]: Starting albumart workers Aug 30 07:46:14 volumio volumio[10756]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Aug 30 07:46:14 volumio volumio-remote-updater[593]: [2025-08-30 07:46:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756507572 101 Aug 30 07:46:14 volumio volumio[10756]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Aug 30 07:46:14 volumio volumio[10756]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Aug 30 07:46:14 volumio volumio[10756]: info: Setting Device type: Raspberry PI Aug 30 07:46:14 volumio volumio[10756]: info: CoreStateMachine::setRepeat null single undefined Aug 30 07:46:14 volumio volumio[10756]: info: CoreStateMachine::pushState Aug 30 07:46:14 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:14 volumio volumio[10756]: info: CoreStateMachine::setRandom null Aug 30 07:46:14 volumio volumio[10756]: info: CoreStateMachine::pushState Aug 30 07:46:14 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 07:46:14 volumio volumio[10756]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:46:14 volumio volumio[10756]: info: CoreStateMachine::pushState Aug 30 07:46:14 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:14 volumio volumio[10756]: info: CoreStateMachine::updateTrackBlock Aug 30 07:46:14 volumio volumio[10756]: info: CorePlayQueue::getTrackBlock Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:46:14 volumio volumio[10756]: info: Completed loading Core Plugins Aug 30 07:46:14 volumio volumio[10756]: info: Preparing to generate the ALSA configuration file Aug 30 07:46:14 volumio volumio[10756]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:46:14 volumio volumio[10756]: info: Reading ALSA contributions from plugins. Aug 30 07:46:14 volumio volumio[10756]: info: Discovery: adding 6d849cfc-83e0-4e78-9cc3-7e8f7bad4661 Aug 30 07:46:14 volumio volumio[10756]: info: Discovery: Found device Volumio Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:14 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:14 volumio volumio[10756]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:14 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:14 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 07:46:14 volumio volumio[10756]: info: Received Get System Info Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:46:14 volumio volumio[10756]: info: Discovery: Getting this device information Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:14 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:14 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:14 volumio volumio[10756]: info: Listing playlists Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 07:46:14 volumio volumio[10756]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:46:14 volumio volumio[10756]: info: CoreStateMachine::pushState Aug 30 07:46:14 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:14 volumio volumio[10756]: info: Asound.conf file unchanged, so no further update is needed Aug 30 07:46:14 volumio volumio[10756]: info: Output device has changed, restarting MPD Aug 30 07:46:14 volumio sudo[10865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:14 volumio sudo[10865]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:14 volumio volumio[10756]: info: Output device has changed, restarting Shairport Sync Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:14 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:14 volumio sudo[10867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:14 volumio sudo[10867]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:14 volumio sudo[10865]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:14 volumio systemd[1]: Stopping Music Player Daemon... Aug 30 07:46:15 volumio volumio[10756]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:15 volumio volumio[10756]: info: ___________ START PLUGINS ___________ Aug 30 07:46:15 volumio volumio[10756]: info: ControllerMpd::onStart: Initializing MPD Aug 30 07:46:15 volumio volumio[10756]: info: Creating MPD Configuration file Aug 30 07:46:15 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:15 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:15 volumio volumio[10756]: info: [1756507575059] CoreMusicLibrary::Adding element 미디어 서버 Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:15 volumio sudo[10891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:15 volumio sudo[10891]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:15 volumio sudo[10891]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:15 volumio sudo[10893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:15 volumio sudo[10893]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:15 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:15 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 30 07:46:15 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:15 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:15 volumio volumio[10756]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:15 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:15 volumio volumio[10756]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:15 volumio volumio[10756]: info: [1756507575157] CoreMusicLibrary::Adding element Last_100 Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:15 volumio volumio[10756]: info: [1756507575159] CoreMusicLibrary::Adding element Webradio Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:15 volumio volumio[10756]: info: Initializing BBC Radios Aug 30 07:46:15 volumio sudo[10899]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 07:46:15 volumio sudo[10899]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:15 volumio sudo[10899]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: Loading i18n strings for locale ko Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: FusionDsp - mixtype--------------------- Hardware Aug 30 07:46:15 volumio volumio[10756]: info: Volumio Calling Home Aug 30 07:46:15 volumio volumio[10756]: info: Preparing to generate the ALSA configuration file Aug 30 07:46:15 volumio volumio[10756]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:46:15 volumio volumio[10756]: info: Reading ALSA contributions from plugins. Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:15 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 07:46:15 volumio volumio[10756]: info: Received Get System Info Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:46:15 volumio volumio[10756]: info: Discovery: Getting this device information Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:15 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:15 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:15 volumio volumio[10756]: info: Listing playlists Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:15 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 07:46:15 volumio volumio[10756]: info: Received Get System Info Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:46:15 volumio volumio[10756]: info: Discovery: Getting this device information Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:15 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:15 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:15 volumio volumio[10756]: info: Listing playlists Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: MPD Permissions set Aug 30 07:46:15 volumio volumio[10756]: info: MPD Permissions set Aug 30 07:46:15 volumio volumio[10756]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 07:46:15 volumio volumio[10756]: info: Received Get System Info Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:46:15 volumio volumio[10756]: info: Discovery: Getting this device information Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:15 volumio volumio[10756]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 30 07:46:15 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 07:46:15 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:15 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:15 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:16 volumio mpd[10910]: Aug 30 07:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 07:46:16 volumio systemd[1]: Started Music Player Daemon. Aug 30 07:46:16 volumio sudo[10867]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:16 volumio sudo[10893]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:16 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:16 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:16 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:16 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:16 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:16 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:16 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:16 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:16 volumio sudo[10799]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:16 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:17 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: info: FusionDsp - Aug 30 07:46:18 volumio volumio[10756]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 30 07:46:18 volumio volumio[10756]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Aug 30 07:46:18 volumio volumio[10756]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 30 07:46:18 volumio volumio[10756]: info: camilladsp service started and running in background, instance 1 Aug 30 07:46:18 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:19 volumio volumio[10756]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 30 07:46:19 volumio volumio[10756]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 30 07:46:19 volumio volumio[10756]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 30 07:46:19 volumio volumio[10756]: info: FusionDsp loaded Aug 30 07:46:19 volumio volumio[10756]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:19 volumio sudo[10944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 30 07:46:19 volumio sudo[10944]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:19 volumio volumio[10756]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 30 07:46:19 volumio volumio[10756]: info: Adding Signal Path Element [object Object] Aug 30 07:46:19 volumio volumio[10756]: info: Adding fusiondspeq DSP Signal Path Element Aug 30 07:46:19 volumio volumio[10756]: info: FusionDsp - ---- installed callbackRead Aug 30 07:46:19 volumio volumio[10756]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:46:19 volumio sudo[10944]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:19 volumio volumio[10756]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 30 07:46:19 volumio volumio[10756]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 30 07:46:19 volumio volumio[10756]: at onErrorNT (internal/child_process.js:465:16) Aug 30 07:46:19 volumio volumio[10756]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 30 07:46:19 volumio volumio[10756]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 30 07:46:19 volumio volumio[10756]: at listOnTimeout (internal/timers.js:523:9) Aug 30 07:46:19 volumio volumio[10756]: at processTimers (internal/timers.js:497:7) { Aug 30 07:46:19 volumio volumio[10756]: errno: -2, Aug 30 07:46:19 volumio volumio[10756]: code: 'ENOENT', Aug 30 07:46:19 volumio volumio[10756]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 30 07:46:19 volumio volumio[10756]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 30 07:46:19 volumio volumio[10756]: spawnargs: [ Aug 30 07:46:19 volumio volumio[10756]: '-p', Aug 30 07:46:19 volumio volumio[10756]: 9876, Aug 30 07:46:19 volumio volumio[10756]: '-o', Aug 30 07:46:19 volumio volumio[10756]: '/tmp/camilladsp.log', Aug 30 07:46:19 volumio volumio[10756]: '-l', Aug 30 07:46:19 volumio volumio[10756]: 'warn', Aug 30 07:46:19 volumio volumio[10756]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 30 07:46:19 volumio volumio[10756]: ] Aug 30 07:46:19 volumio volumio[10756]: } Aug 30 07:46:19 volumio volumio[10756]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:46:19 volumio sudo[10955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 07:45 Aug 30 07:46:19 volumio sudo[10955]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:19 volumio sudo[10955]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:19 volumio volumio-remote-updater[593]: [2025-08-30 07:46:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 30 07:46:19 volumio volumio-remote-updater[593]: [2025-08-30 07:46:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 30 07:46:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 30 07:46:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 30 07:46:19 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:19 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:19 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 30 07:46:19 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 40. Aug 30 07:46:19 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:19 volumio systemd[1]: Stopped Volumio Backend Module. Aug 30 07:46:19 volumio systemd[1]: Started Volumio Backend Module. Aug 30 07:46:19 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:20 volumio volumio[10966]: info: ------------------------------------------- Aug 30 07:46:20 volumio volumio[10966]: info: ----- Volumio3 ---- Aug 30 07:46:20 volumio volumio[10966]: info: ------------------------------------------- Aug 30 07:46:20 volumio volumio[10966]: info: ----- System startup ---- Aug 30 07:46:20 volumio volumio[10966]: info: ------------------------------------------- Aug 30 07:46:21 volumio volumio[10966]: info: MYVOLUMIO Environment detected Aug 30 07:46:21 volumio volumio[10966]: info: Plugin folders cleanup Aug 30 07:46:21 volumio volumio[10966]: info: Scanning into folder /volumio/app/plugins/ Aug 30 07:46:21 volumio volumio[10966]: info: Scanning category audio_interface Aug 30 07:46:21 volumio volumio[10966]: info: Scanning category miscellanea Aug 30 07:46:21 volumio volumio[10966]: info: Scanning category music_service Aug 30 07:46:21 volumio volumio[10966]: info: Scanning category plugins.json Aug 30 07:46:21 volumio volumio[10966]: info: Scanning category system_controller Aug 30 07:46:21 volumio volumio[10966]: info: Scanning category user_interface Aug 30 07:46:21 volumio volumio[10966]: info: Scanning into folder /data/plugins/ Aug 30 07:46:21 volumio volumio[10966]: info: Scanning category audio_interface Aug 30 07:46:21 volumio volumio[10966]: info: Plugin folders cleanup completed Aug 30 07:46:21 volumio volumio[10966]: info: ------------------------------------------- Aug 30 07:46:21 volumio volumio[10966]: info: ----- Core plugins startup ---- Aug 30 07:46:21 volumio volumio[10966]: info: ------------------------------------------- Aug 30 07:46:21 volumio volumio[10966]: info: Loading plugins from folder /volumio/app/plugins/ Aug 30 07:46:21 volumio volumio[10966]: info: Adding plugin upnp to MyMusic Plugins Aug 30 07:46:21 volumio volumio[10966]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 30 07:46:21 volumio volumio[10966]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 30 07:46:21 volumio volumio[10966]: info: Loading plugins from folder /data/plugins/ Aug 30 07:46:21 volumio volumio[10966]: info: Loading plugin "system"... Aug 30 07:46:21 volumio volumio[10966]: info: Loading plugin "appearance"... Aug 30 07:46:21 volumio volumio[10966]: info: Loading plugin "network"... Aug 30 07:46:21 volumio volumio[10966]: info: Refreshing Cached IP Addresses Aug 30 07:46:21 volumio sudo[10992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 07:46:21 volumio sudo[10992]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:21 volumio sudo[10992]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:21 volumio sudo[10994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 07:46:21 volumio sudo[10994]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:22 volumio sudo[10994]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "services"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "alsa_controller"... Aug 30 07:46:22 volumio sudo[11004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 30 07:46:22 volumio sudo[11004]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:22 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "wizard"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "networkfs"... Aug 30 07:46:22 volumio volumio[10966]: info: Starting Udev Watcher for removable devices Aug 30 07:46:22 volumio volumio[10966]: info: Ignoring mount for partition: boot Aug 30 07:46:22 volumio volumio[10966]: info: Ignoring mount for partition: volumio Aug 30 07:46:22 volumio volumio[10966]: info: Ignoring mount for partition: volumio_data Aug 30 07:46:22 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "volumio_command_line_client"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "upnp"... Aug 30 07:46:22 volumio volumio[10966]: info: [1756507582126] Starting Upmpd Daemon Aug 30 07:46:22 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "my_music"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "mpd"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "upnp_browser"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "alarm-clock"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "airplay_emulation"... Aug 30 07:46:22 volumio volumio[10966]: info: Starting Shairport Sync Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "last_100"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "webradio"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "i2s_dacs"... Aug 30 07:46:22 volumio volumio[10966]: info: I2S DAC not set, start Auto-detection Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "volumiodiscovery"... Aug 30 07:46:22 volumio volumio[10966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:22 volumio volumio[10966]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:22 volumio volumio[10966]: *** WARNING *** For more information see Aug 30 07:46:22 volumio node[10966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:22 volumio volumio[10966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:22 volumio volumio[10966]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:22 volumio volumio[10966]: *** WARNING *** For more information see Aug 30 07:46:22 volumio node[10966]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:22 volumio node[10966]: *** WARNING *** For more information see Aug 30 07:46:22 volumio node[10966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:22 volumio node[10966]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:22 volumio node[10966]: *** WARNING *** For more information see Aug 30 07:46:22 volumio volumio[10966]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 07:46:22 volumio volumio[10966]: info: Discovery: Started advertising with name: Volumio Aug 30 07:46:22 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "outputs"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "albumart"... Aug 30 07:46:22 volumio volumio[10966]: info: Plugin example_plugin is not enabled Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "inputs"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "updater_comm"... Aug 30 07:46:22 volumio volumio[10966]: info: Plugin mpdemulation is not enabled Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "rest_api"... Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "websocket"... Aug 30 07:46:22 volumio volumio[10966]: info: Starting Socket.io Server version 2.3.0 Aug 30 07:46:22 volumio volumio[10966]: info: Loading plugin "fusiondsp"... Aug 30 07:46:22 volumio volumio[10966]: Forking 3 albumart workers Aug 30 07:46:23 volumio volumio[10966]: info: Applying required configuration parameters for plugin fusiondsp Aug 30 07:46:23 volumio volumio[10966]: info: Loading i18n strings for locale ko Aug 30 07:46:23 volumio volumio[10966]: Updating browse sources language Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::initPlayerControls Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: Express server listening on port 3000 Aug 30 07:46:23 volumio volumio[10966]: [Metrics] WebUI: 2s 908.45ms Aug 30 07:46:23 volumio volumio[10966]: info: CoreStateMachine::resetVolumioState Aug 30 07:46:23 volumio volumio[10966]: info: CoreStateMachine::getcurrentVolume Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: Starting albumart workers Aug 30 07:46:23 volumio volumio[10966]: info: Cannot read play queue from file Aug 30 07:46:23 volumio volumio[10966]: info: Volumio Network Manager: Network status updated: 2 Aug 30 07:46:23 volumio volumio[10966]: Starting albumart workers Aug 30 07:46:23 volumio volumio[10966]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Aug 30 07:46:23 volumio volumio[10966]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Aug 30 07:46:23 volumio volumio[10966]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Aug 30 07:46:23 volumio volumio[10966]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:46:23 volumio volumio[10966]: info: CoreStateMachine::pushState Aug 30 07:46:23 volumio volumio[10966]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:23 volumio volumio[10966]: info: CoreStateMachine::updateTrackBlock Aug 30 07:46:23 volumio volumio[10966]: info: CorePlayQueue::getTrackBlock Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:46:23 volumio volumio[10966]: Starting albumart workers Aug 30 07:46:23 volumio volumio[10966]: info: CoreStateMachine::setRepeat null single undefined Aug 30 07:46:23 volumio volumio[10966]: info: CoreStateMachine::pushState Aug 30 07:46:23 volumio volumio[10966]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:23 volumio volumio[10966]: info: CoreStateMachine::setRandom null Aug 30 07:46:23 volumio volumio[10966]: info: CoreStateMachine::pushState Aug 30 07:46:23 volumio volumio[10966]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:23 volumio volumio[10966]: info: Setting Device type: Raspberry PI Aug 30 07:46:23 volumio volumio[10966]: info: Completed loading Core Plugins Aug 30 07:46:23 volumio volumio[10966]: info: Preparing to generate the ALSA configuration file Aug 30 07:46:23 volumio volumio[10966]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:46:23 volumio volumio[10966]: info: Reading ALSA contributions from plugins. Aug 30 07:46:23 volumio volumio[10966]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:46:23 volumio volumio[10966]: info: CoreStateMachine::pushState Aug 30 07:46:23 volumio volumio[10966]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:23 volumio volumio[10966]: info: Asound.conf file unchanged, so no further update is needed Aug 30 07:46:23 volumio volumio[10966]: info: Output device has changed, restarting MPD Aug 30 07:46:23 volumio sudo[11062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:23 volumio sudo[11062]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:23 volumio volumio[10966]: info: Output device has changed, restarting Shairport Sync Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:23 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:23 volumio sudo[11062]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:24 volumio sudo[11064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:24 volumio sudo[11064]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:24 volumio volumio[10966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:24 volumio volumio[10966]: info: ___________ START PLUGINS ___________ Aug 30 07:46:24 volumio volumio[10966]: info: ControllerMpd::onStart: Initializing MPD Aug 30 07:46:24 volumio volumio[10966]: info: Creating MPD Configuration file Aug 30 07:46:24 volumio systemd[1]: Stopping Music Player Daemon... Aug 30 07:46:24 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:24 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:24 volumio sudo[11071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:24 volumio sudo[11071]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:24 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:24 volumio volumio[10966]: info: [1756507584101] CoreMusicLibrary::Adding element 미디어 서버 Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:24 volumio sudo[11071]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:24 volumio sudo[11084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:24 volumio sudo[11084]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:24 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 30 07:46:24 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:24 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:24 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:24 volumio volumio[10966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:24 volumio volumio[10966]: info: [1756507584256] CoreMusicLibrary::Adding element Last_100 Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:24 volumio sudo[11096]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:24 volumio volumio[10966]: info: [1756507584259] CoreMusicLibrary::Adding element Webradio Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:24 volumio sudo[11096]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:24 volumio volumio[10966]: info: Initializing BBC Radios Aug 30 07:46:24 volumio sudo[11096]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: Loading i18n strings for locale ko Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: FusionDsp - mixtype--------------------- Hardware Aug 30 07:46:24 volumio volumio[10966]: info: Volumio Calling Home Aug 30 07:46:24 volumio volumio[10966]: info: Preparing to generate the ALSA configuration file Aug 30 07:46:24 volumio volumio[10966]: info: Discovery: adding 6d849cfc-83e0-4e78-9cc3-7e8f7bad4661 Aug 30 07:46:24 volumio volumio[10966]: info: Discovery: Found device Volumio Aug 30 07:46:24 volumio volumio-remote-updater[593]: [2025-08-30 07:46:24] [connect] Successful connection Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:24 volumio volumio[10966]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:24 volumio volumio[10966]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:46:24 volumio volumio[10966]: info: Reading ALSA contributions from plugins. Aug 30 07:46:24 volumio volumio[10966]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Aug 30 07:46:24 volumio volumio[10966]: info: MPD Permissions set Aug 30 07:46:24 volumio volumio[10966]: info: MPD Permissions set Aug 30 07:46:24 volumio volumio-remote-updater[593]: [2025-08-30 07:46:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756507584 101 Aug 30 07:46:24 volumio volumio[10966]: 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: 5 Aug 30 07:46:24 volumio volumio[10966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 07:46:24 volumio volumio[10966]: info: Starting Shairport Sync Aug 30 07:46:24 volumio volumio[10966]: info: Starting Shairport Sync Aug 30 07:46:24 volumio volumio[10966]: info: Starting Shairport Sync Aug 30 07:46:24 volumio sudo[11132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:24 volumio sudo[11132]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:24 volumio sudo[11134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:24 volumio sudo[11134]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:24 volumio sudo[11137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:24 volumio volumio[10966]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:24 volumio sudo[11137]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 07:46:24 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 07:46:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:24 volumio sudo[11132]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:24 volumio sudo[11134]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:24 volumio sudo[11137]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:24 volumio volumio[10966]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:24 volumio volumio[10966]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 07:46:24 volumio volumio[10966]: info: Received Get System Info Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:46:24 volumio volumio[10966]: info: Discovery: Getting this device information Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:24 volumio volumio[10966]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:24 volumio volumio[10966]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:24 volumio volumio[10966]: info: Listing playlists Aug 30 07:46:24 volumio volumio[10966]: info: Asound.conf file unchanged, so no further update is needed Aug 30 07:46:24 volumio volumio[10966]: info: Output device has changed, restarting MPD Aug 30 07:46:24 volumio volumio[10966]: info: Output device has changed, restarting Shairport Sync Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:24 volumio sudo[11144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:24 volumio sudo[11144]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:24 volumio sudo[11144]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:24 volumio sudo[11146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:24 volumio sudo[11146]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:24 volumio volumio[10966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:24 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 30 07:46:24 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:24 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:24 volumio volumio[10966]: info: Shairport-Sync Started Aug 30 07:46:24 volumio volumio[10966]: Error adding Membership: Error: addMembership EINVAL Aug 30 07:46:24 volumio volumio[10966]: info: Shairport-Sync Started Aug 30 07:46:24 volumio volumio[10966]: info: Shairport-Sync Started Aug 30 07:46:24 volumio volumio[10966]: info: MPD Permissions set Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:24 volumio volumio[10966]: info: Starting Shairport Sync Aug 30 07:46:24 volumio sudo[11158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:24 volumio sudo[11158]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:24 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 07:46:25 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 07:46:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:25 volumio sudo[11158]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: Shairport-Sync Started Aug 30 07:46:25 volumio sudo[11152]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 07:46:25 volumio sudo[11152]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio sudo[11152]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:25 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:25 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:25 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:25 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:25 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:25 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:25 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:25 volumio mpd[11164]: Aug 30 07:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 07:46:25 volumio systemd[1]: Started Music Player Daemon. Aug 30 07:46:25 volumio sudo[11084]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:25 volumio sudo[11064]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:25 volumio sudo[11146]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:25 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:25 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:26 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:26 volumio sudo[11004]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:26 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:26 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:26 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:26 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:26 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:26 volumio volumio[10966]: info: FusionDsp - Aug 30 07:46:26 volumio volumio[10966]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 30 07:46:26 volumio volumio[10966]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Aug 30 07:46:26 volumio volumio[10966]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 30 07:46:26 volumio volumio[10966]: info: camilladsp service started and running in background, instance 1 Aug 30 07:46:26 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:26 volumio volumio[10966]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 30 07:46:26 volumio volumio[10966]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 30 07:46:26 volumio volumio[10966]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 30 07:46:26 volumio volumio[10966]: info: FusionDsp loaded Aug 30 07:46:26 volumio volumio[10966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:26 volumio sudo[11177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 30 07:46:26 volumio sudo[11177]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:26 volumio volumio[10966]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 30 07:46:26 volumio volumio[10966]: info: Adding Signal Path Element [object Object] Aug 30 07:46:26 volumio volumio[10966]: info: Adding fusiondspeq DSP Signal Path Element Aug 30 07:46:26 volumio volumio[10966]: info: FusionDsp - ---- installed callbackRead Aug 30 07:46:26 volumio volumio[10966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:46:26 volumio sudo[11177]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:26 volumio volumio[10966]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 30 07:46:26 volumio volumio[10966]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 30 07:46:26 volumio volumio[10966]: at onErrorNT (internal/child_process.js:465:16) Aug 30 07:46:26 volumio volumio[10966]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 30 07:46:26 volumio volumio[10966]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 30 07:46:26 volumio volumio[10966]: at listOnTimeout (internal/timers.js:523:9) Aug 30 07:46:26 volumio volumio[10966]: at processTimers (internal/timers.js:497:7) { Aug 30 07:46:26 volumio volumio[10966]: errno: -2, Aug 30 07:46:26 volumio volumio[10966]: code: 'ENOENT', Aug 30 07:46:26 volumio volumio[10966]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 30 07:46:26 volumio volumio[10966]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 30 07:46:26 volumio volumio[10966]: spawnargs: [ Aug 30 07:46:26 volumio volumio[10966]: '-p', Aug 30 07:46:26 volumio volumio[10966]: 9876, Aug 30 07:46:26 volumio volumio[10966]: '-o', Aug 30 07:46:26 volumio volumio[10966]: '/tmp/camilladsp.log', Aug 30 07:46:26 volumio volumio[10966]: '-l', Aug 30 07:46:26 volumio volumio[10966]: 'warn', Aug 30 07:46:26 volumio volumio[10966]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 30 07:46:26 volumio volumio[10966]: ] Aug 30 07:46:26 volumio volumio[10966]: } Aug 30 07:46:26 volumio volumio[10966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:46:27 volumio sudo[11189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 07:45 Aug 30 07:46:27 volumio sudo[11189]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:27 volumio sudo[11189]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:27 volumio volumio-remote-updater[593]: [2025-08-30 07:46:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 30 07:46:27 volumio volumio-remote-updater[593]: [2025-08-30 07:46:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 30 07:46:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 30 07:46:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 30 07:46:27 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:27 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:27 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 30 07:46:27 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 41. Aug 30 07:46:27 volumio systemd[1]: Stopped Volumio Backend Module. Aug 30 07:46:27 volumio systemd[1]: Started Volumio Backend Module. Aug 30 07:46:27 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:27 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:28 volumio volumio[11200]: info: ------------------------------------------- Aug 30 07:46:28 volumio volumio[11200]: info: ----- Volumio3 ---- Aug 30 07:46:28 volumio volumio[11200]: info: ------------------------------------------- Aug 30 07:46:28 volumio volumio[11200]: info: ----- System startup ---- Aug 30 07:46:28 volumio volumio[11200]: info: ------------------------------------------- Aug 30 07:46:29 volumio volumio[11200]: info: MYVOLUMIO Environment detected Aug 30 07:46:29 volumio volumio[11200]: info: Plugin folders cleanup Aug 30 07:46:29 volumio volumio[11200]: info: Scanning into folder /volumio/app/plugins/ Aug 30 07:46:29 volumio volumio[11200]: info: Scanning category audio_interface Aug 30 07:46:29 volumio volumio[11200]: info: Scanning category miscellanea Aug 30 07:46:29 volumio volumio[11200]: info: Scanning category music_service Aug 30 07:46:29 volumio volumio[11200]: info: Scanning category plugins.json Aug 30 07:46:29 volumio volumio[11200]: info: Scanning category system_controller Aug 30 07:46:29 volumio volumio[11200]: info: Scanning category user_interface Aug 30 07:46:29 volumio volumio[11200]: info: Scanning into folder /data/plugins/ Aug 30 07:46:29 volumio volumio[11200]: info: Scanning category audio_interface Aug 30 07:46:29 volumio volumio[11200]: info: Plugin folders cleanup completed Aug 30 07:46:29 volumio volumio[11200]: info: ------------------------------------------- Aug 30 07:46:29 volumio volumio[11200]: info: ----- Core plugins startup ---- Aug 30 07:46:29 volumio volumio[11200]: info: ------------------------------------------- Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugins from folder /volumio/app/plugins/ Aug 30 07:46:29 volumio volumio[11200]: info: Adding plugin upnp to MyMusic Plugins Aug 30 07:46:29 volumio volumio[11200]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 30 07:46:29 volumio volumio[11200]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugins from folder /data/plugins/ Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugin "system"... Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugin "appearance"... Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugin "network"... Aug 30 07:46:29 volumio volumio[11200]: info: Refreshing Cached IP Addresses Aug 30 07:46:29 volumio sudo[11227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 07:46:29 volumio sudo[11227]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:29 volumio sudo[11227]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:29 volumio sudo[11229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 07:46:29 volumio sudo[11229]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:29 volumio sudo[11229]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugin "services"... Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugin "alsa_controller"... Aug 30 07:46:29 volumio sudo[11239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 30 07:46:29 volumio sudo[11239]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:29 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugin "wizard"... Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugin "networkfs"... Aug 30 07:46:29 volumio volumio[11200]: info: Starting Udev Watcher for removable devices Aug 30 07:46:29 volumio volumio[11200]: info: Ignoring mount for partition: boot Aug 30 07:46:29 volumio volumio[11200]: info: Ignoring mount for partition: volumio Aug 30 07:46:29 volumio volumio[11200]: info: Ignoring mount for partition: volumio_data Aug 30 07:46:29 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugin "volumio_command_line_client"... Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugin "upnp"... Aug 30 07:46:29 volumio volumio[11200]: info: [1756507589937] Starting Upmpd Daemon Aug 30 07:46:29 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugin "my_music"... Aug 30 07:46:29 volumio volumio[11200]: info: Loading plugin "mpd"... Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "upnp_browser"... Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "alarm-clock"... Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "airplay_emulation"... Aug 30 07:46:30 volumio volumio[11200]: info: Starting Shairport Sync Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "last_100"... Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "webradio"... Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "i2s_dacs"... Aug 30 07:46:30 volumio volumio[11200]: info: I2S DAC not set, start Auto-detection Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "volumiodiscovery"... Aug 30 07:46:30 volumio volumio[11200]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:30 volumio volumio[11200]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:30 volumio node[11200]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:30 volumio volumio[11200]: *** WARNING *** For more information see Aug 30 07:46:30 volumio volumio[11200]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:30 volumio volumio[11200]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:30 volumio volumio[11200]: *** WARNING *** For more information see Aug 30 07:46:30 volumio node[11200]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:30 volumio node[11200]: *** WARNING *** For more information see Aug 30 07:46:30 volumio node[11200]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:30 volumio node[11200]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:30 volumio node[11200]: *** WARNING *** For more information see Aug 30 07:46:30 volumio volumio[11200]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 07:46:30 volumio volumio[11200]: info: Discovery: Started advertising with name: Volumio Aug 30 07:46:30 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "outputs"... Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "albumart"... Aug 30 07:46:30 volumio volumio[11200]: info: Plugin example_plugin is not enabled Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "inputs"... Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "updater_comm"... Aug 30 07:46:30 volumio volumio[11200]: info: Plugin mpdemulation is not enabled Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "rest_api"... Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "websocket"... Aug 30 07:46:30 volumio volumio[11200]: info: Starting Socket.io Server version 2.3.0 Aug 30 07:46:30 volumio volumio[11200]: info: Loading plugin "fusiondsp"... Aug 30 07:46:30 volumio volumio[11200]: Forking 3 albumart workers Aug 30 07:46:30 volumio volumio[11200]: info: Applying required configuration parameters for plugin fusiondsp Aug 30 07:46:31 volumio volumio[11200]: info: Loading i18n strings for locale ko Aug 30 07:46:31 volumio volumio[11200]: Updating browse sources language Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::initPlayerControls Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:31 volumio volumio[11200]: Express server listening on port 3000 Aug 30 07:46:31 volumio volumio[11200]: [Metrics] WebUI: 3s 4.95ms Aug 30 07:46:31 volumio volumio[11200]: info: CoreStateMachine::resetVolumioState Aug 30 07:46:31 volumio volumio[11200]: info: CoreStateMachine::getcurrentVolume Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:31 volumio volumio[11200]: info: Cannot read play queue from file Aug 30 07:46:31 volumio volumio[11200]: info: Volumio Network Manager: Network status updated: 2 Aug 30 07:46:31 volumio volumio[11200]: Starting albumart workers Aug 30 07:46:31 volumio volumio[11200]: Starting albumart workers Aug 30 07:46:31 volumio volumio[11200]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Aug 30 07:46:31 volumio volumio[11200]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Aug 30 07:46:31 volumio volumio[11200]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Aug 30 07:46:31 volumio volumio[11200]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:46:31 volumio volumio[11200]: info: CoreStateMachine::pushState Aug 30 07:46:31 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:31 volumio volumio[11200]: info: CoreStateMachine::updateTrackBlock Aug 30 07:46:31 volumio volumio[11200]: info: CorePlayQueue::getTrackBlock Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:46:31 volumio volumio[11200]: Starting albumart workers Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 07:46:31 volumio volumio[11200]: info: CoreStateMachine::setRepeat null single undefined Aug 30 07:46:31 volumio volumio[11200]: info: CoreStateMachine::pushState Aug 30 07:46:31 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:31 volumio volumio[11200]: info: CoreStateMachine::setRandom null Aug 30 07:46:31 volumio volumio[11200]: info: CoreStateMachine::pushState Aug 30 07:46:31 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:31 volumio volumio[11200]: info: Setting Device type: Raspberry PI Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:31 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 07:46:31 volumio volumio[11200]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 30 07:46:31 volumio volumio[11200]: info: Received Get System Version Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:31 volumio volumio[11200]: info: Received Get System Info Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:46:31 volumio volumio[11200]: info: Discovery: Getting this device information Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:31 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:31 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:31 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 07:46:31 volumio volumio[11200]: info: Received Get System Info Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:46:31 volumio volumio[11200]: info: Discovery: Getting this device information Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:31 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:31 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:31 volumio volumio[11200]: info: Listing playlists Aug 30 07:46:31 volumio volumio[11200]: info: Discovery: adding 6d849cfc-83e0-4e78-9cc3-7e8f7bad4661 Aug 30 07:46:31 volumio volumio[11200]: info: Discovery: Found device Volumio Aug 30 07:46:31 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:31 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:32 volumio volumio[11200]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:46:32 volumio volumio[11200]: info: CoreStateMachine::pushState Aug 30 07:46:32 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:32 volumio volumio[11200]: info: Completed loading Core Plugins Aug 30 07:46:32 volumio volumio[11200]: info: Preparing to generate the ALSA configuration file Aug 30 07:46:32 volumio volumio[11200]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:46:32 volumio volumio[11200]: info: Reading ALSA contributions from plugins. Aug 30 07:46:32 volumio volumio[11200]: info: Asound.conf file unchanged, so no further update is needed Aug 30 07:46:32 volumio volumio[11200]: info: Output device has changed, restarting MPD Aug 30 07:46:32 volumio sudo[11321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:32 volumio sudo[11321]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:32 volumio volumio[11200]: info: Output device has changed, restarting Shairport Sync Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:32 volumio sudo[11323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:32 volumio sudo[11323]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:32 volumio sudo[11321]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:32 volumio systemd[1]: Stopping Music Player Daemon... Aug 30 07:46:32 volumio volumio[11200]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:32 volumio volumio[11200]: info: ___________ START PLUGINS ___________ Aug 30 07:46:32 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:32 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:32 volumio volumio[11200]: info: ControllerMpd::onStart: Initializing MPD Aug 30 07:46:32 volumio volumio[11200]: info: Creating MPD Configuration file Aug 30 07:46:32 volumio sudo[11331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:32 volumio sudo[11331]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:32 volumio volumio-remote-updater[593]: [2025-08-30 07:46:32] [connect] Successful connection Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:32 volumio volumio[11200]: info: [1756507592181] CoreMusicLibrary::Adding element 미디어 서버 Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:32 volumio sudo[11331]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:32 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:32 volumio sudo[11333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:32 volumio sudo[11333]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:32 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 30 07:46:32 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:32 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:32 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:32 volumio volumio[11200]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:32 volumio volumio[11200]: info: [1756507592279] CoreMusicLibrary::Adding element Last_100 Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:32 volumio volumio[11200]: info: [1756507592282] CoreMusicLibrary::Adding element Webradio Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:32 volumio volumio[11200]: info: Initializing BBC Radios Aug 30 07:46:32 volumio sudo[11342]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 07:46:32 volumio sudo[11342]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:32 volumio sudo[11342]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: Loading i18n strings for locale ko Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: FusionDsp - mixtype--------------------- Hardware Aug 30 07:46:32 volumio volumio[11200]: info: Volumio Calling Home Aug 30 07:46:32 volumio volumio[11200]: info: Preparing to generate the ALSA configuration file Aug 30 07:46:32 volumio volumio[11200]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:46:32 volumio volumio[11200]: info: Reading ALSA contributions from plugins. Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:32 volumio volumio[11200]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Aug 30 07:46:32 volumio volumio[11200]: info: MPD Permissions set Aug 30 07:46:32 volumio volumio[11200]: info: MPD Permissions set Aug 30 07:46:32 volumio volumio-remote-updater[593]: [2025-08-30 07:46:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756507592 101 Aug 30 07:46:32 volumio volumio[11200]: 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: 5 Aug 30 07:46:32 volumio volumio[11200]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:32 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:32 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:32 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:32 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 07:46:33 volumio volumio[11200]: info: FusionDsp - undefined Aug 30 07:46:33 volumio volumio[11200]: info: FusionDsp - undefined Aug 30 07:46:33 volumio volumio[11200]: info: FusionDsp - undefined Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:33 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:33 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 07:46:33 volumio volumio[11200]: info: Received Get System Info Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:46:33 volumio volumio[11200]: info: Discovery: Getting this device information Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:33 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:33 volumio mpd[11354]: Aug 30 07:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 07:46:33 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:33 volumio volumio[11200]: info: Listing playlists Aug 30 07:46:33 volumio systemd[1]: Started Music Player Daemon. Aug 30 07:46:33 volumio sudo[11333]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:33 volumio sudo[11323]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:33 volumio volumio[11200]: info: Starting Shairport Sync Aug 30 07:46:33 volumio volumio[11200]: info: Starting Shairport Sync Aug 30 07:46:33 volumio sudo[11372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:33 volumio sudo[11372]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:33 volumio volumio[11200]: info: Starting Shairport Sync Aug 30 07:46:33 volumio sudo[11375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:33 volumio sudo[11375]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 07:46:33 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 07:46:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:33 volumio sudo[11378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:33 volumio sudo[11378]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:33 volumio volumio[11200]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:33 volumio volumio[11200]: info: Completed starting Core Plugins Aug 30 07:46:33 volumio volumio[11200]: info: ------------------------------------------- Aug 30 07:46:33 volumio volumio[11200]: info: ----- MyVolumio plugins startup ---- Aug 30 07:46:33 volumio volumio[11200]: info: ------------------------------------------- Aug 30 07:46:33 volumio volumio[11200]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 30 07:46:33 volumio volumio[11200]: info: Volumio called home Aug 30 07:46:33 volumio volumio[11200]: info: Asound.conf file unchanged, so no further update is needed Aug 30 07:46:33 volumio volumio[11200]: info: Output device has changed, restarting MPD Aug 30 07:46:33 volumio sudo[11372]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:33 volumio sudo[11384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:33 volumio sudo[11384]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 07:46:33 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 07:46:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:33 volumio volumio[11200]: info: Output device has changed, restarting Shairport Sync Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:33 volumio sudo[11384]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:33 volumio sudo[11388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:33 volumio sudo[11388]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:33 volumio sudo[11378]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:33 volumio sudo[11375]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:33 volumio systemd[1]: Stopping Music Player Daemon... Aug 30 07:46:33 volumio volumio[11200]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:33 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:33 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:33 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:33 volumio volumio[11200]: error: MPD error: Error: write EPIPE Aug 30 07:46:33 volumio volumio[11200]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Aug 30 07:46:33 volumio volumio[11200]: error: MPD error: The expression evaluated to a falsy value: Aug 30 07:46:33 volumio volumio[11200]: assert.ok(self.idling) Aug 30 07:46:33 volumio volumio[11200]: error: The expression evaluated to a falsy value: Aug 30 07:46:33 volumio volumio[11200]: assert.ok(self.idling) Aug 30 07:46:33 volumio volumio[11200]: error: MPD error: Error: write EPIPE Aug 30 07:46:33 volumio volumio[11200]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Aug 30 07:46:33 volumio volumio[11200]: info: MPD running with PID11354 Aug 30 07:46:33 volumio volumio[11200]: ,establishing connection Aug 30 07:46:33 volumio sudo[11396]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 07:46:33 volumio sudo[11396]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:33 volumio sudo[11396]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 07:46:33 volumio volumio[11200]: info: MPD Permissions set Aug 30 07:46:33 volumio volumio[11200]: info: Shairport-Sync Started Aug 30 07:46:33 volumio volumio[11200]: Error adding Membership: Error: addMembership EINVAL Aug 30 07:46:33 volumio volumio[11200]: info: Shairport-Sync Started Aug 30 07:46:33 volumio volumio[11200]: info: Shairport-Sync Started Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:33 volumio volumio[11200]: info: Starting Shairport Sync Aug 30 07:46:33 volumio sudo[11404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:33 volumio sudo[11404]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 07:46:33 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 07:46:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:33 volumio sudo[11404]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:33 volumio volumio[11200]: info: Shairport-Sync Started Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:33 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:33 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:33 volumio sudo[11239]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:34 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:34 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:34 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:34 volumio mpd[11398]: Aug 30 07:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 07:46:34 volumio systemd[1]: Started Music Player Daemon. Aug 30 07:46:34 volumio sudo[11388]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:34 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:34 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:34 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:34 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:34 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:34 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:34 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:35 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Aug 30 07:46:36 volumio volumio[11200]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 30 07:46:36 volumio volumio[11200]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Aug 30 07:46:36 volumio volumio[11200]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 30 07:46:36 volumio volumio[11200]: info: camilladsp service started and running in background, instance 1 Aug 30 07:46:36 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:36 volumio volumio[11200]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 30 07:46:36 volumio volumio[11200]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 30 07:46:36 volumio volumio[11200]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp loaded Aug 30 07:46:36 volumio volumio[11200]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:36 volumio sudo[11422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 30 07:46:36 volumio sudo[11422]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:36 volumio sudo[11422]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 30 07:46:36 volumio volumio[11200]: info: Adding Signal Path Element [object Object] Aug 30 07:46:36 volumio volumio[11200]: info: Adding fusiondspeq DSP Signal Path Element Aug 30 07:46:36 volumio volumio[11200]: info: FusionDsp - ---- installed callbackRead Aug 30 07:46:36 volumio volumio[11200]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:46:37 volumio volumio[11200]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 30 07:46:37 volumio volumio[11200]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 30 07:46:37 volumio volumio[11200]: at onErrorNT (internal/child_process.js:465:16) Aug 30 07:46:37 volumio volumio[11200]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 30 07:46:37 volumio volumio[11200]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 30 07:46:37 volumio volumio[11200]: at listOnTimeout (internal/timers.js:523:9) Aug 30 07:46:37 volumio volumio[11200]: at processTimers (internal/timers.js:497:7) { Aug 30 07:46:37 volumio volumio[11200]: errno: -2, Aug 30 07:46:37 volumio volumio[11200]: code: 'ENOENT', Aug 30 07:46:37 volumio volumio[11200]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 30 07:46:37 volumio volumio[11200]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 30 07:46:37 volumio volumio[11200]: spawnargs: [ Aug 30 07:46:37 volumio volumio[11200]: '-p', Aug 30 07:46:37 volumio volumio[11200]: 9876, Aug 30 07:46:37 volumio volumio[11200]: '-o', Aug 30 07:46:37 volumio volumio[11200]: '/tmp/camilladsp.log', Aug 30 07:46:37 volumio volumio[11200]: '-l', Aug 30 07:46:37 volumio volumio[11200]: 'warn', Aug 30 07:46:37 volumio volumio[11200]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 30 07:46:37 volumio volumio[11200]: ] Aug 30 07:46:37 volumio volumio[11200]: } Aug 30 07:46:37 volumio volumio[11200]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:46:37 volumio sudo[11433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 07:45 Aug 30 07:46:37 volumio sudo[11433]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:37 volumio sudo[11433]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:37 volumio volumio-remote-updater[593]: [2025-08-30 07:46:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 30 07:46:37 volumio volumio-remote-updater[593]: [2025-08-30 07:46:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 30 07:46:37 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 30 07:46:37 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 30 07:46:37 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:37 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 30 07:46:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 42. Aug 30 07:46:37 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:37 volumio systemd[1]: Stopped Volumio Backend Module. Aug 30 07:46:37 volumio systemd[1]: Started Volumio Backend Module. Aug 30 07:46:37 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:38 volumio volumio[11444]: info: ------------------------------------------- Aug 30 07:46:38 volumio volumio[11444]: info: ----- Volumio3 ---- Aug 30 07:46:38 volumio volumio[11444]: info: ------------------------------------------- Aug 30 07:46:38 volumio volumio[11444]: info: ----- System startup ---- Aug 30 07:46:38 volumio volumio[11444]: info: ------------------------------------------- Aug 30 07:46:39 volumio volumio[11444]: info: MYVOLUMIO Environment detected Aug 30 07:46:39 volumio volumio[11444]: info: Plugin folders cleanup Aug 30 07:46:39 volumio volumio[11444]: info: Scanning into folder /volumio/app/plugins/ Aug 30 07:46:39 volumio volumio[11444]: info: Scanning category audio_interface Aug 30 07:46:39 volumio volumio[11444]: info: Scanning category miscellanea Aug 30 07:46:39 volumio volumio[11444]: info: Scanning category music_service Aug 30 07:46:39 volumio volumio[11444]: info: Scanning category plugins.json Aug 30 07:46:39 volumio volumio[11444]: info: Scanning category system_controller Aug 30 07:46:39 volumio volumio[11444]: info: Scanning category user_interface Aug 30 07:46:39 volumio volumio[11444]: info: Scanning into folder /data/plugins/ Aug 30 07:46:39 volumio volumio[11444]: info: Scanning category audio_interface Aug 30 07:46:39 volumio volumio[11444]: info: Plugin folders cleanup completed Aug 30 07:46:39 volumio volumio[11444]: info: ------------------------------------------- Aug 30 07:46:39 volumio volumio[11444]: info: ----- Core plugins startup ---- Aug 30 07:46:39 volumio volumio[11444]: info: ------------------------------------------- Aug 30 07:46:39 volumio volumio[11444]: info: Loading plugins from folder /volumio/app/plugins/ Aug 30 07:46:39 volumio volumio[11444]: info: Adding plugin upnp to MyMusic Plugins Aug 30 07:46:39 volumio volumio[11444]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 30 07:46:39 volumio volumio[11444]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 30 07:46:39 volumio volumio[11444]: info: Loading plugins from folder /data/plugins/ Aug 30 07:46:39 volumio volumio[11444]: info: Loading plugin "system"... Aug 30 07:46:39 volumio volumio[11444]: info: Loading plugin "appearance"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "network"... Aug 30 07:46:40 volumio volumio[11444]: info: Refreshing Cached IP Addresses Aug 30 07:46:40 volumio sudo[11470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 07:46:40 volumio sudo[11470]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:40 volumio sudo[11470]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:40 volumio sudo[11472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 07:46:40 volumio sudo[11472]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:40 volumio sudo[11472]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "services"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "alsa_controller"... Aug 30 07:46:40 volumio sudo[11481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 30 07:46:40 volumio sudo[11481]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:40 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "wizard"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "networkfs"... Aug 30 07:46:40 volumio volumio[11444]: info: Starting Udev Watcher for removable devices Aug 30 07:46:40 volumio volumio[11444]: info: Ignoring mount for partition: boot Aug 30 07:46:40 volumio volumio[11444]: info: Ignoring mount for partition: volumio Aug 30 07:46:40 volumio volumio[11444]: info: Ignoring mount for partition: volumio_data Aug 30 07:46:40 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "volumio_command_line_client"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "upnp"... Aug 30 07:46:40 volumio volumio[11444]: info: [1756507600254] Starting Upmpd Daemon Aug 30 07:46:40 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "my_music"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "mpd"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "upnp_browser"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "alarm-clock"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "airplay_emulation"... Aug 30 07:46:40 volumio volumio[11444]: info: Starting Shairport Sync Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "last_100"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "webradio"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "i2s_dacs"... Aug 30 07:46:40 volumio volumio[11444]: info: I2S DAC not set, start Auto-detection Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "volumiodiscovery"... Aug 30 07:46:40 volumio volumio[11444]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:40 volumio volumio[11444]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:40 volumio node[11444]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:40 volumio volumio[11444]: *** WARNING *** For more information see Aug 30 07:46:40 volumio volumio[11444]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:40 volumio volumio[11444]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:40 volumio volumio[11444]: *** WARNING *** For more information see Aug 30 07:46:40 volumio node[11444]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:40 volumio node[11444]: *** WARNING *** For more information see Aug 30 07:46:40 volumio node[11444]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:40 volumio node[11444]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:40 volumio node[11444]: *** WARNING *** For more information see Aug 30 07:46:40 volumio volumio[11444]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 07:46:40 volumio volumio[11444]: info: Discovery: Started advertising with name: Volumio Aug 30 07:46:40 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "outputs"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "albumart"... Aug 30 07:46:40 volumio volumio[11444]: info: Plugin example_plugin is not enabled Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "inputs"... Aug 30 07:46:40 volumio volumio[11444]: info: Loading plugin "updater_comm"... Aug 30 07:46:41 volumio volumio[11444]: info: Plugin mpdemulation is not enabled Aug 30 07:46:41 volumio volumio[11444]: info: Loading plugin "rest_api"... Aug 30 07:46:41 volumio volumio[11444]: info: Loading plugin "websocket"... Aug 30 07:46:41 volumio volumio[11444]: info: Starting Socket.io Server version 2.3.0 Aug 30 07:46:41 volumio volumio[11444]: info: Loading plugin "fusiondsp"... Aug 30 07:46:41 volumio volumio[11444]: Forking 3 albumart workers Aug 30 07:46:41 volumio volumio[11444]: info: Applying required configuration parameters for plugin fusiondsp Aug 30 07:46:41 volumio volumio[11444]: info: Loading i18n strings for locale ko Aug 30 07:46:41 volumio volumio[11444]: Updating browse sources language Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::initPlayerControls Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:41 volumio volumio[11444]: Express server listening on port 3000 Aug 30 07:46:41 volumio volumio[11444]: [Metrics] WebUI: 3s 51.61ms Aug 30 07:46:41 volumio volumio[11444]: info: CoreStateMachine::resetVolumioState Aug 30 07:46:41 volumio volumio[11444]: info: CoreStateMachine::getcurrentVolume Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:46:41 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:41 volumio volumio[11444]: info: Cannot read play queue from file Aug 30 07:46:41 volumio volumio[11444]: info: Volumio Network Manager: Network status updated: 2 Aug 30 07:46:41 volumio volumio[11444]: Starting albumart workers Aug 30 07:46:41 volumio volumio[11444]: Starting albumart workers Aug 30 07:46:41 volumio volumio[11444]: Starting albumart workers Aug 30 07:46:41 volumio volumio[11444]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Aug 30 07:46:41 volumio volumio[11444]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Aug 30 07:46:42 volumio volumio[11444]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Aug 30 07:46:42 volumio volumio[11444]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:46:42 volumio volumio[11444]: info: CoreStateMachine::pushState Aug 30 07:46:42 volumio volumio[11444]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:42 volumio volumio[11444]: info: CoreStateMachine::updateTrackBlock Aug 30 07:46:42 volumio volumio[11444]: info: CorePlayQueue::getTrackBlock Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:46:42 volumio volumio[11444]: info: CoreStateMachine::setRepeat null single undefined Aug 30 07:46:42 volumio volumio[11444]: info: CoreStateMachine::pushState Aug 30 07:46:42 volumio volumio[11444]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:42 volumio volumio[11444]: info: CoreStateMachine::setRandom null Aug 30 07:46:42 volumio volumio[11444]: info: CoreStateMachine::pushState Aug 30 07:46:42 volumio volumio[11444]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:42 volumio volumio[11444]: info: Setting Device type: Raspberry PI Aug 30 07:46:42 volumio volumio[11444]: info: Completed loading Core Plugins Aug 30 07:46:42 volumio volumio[11444]: info: Preparing to generate the ALSA configuration file Aug 30 07:46:42 volumio volumio[11444]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:46:42 volumio volumio[11444]: info: Reading ALSA contributions from plugins. Aug 30 07:46:42 volumio volumio[11444]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:46:42 volumio volumio[11444]: info: CoreStateMachine::pushState Aug 30 07:46:42 volumio volumio[11444]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:42 volumio volumio[11444]: info: Asound.conf file unchanged, so no further update is needed Aug 30 07:46:42 volumio volumio[11444]: info: Output device has changed, restarting MPD Aug 30 07:46:42 volumio volumio[11444]: info: Output device has changed, restarting Shairport Sync Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:42 volumio sudo[11561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:42 volumio sudo[11561]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:42 volumio sudo[11566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:42 volumio sudo[11566]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:42 volumio sudo[11561]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:42 volumio systemd[1]: Stopping Music Player Daemon... Aug 30 07:46:42 volumio volumio[11444]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:42 volumio volumio[11444]: info: ___________ START PLUGINS ___________ Aug 30 07:46:42 volumio volumio[11444]: info: ControllerMpd::onStart: Initializing MPD Aug 30 07:46:42 volumio volumio[11444]: info: Creating MPD Configuration file Aug 30 07:46:42 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:42 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:42 volumio sudo[11573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:42 volumio sudo[11573]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:42 volumio volumio[11444]: info: [1756507602396] CoreMusicLibrary::Adding element 미디어 서버 Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:42 volumio sudo[11573]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:42 volumio sudo[11577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:42 volumio sudo[11577]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:42 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:42 volumio volumio[11444]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:42 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 30 07:46:42 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:42 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:42 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:42 volumio volumio-remote-updater[593]: [2025-08-30 07:46:42] [connect] Successful connection Aug 30 07:46:42 volumio volumio[11444]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:42 volumio volumio[11444]: info: [1756507602491] CoreMusicLibrary::Adding element Last_100 Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:42 volumio volumio[11444]: info: [1756507602493] CoreMusicLibrary::Adding element Webradio Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:42 volumio volumio[11444]: info: Initializing BBC Radios Aug 30 07:46:42 volumio sudo[11583]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 07:46:42 volumio sudo[11583]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:42 volumio sudo[11583]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: Loading i18n strings for locale ko Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: FusionDsp - mixtype--------------------- Hardware Aug 30 07:46:42 volumio volumio[11444]: info: Volumio Calling Home Aug 30 07:46:42 volumio volumio[11444]: info: Preparing to generate the ALSA configuration file Aug 30 07:46:42 volumio volumio[11444]: info: Discovery: adding 6d849cfc-83e0-4e78-9cc3-7e8f7bad4661 Aug 30 07:46:42 volumio volumio[11444]: info: Discovery: Found device Volumio Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:42 volumio volumio[11444]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:42 volumio volumio[11444]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:46:42 volumio volumio[11444]: info: Reading ALSA contributions from plugins. Aug 30 07:46:42 volumio volumio[11444]: info: MPD Permissions set Aug 30 07:46:42 volumio volumio[11444]: info: MPD Permissions set Aug 30 07:46:42 volumio volumio-remote-updater[593]: [2025-08-30 07:46:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756507602 101 Aug 30 07:46:42 volumio volumio[11444]: 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: 4 Aug 30 07:46:42 volumio volumio[11444]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:42 volumio volumio[11444]: info: Starting Shairport Sync Aug 30 07:46:42 volumio volumio[11444]: info: Starting Shairport Sync Aug 30 07:46:42 volumio sudo[11610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:42 volumio volumio[11444]: info: Starting Shairport Sync Aug 30 07:46:42 volumio sudo[11610]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:42 volumio sudo[11612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:42 volumio sudo[11612]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 07:46:42 volumio sudo[11615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:42 volumio sudo[11615]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:42 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 07:46:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:42 volumio volumio[11444]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:42 volumio volumio[11444]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:42 volumio volumio[11444]: info: Asound.conf file unchanged, so no further update is needed Aug 30 07:46:42 volumio volumio[11444]: info: Output device has changed, restarting MPD Aug 30 07:46:42 volumio volumio[11444]: info: Output device has changed, restarting Shairport Sync Aug 30 07:46:42 volumio sudo[11620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:42 volumio sudo[11620]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:43 volumio sudo[11620]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:43 volumio sudo[11610]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 07:46:43 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 30 07:46:43 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 07:46:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:43 volumio sudo[11622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:43 volumio sudo[11622]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:43 volumio sudo[11615]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:43 volumio sudo[11612]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:43 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 30 07:46:43 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:43 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:43 volumio volumio[11444]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:43 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:43 volumio volumio[11444]: info: MPD Permissions set Aug 30 07:46:43 volumio volumio[11444]: info: Shairport-Sync Started Aug 30 07:46:43 volumio volumio[11444]: Error adding Membership: Error: addMembership EINVAL Aug 30 07:46:43 volumio volumio[11444]: info: Shairport-Sync Started Aug 30 07:46:43 volumio volumio[11444]: info: Shairport-Sync Started Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:43 volumio volumio[11444]: info: Starting Shairport Sync Aug 30 07:46:43 volumio sudo[11630]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 07:46:43 volumio sudo[11630]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:43 volumio sudo[11630]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:43 volumio sudo[11637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:43 volumio sudo[11637]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 07:46:43 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 07:46:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:43 volumio sudo[11637]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:43 volumio volumio[11444]: info: Shairport-Sync Started Aug 30 07:46:43 volumio volumio[11444]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 07:46:43 volumio volumio[11444]: info: Volumio called home Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:43 volumio volumio[11444]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:43 volumio volumio[11444]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 07:46:43 volumio volumio[11444]: info: Received Get System Info Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:46:43 volumio volumio[11444]: info: Discovery: Getting this device information Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:43 volumio volumio[11444]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:43 volumio volumio[11444]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:43 volumio volumio[11444]: info: Listing playlists Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:43 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:43 volumio mpd[11639]: Aug 30 07:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 07:46:43 volumio systemd[1]: Started Music Player Daemon. Aug 30 07:46:43 volumio sudo[11577]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:43 volumio sudo[11622]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:43 volumio sudo[11566]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:43 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:44 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:44 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:44 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:44 volumio sudo[11481]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:44 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:44 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:44 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:44 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:44 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:44 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:45 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:46 volumio volumio[11444]: info: FusionDsp - Aug 30 07:46:47 volumio volumio[11444]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 30 07:46:47 volumio volumio[11444]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Aug 30 07:46:47 volumio volumio[11444]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 30 07:46:47 volumio volumio[11444]: info: camilladsp service started and running in background, instance 1 Aug 30 07:46:47 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:47 volumio volumio[11444]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 30 07:46:47 volumio volumio[11444]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 30 07:46:47 volumio volumio[11444]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 30 07:46:47 volumio volumio[11444]: info: FusionDsp loaded Aug 30 07:46:47 volumio volumio[11444]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:47 volumio sudo[11694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 30 07:46:47 volumio sudo[11694]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:47 volumio volumio[11444]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 30 07:46:47 volumio volumio[11444]: info: Adding Signal Path Element [object Object] Aug 30 07:46:47 volumio volumio[11444]: info: Adding fusiondspeq DSP Signal Path Element Aug 30 07:46:47 volumio volumio[11444]: info: FusionDsp - ---- installed callbackRead Aug 30 07:46:47 volumio volumio[11444]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:46:47 volumio sudo[11694]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:47 volumio volumio[11444]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 30 07:46:47 volumio volumio[11444]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 30 07:46:47 volumio volumio[11444]: at onErrorNT (internal/child_process.js:465:16) Aug 30 07:46:47 volumio volumio[11444]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 30 07:46:47 volumio volumio[11444]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 30 07:46:47 volumio volumio[11444]: at listOnTimeout (internal/timers.js:523:9) Aug 30 07:46:47 volumio volumio[11444]: at processTimers (internal/timers.js:497:7) { Aug 30 07:46:47 volumio volumio[11444]: errno: -2, Aug 30 07:46:47 volumio volumio[11444]: code: 'ENOENT', Aug 30 07:46:47 volumio volumio[11444]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 30 07:46:47 volumio volumio[11444]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 30 07:46:47 volumio volumio[11444]: spawnargs: [ Aug 30 07:46:47 volumio volumio[11444]: '-p', Aug 30 07:46:47 volumio volumio[11444]: 9876, Aug 30 07:46:47 volumio volumio[11444]: '-o', Aug 30 07:46:47 volumio volumio[11444]: '/tmp/camilladsp.log', Aug 30 07:46:47 volumio volumio[11444]: '-l', Aug 30 07:46:47 volumio volumio[11444]: 'warn', Aug 30 07:46:47 volumio volumio[11444]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 30 07:46:47 volumio volumio[11444]: ] Aug 30 07:46:47 volumio volumio[11444]: } Aug 30 07:46:47 volumio volumio[11444]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:46:47 volumio sudo[11705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 07:45 Aug 30 07:46:47 volumio sudo[11705]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:47 volumio sudo[11705]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:47 volumio volumio-remote-updater[593]: [2025-08-30 07:46:47] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 30 07:46:47 volumio volumio-remote-updater[593]: [2025-08-30 07:46:47] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 30 07:46:47 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 30 07:46:47 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 30 07:46:47 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:47 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:47 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 30 07:46:47 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 43. Aug 30 07:46:47 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:47 volumio systemd[1]: Stopped Volumio Backend Module. Aug 30 07:46:47 volumio systemd[1]: Started Volumio Backend Module. Aug 30 07:46:47 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:48 volumio volumio[11716]: info: ------------------------------------------- Aug 30 07:46:48 volumio volumio[11716]: info: ----- Volumio3 ---- Aug 30 07:46:48 volumio volumio[11716]: info: ------------------------------------------- Aug 30 07:46:48 volumio volumio[11716]: info: ----- System startup ---- Aug 30 07:46:48 volumio volumio[11716]: info: ------------------------------------------- Aug 30 07:46:49 volumio volumio[11716]: info: MYVOLUMIO Environment detected Aug 30 07:46:49 volumio volumio[11716]: info: Plugin folders cleanup Aug 30 07:46:49 volumio volumio[11716]: info: Scanning into folder /volumio/app/plugins/ Aug 30 07:46:49 volumio volumio[11716]: info: Scanning category audio_interface Aug 30 07:46:49 volumio volumio[11716]: info: Scanning category miscellanea Aug 30 07:46:49 volumio volumio[11716]: info: Scanning category music_service Aug 30 07:46:49 volumio volumio[11716]: info: Scanning category plugins.json Aug 30 07:46:49 volumio volumio[11716]: info: Scanning category system_controller Aug 30 07:46:49 volumio volumio[11716]: info: Scanning category user_interface Aug 30 07:46:49 volumio volumio[11716]: info: Scanning into folder /data/plugins/ Aug 30 07:46:49 volumio volumio[11716]: info: Scanning category audio_interface Aug 30 07:46:49 volumio volumio[11716]: info: Plugin folders cleanup completed Aug 30 07:46:49 volumio volumio[11716]: info: ------------------------------------------- Aug 30 07:46:49 volumio volumio[11716]: info: ----- Core plugins startup ---- Aug 30 07:46:49 volumio volumio[11716]: info: ------------------------------------------- Aug 30 07:46:49 volumio volumio[11716]: info: Loading plugins from folder /volumio/app/plugins/ Aug 30 07:46:49 volumio volumio[11716]: info: Adding plugin upnp to MyMusic Plugins Aug 30 07:46:49 volumio volumio[11716]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 30 07:46:49 volumio volumio[11716]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 30 07:46:49 volumio volumio[11716]: info: Loading plugins from folder /data/plugins/ Aug 30 07:46:49 volumio volumio[11716]: info: Loading plugin "system"... Aug 30 07:46:49 volumio volumio[11716]: info: Loading plugin "appearance"... Aug 30 07:46:49 volumio volumio[11716]: info: Loading plugin "network"... Aug 30 07:46:49 volumio volumio[11716]: info: Refreshing Cached IP Addresses Aug 30 07:46:49 volumio sudo[11742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 07:46:49 volumio sudo[11742]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:49 volumio sudo[11742]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:49 volumio sudo[11744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 07:46:49 volumio sudo[11744]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:49 volumio sudo[11744]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:49 volumio volumio[11716]: info: Loading plugin "services"... Aug 30 07:46:49 volumio volumio[11716]: info: Loading plugin "alsa_controller"... Aug 30 07:46:49 volumio sudo[11754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 30 07:46:49 volumio sudo[11754]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:50 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "wizard"... Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "networkfs"... Aug 30 07:46:50 volumio volumio[11716]: info: Starting Udev Watcher for removable devices Aug 30 07:46:50 volumio volumio[11716]: info: Ignoring mount for partition: boot Aug 30 07:46:50 volumio volumio[11716]: info: Ignoring mount for partition: volumio Aug 30 07:46:50 volumio volumio[11716]: info: Ignoring mount for partition: volumio_data Aug 30 07:46:50 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "volumio_command_line_client"... Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "upnp"... Aug 30 07:46:50 volumio volumio[11716]: info: [1756507610076] Starting Upmpd Daemon Aug 30 07:46:50 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "my_music"... Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "mpd"... Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "upnp_browser"... Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "alarm-clock"... Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "airplay_emulation"... Aug 30 07:46:50 volumio volumio[11716]: info: Starting Shairport Sync Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "last_100"... Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "webradio"... Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "i2s_dacs"... Aug 30 07:46:50 volumio volumio[11716]: info: I2S DAC not set, start Auto-detection Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "volumiodiscovery"... Aug 30 07:46:50 volumio volumio[11716]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:50 volumio volumio[11716]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:50 volumio volumio[11716]: *** WARNING *** For more information see Aug 30 07:46:50 volumio node[11716]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:50 volumio volumio[11716]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:50 volumio volumio[11716]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:50 volumio volumio[11716]: *** WARNING *** For more information see Aug 30 07:46:50 volumio node[11716]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:50 volumio node[11716]: *** WARNING *** For more information see Aug 30 07:46:50 volumio node[11716]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:50 volumio node[11716]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:50 volumio node[11716]: *** WARNING *** For more information see Aug 30 07:46:50 volumio volumio[11716]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 07:46:50 volumio volumio[11716]: info: Discovery: Started advertising with name: Volumio Aug 30 07:46:50 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "outputs"... Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "albumart"... Aug 30 07:46:50 volumio volumio[11716]: info: Plugin example_plugin is not enabled Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "inputs"... Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "updater_comm"... Aug 30 07:46:50 volumio volumio[11716]: info: Plugin mpdemulation is not enabled Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "rest_api"... Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "websocket"... Aug 30 07:46:50 volumio volumio[11716]: info: Starting Socket.io Server version 2.3.0 Aug 30 07:46:50 volumio volumio[11716]: info: Loading plugin "fusiondsp"... Aug 30 07:46:50 volumio volumio[11716]: Forking 3 albumart workers Aug 30 07:46:51 volumio volumio[11716]: info: Applying required configuration parameters for plugin fusiondsp Aug 30 07:46:51 volumio volumio[11716]: info: Loading i18n strings for locale ko Aug 30 07:46:51 volumio volumio[11716]: Updating browse sources language Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::initPlayerControls Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:51 volumio volumio[11716]: Express server listening on port 3000 Aug 30 07:46:51 volumio volumio[11716]: [Metrics] WebUI: 3s 30.64ms Aug 30 07:46:51 volumio volumio[11716]: Starting albumart workers Aug 30 07:46:51 volumio volumio[11716]: info: CoreStateMachine::resetVolumioState Aug 30 07:46:51 volumio volumio[11716]: info: CoreStateMachine::getcurrentVolume Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:51 volumio volumio[11716]: Starting albumart workers Aug 30 07:46:51 volumio volumio[11716]: info: Cannot read play queue from file Aug 30 07:46:51 volumio volumio[11716]: Starting albumart workers Aug 30 07:46:51 volumio volumio[11716]: info: Volumio Network Manager: Network status updated: 2 Aug 30 07:46:51 volumio volumio[11716]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Aug 30 07:46:51 volumio volumio[11716]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Aug 30 07:46:51 volumio volumio[11716]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Aug 30 07:46:51 volumio volumio[11716]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:46:51 volumio volumio[11716]: info: CoreStateMachine::pushState Aug 30 07:46:51 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:51 volumio volumio[11716]: info: CoreStateMachine::updateTrackBlock Aug 30 07:46:51 volumio volumio[11716]: info: CorePlayQueue::getTrackBlock Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:46:51 volumio volumio[11716]: info: CoreStateMachine::setRepeat null single undefined Aug 30 07:46:51 volumio volumio[11716]: info: CoreStateMachine::pushState Aug 30 07:46:51 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:51 volumio volumio[11716]: info: CoreStateMachine::setRandom null Aug 30 07:46:51 volumio volumio[11716]: info: CoreStateMachine::pushState Aug 30 07:46:51 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:51 volumio volumio[11716]: info: Setting Device type: Raspberry PI Aug 30 07:46:51 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 07:46:52 volumio volumio[11716]: info: Completed loading Core Plugins Aug 30 07:46:52 volumio volumio[11716]: info: Preparing to generate the ALSA configuration file Aug 30 07:46:52 volumio volumio[11716]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:46:52 volumio volumio[11716]: info: Reading ALSA contributions from plugins. Aug 30 07:46:52 volumio volumio[11716]: info: Asound.conf file unchanged, so no further update is needed Aug 30 07:46:52 volumio volumio[11716]: info: Output device has changed, restarting MPD Aug 30 07:46:52 volumio sudo[11820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:52 volumio sudo[11820]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:52 volumio sudo[11820]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:52 volumio volumio[11716]: info: Output device has changed, restarting Shairport Sync Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:52 volumio sudo[11829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:52 volumio sudo[11829]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:52 volumio systemd[1]: Stopping Music Player Daemon... Aug 30 07:46:52 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:52 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:52 volumio volumio[11716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:52 volumio volumio[11716]: info: ___________ START PLUGINS ___________ Aug 30 07:46:52 volumio volumio[11716]: info: ControllerMpd::onStart: Initializing MPD Aug 30 07:46:52 volumio volumio[11716]: info: Creating MPD Configuration file Aug 30 07:46:52 volumio sudo[11846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:52 volumio sudo[11846]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:52 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:52 volumio sudo[11846]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:52 volumio volumio[11716]: info: [1756507612221] CoreMusicLibrary::Adding element 미디어 서버 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:52 volumio sudo[11848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:52 volumio sudo[11848]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:52 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 30 07:46:52 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:52 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:52 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:52 volumio volumio[11716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:52 volumio volumio[11716]: info: [1756507612312] CoreMusicLibrary::Adding element Last_100 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:46:52 volumio volumio[11716]: info: [1756507612315] CoreMusicLibrary::Adding element Webradio Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:52 volumio sudo[11852]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 07:46:52 volumio sudo[11852]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:52 volumio volumio[11716]: info: Initializing BBC Radios Aug 30 07:46:52 volumio sudo[11852]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: Loading i18n strings for locale ko Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: FusionDsp - mixtype--------------------- Hardware Aug 30 07:46:52 volumio volumio[11716]: info: Volumio Calling Home Aug 30 07:46:52 volumio volumio[11716]: info: Preparing to generate the ALSA configuration file Aug 30 07:46:52 volumio volumio[11716]: info: Discovery: adding 6d849cfc-83e0-4e78-9cc3-7e8f7bad4661 Aug 30 07:46:52 volumio volumio[11716]: info: Discovery: Found device Volumio Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:52 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:52 volumio volumio[11716]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:46:52 volumio volumio[11716]: info: Reading ALSA contributions from plugins. Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:52 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:52 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 07:46:52 volumio volumio[11716]: info: Received Get System Info Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:46:52 volumio volumio[11716]: info: Discovery: Getting this device information Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:52 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:52 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:52 volumio volumio[11716]: info: Listing playlists Aug 30 07:46:52 volumio volumio[11716]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Aug 30 07:46:52 volumio volumio[11716]: info: MPD Permissions set Aug 30 07:46:52 volumio volumio[11716]: info: MPD Permissions set Aug 30 07:46:52 volumio volumio[11716]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:46:52 volumio volumio[11716]: info: CoreStateMachine::pushState Aug 30 07:46:52 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:52 volumio volumio-remote-updater[593]: [2025-08-30 07:46:52] [connect] Successful connection Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioPushState Aug 30 07:46:52 volumio volumio[11716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 30 07:46:52 volumio volumio-remote-updater[593]: [2025-08-30 07:46:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756507612 101 Aug 30 07:46:52 volumio volumio[11716]: 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: 6 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:52 volumio volumio[11716]: info: Starting Shairport Sync Aug 30 07:46:52 volumio volumio[11716]: info: Starting Shairport Sync Aug 30 07:46:52 volumio sudo[11882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:52 volumio volumio[11716]: info: Starting Shairport Sync Aug 30 07:46:52 volumio sudo[11882]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:52 volumio sudo[11884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:52 volumio sudo[11884]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:52 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 07:46:52 volumio sudo[11888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:52 volumio sudo[11888]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:52 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 07:46:52 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:52 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:52 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:52 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 07:46:52 volumio volumio[11716]: info: Received Get System Info Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:46:52 volumio volumio[11716]: info: Discovery: Getting this device information Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:52 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:52 volumio volumio[11716]: info: CoreCommandRouter::volumioGetState Aug 30 07:46:52 volumio volumio[11716]: info: CorePlayQueue::getTrack 0 Aug 30 07:46:52 volumio volumio[11716]: info: Listing playlists Aug 30 07:46:52 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:52 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:52 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 07:46:52 volumio sudo[11882]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:52 volumio sudo[11884]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:52 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 30 07:46:52 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 07:46:52 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:52 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:52 volumio sudo[11888]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:52 volumio volumio[11716]: info: Asound.conf file unchanged, so no further update is needed Aug 30 07:46:52 volumio volumio[11716]: info: Output device has changed, restarting MPD Aug 30 07:46:53 volumio volumio[11716]: info: Output device has changed, restarting Shairport Sync Aug 30 07:46:53 volumio sudo[11895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:53 volumio sudo[11895]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:53 volumio sudo[11895]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:53 volumio sudo[11897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:46:53 volumio sudo[11897]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:53 volumio volumio[11716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:46:53 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 30 07:46:53 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:46:53 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:46:53 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:53 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:46:53 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:53 volumio sudo[11903]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 07:46:53 volumio sudo[11903]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:53 volumio sudo[11903]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:53 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:53 volumio volumio[11716]: info: FusionDsp - undefined Aug 30 07:46:53 volumio volumio[11716]: info: FusionDsp - undefined Aug 30 07:46:53 volumio volumio[11716]: info: FusionDsp - undefined Aug 30 07:46:53 volumio volumio[11716]: info: FusionDsp - undefined Aug 30 07:46:53 volumio volumio[11716]: info: MPD Permissions set Aug 30 07:46:53 volumio volumio[11716]: info: Volumio called home Aug 30 07:46:53 volumio volumio[11716]: info: Shairport-Sync Started Aug 30 07:46:53 volumio volumio[11716]: Error adding Membership: Error: addMembership EINVAL Aug 30 07:46:53 volumio volumio[11716]: info: Shairport-Sync Started Aug 30 07:46:53 volumio volumio[11716]: info: Shairport-Sync Started Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:53 volumio volumio[11716]: info: Starting Shairport Sync Aug 30 07:46:53 volumio sudo[11913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 07:46:53 volumio sudo[11913]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 07:46:53 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 30 07:46:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 07:46:53 volumio sudo[11913]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:53 volumio volumio[11716]: info: Shairport-Sync Started Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:46:53 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:54 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:54 volumio mpd[11907]: Aug 30 07:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 07:46:54 volumio systemd[1]: Started Music Player Daemon. Aug 30 07:46:54 volumio sudo[11897]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:54 volumio sudo[11848]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:54 volumio sudo[11829]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:54 volumio sudo[11754]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:54 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:54 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:54 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:54 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:54 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:54 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:54 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:54 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:54 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:54 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:55 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:55 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:55 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:55 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:55 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:55 volumio volumio[11716]: info: FusionDsp - Aug 30 07:46:55 volumio volumio[11716]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 30 07:46:55 volumio volumio[11716]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Aug 30 07:46:55 volumio volumio[11716]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 30 07:46:55 volumio volumio[11716]: info: camilladsp service started and running in background, instance 1 Aug 30 07:46:55 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:46:55 volumio volumio[11716]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 30 07:46:55 volumio volumio[11716]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 30 07:46:55 volumio volumio[11716]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 30 07:46:55 volumio volumio[11716]: info: FusionDsp loaded Aug 30 07:46:55 volumio volumio[11716]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:46:55 volumio sudo[11932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 30 07:46:55 volumio sudo[11932]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:55 volumio volumio[11716]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 30 07:46:55 volumio volumio[11716]: info: Adding Signal Path Element [object Object] Aug 30 07:46:55 volumio volumio[11716]: info: Adding fusiondspeq DSP Signal Path Element Aug 30 07:46:55 volumio volumio[11716]: info: FusionDsp - ---- installed callbackRead Aug 30 07:46:55 volumio volumio[11716]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:46:55 volumio sudo[11932]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:55 volumio volumio[11716]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 30 07:46:55 volumio volumio[11716]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 30 07:46:55 volumio volumio[11716]: at onErrorNT (internal/child_process.js:465:16) Aug 30 07:46:55 volumio volumio[11716]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 30 07:46:55 volumio volumio[11716]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 30 07:46:55 volumio volumio[11716]: at listOnTimeout (internal/timers.js:523:9) Aug 30 07:46:55 volumio volumio[11716]: at processTimers (internal/timers.js:497:7) { Aug 30 07:46:55 volumio volumio[11716]: errno: -2, Aug 30 07:46:55 volumio volumio[11716]: code: 'ENOENT', Aug 30 07:46:55 volumio volumio[11716]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 30 07:46:55 volumio volumio[11716]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 30 07:46:55 volumio volumio[11716]: spawnargs: [ Aug 30 07:46:55 volumio volumio[11716]: '-p', Aug 30 07:46:55 volumio volumio[11716]: 9876, Aug 30 07:46:55 volumio volumio[11716]: '-o', Aug 30 07:46:55 volumio volumio[11716]: '/tmp/camilladsp.log', Aug 30 07:46:55 volumio volumio[11716]: '-l', Aug 30 07:46:55 volumio volumio[11716]: 'warn', Aug 30 07:46:55 volumio volumio[11716]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 30 07:46:55 volumio volumio[11716]: ] Aug 30 07:46:55 volumio volumio[11716]: } Aug 30 07:46:55 volumio volumio[11716]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:46:55 volumio sudo[11943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 07:45 Aug 30 07:46:55 volumio sudo[11943]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:56 volumio sudo[11943]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:56 volumio volumio-remote-updater[593]: [2025-08-30 07:46:56] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 30 07:46:56 volumio volumio-remote-updater[593]: [2025-08-30 07:46:56] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 30 07:46:56 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 30 07:46:56 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 30 07:46:56 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:56 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:56 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 30 07:46:56 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 44. Aug 30 07:46:56 volumio systemd[1]: Stopped Volumio Backend Module. Aug 30 07:46:56 volumio systemd[1]: Started Volumio Backend Module. Aug 30 07:46:56 volumio systemd[1]: Started dynamicswap service. Aug 30 07:46:56 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 30 07:46:57 volumio volumio[11954]: info: ------------------------------------------- Aug 30 07:46:57 volumio volumio[11954]: info: ----- Volumio3 ---- Aug 30 07:46:57 volumio volumio[11954]: info: ------------------------------------------- Aug 30 07:46:57 volumio volumio[11954]: info: ----- System startup ---- Aug 30 07:46:57 volumio volumio[11954]: info: ------------------------------------------- Aug 30 07:46:57 volumio volumio[11954]: info: MYVOLUMIO Environment detected Aug 30 07:46:57 volumio volumio[11954]: info: Plugin folders cleanup Aug 30 07:46:57 volumio volumio[11954]: info: Scanning into folder /volumio/app/plugins/ Aug 30 07:46:57 volumio volumio[11954]: info: Scanning category audio_interface Aug 30 07:46:57 volumio volumio[11954]: info: Scanning category miscellanea Aug 30 07:46:57 volumio volumio[11954]: info: Scanning category music_service Aug 30 07:46:57 volumio volumio[11954]: info: Scanning category plugins.json Aug 30 07:46:57 volumio volumio[11954]: info: Scanning category system_controller Aug 30 07:46:57 volumio volumio[11954]: info: Scanning category user_interface Aug 30 07:46:57 volumio volumio[11954]: info: Scanning into folder /data/plugins/ Aug 30 07:46:57 volumio volumio[11954]: info: Scanning category audio_interface Aug 30 07:46:57 volumio volumio[11954]: info: Plugin folders cleanup completed Aug 30 07:46:57 volumio volumio[11954]: info: ------------------------------------------- Aug 30 07:46:57 volumio volumio[11954]: info: ----- Core plugins startup ---- Aug 30 07:46:57 volumio volumio[11954]: info: ------------------------------------------- Aug 30 07:46:57 volumio volumio[11954]: info: Loading plugins from folder /volumio/app/plugins/ Aug 30 07:46:57 volumio volumio[11954]: info: Adding plugin upnp to MyMusic Plugins Aug 30 07:46:57 volumio volumio[11954]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 30 07:46:57 volumio volumio[11954]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 30 07:46:57 volumio volumio[11954]: info: Loading plugins from folder /data/plugins/ Aug 30 07:46:57 volumio volumio[11954]: info: Loading plugin "system"... Aug 30 07:46:57 volumio volumio[11954]: info: Loading plugin "appearance"... Aug 30 07:46:58 volumio volumio[11954]: info: Loading plugin "network"... Aug 30 07:46:58 volumio volumio[11954]: info: Refreshing Cached IP Addresses Aug 30 07:46:58 volumio sudo[11981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 07:46:58 volumio sudo[11981]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:58 volumio sudo[11981]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:58 volumio sudo[11983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 07:46:58 volumio sudo[11983]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:58 volumio sudo[11983]: pam_unix(sudo:session): session closed for user root Aug 30 07:46:58 volumio volumio[11954]: info: Loading plugin "services"... Aug 30 07:46:58 volumio volumio[11954]: info: Loading plugin "alsa_controller"... Aug 30 07:46:58 volumio sudo[11996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 30 07:46:58 volumio sudo[11996]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:46:58 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:46:58 volumio volumio[11954]: info: Loading plugin "wizard"... Aug 30 07:46:58 volumio volumio[11954]: info: Loading plugin "networkfs"... Aug 30 07:46:58 volumio volumio[11954]: info: Starting Udev Watcher for removable devices Aug 30 07:46:58 volumio volumio[11954]: info: Ignoring mount for partition: boot Aug 30 07:46:58 volumio volumio[11954]: info: Ignoring mount for partition: volumio Aug 30 07:46:58 volumio volumio[11954]: info: Ignoring mount for partition: volumio_data Aug 30 07:46:58 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:58 volumio volumio[11954]: info: Loading plugin "volumio_command_line_client"... Aug 30 07:46:58 volumio volumio[11954]: info: Loading plugin "upnp"... Aug 30 07:46:58 volumio volumio[11954]: info: [1756507618642] Starting Upmpd Daemon Aug 30 07:46:58 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:58 volumio volumio[11954]: info: Loading plugin "my_music"... Aug 30 07:46:58 volumio volumio[11954]: info: Loading plugin "mpd"... Aug 30 07:46:58 volumio volumio[11954]: info: Loading plugin "upnp_browser"... Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "alarm-clock"... Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "airplay_emulation"... Aug 30 07:46:59 volumio volumio[11954]: info: Starting Shairport Sync Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "last_100"... Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "webradio"... Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "i2s_dacs"... Aug 30 07:46:59 volumio volumio[11954]: info: I2S DAC not set, start Auto-detection Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "volumiodiscovery"... Aug 30 07:46:59 volumio volumio[11954]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:59 volumio volumio[11954]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:59 volumio volumio[11954]: *** WARNING *** For more information see Aug 30 07:46:59 volumio node[11954]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:59 volumio volumio[11954]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:59 volumio volumio[11954]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:59 volumio volumio[11954]: *** WARNING *** For more information see Aug 30 07:46:59 volumio node[11954]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:59 volumio node[11954]: *** WARNING *** For more information see Aug 30 07:46:59 volumio node[11954]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 07:46:59 volumio node[11954]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 07:46:59 volumio node[11954]: *** WARNING *** For more information see Aug 30 07:46:59 volumio volumio[11954]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 07:46:59 volumio volumio[11954]: info: Discovery: Started advertising with name: Volumio Aug 30 07:46:59 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "outputs"... Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "albumart"... Aug 30 07:46:59 volumio volumio[11954]: info: Plugin example_plugin is not enabled Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "inputs"... Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "updater_comm"... Aug 30 07:46:59 volumio volumio[11954]: info: Plugin mpdemulation is not enabled Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "rest_api"... Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "websocket"... Aug 30 07:46:59 volumio volumio[11954]: info: Starting Socket.io Server version 2.3.0 Aug 30 07:46:59 volumio volumio[11954]: info: Loading plugin "fusiondsp"... Aug 30 07:46:59 volumio volumio[11954]: Forking 3 albumart workers Aug 30 07:46:59 volumio volumio[11954]: info: Applying required configuration parameters for plugin fusiondsp Aug 30 07:46:59 volumio volumio[11954]: info: Loading i18n strings for locale ko Aug 30 07:46:59 volumio volumio[11954]: Updating browse sources language Aug 30 07:46:59 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::initPlayerControls Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: Express server listening on port 3000 Aug 30 07:47:00 volumio volumio[11954]: [Metrics] WebUI: 3s 81.76ms Aug 30 07:47:00 volumio volumio[11954]: info: CoreStateMachine::resetVolumioState Aug 30 07:47:00 volumio volumio[11954]: info: CoreStateMachine::getcurrentVolume Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:47:00 volumio volumio[11954]: Starting albumart workers Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: Cannot read play queue from file Aug 30 07:47:00 volumio volumio[11954]: info: Volumio Network Manager: Network status updated: 2 Aug 30 07:47:00 volumio volumio[11954]: Starting albumart workers Aug 30 07:47:00 volumio volumio[11954]: Starting albumart workers Aug 30 07:47:00 volumio volumio[11954]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Aug 30 07:47:00 volumio volumio[11954]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Aug 30 07:47:00 volumio volumio[11954]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Aug 30 07:47:00 volumio volumio[11954]: info: CoreStateMachine::setRepeat null single undefined Aug 30 07:47:00 volumio volumio[11954]: info: CoreStateMachine::pushState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioPushState Aug 30 07:47:00 volumio volumio[11954]: info: CoreStateMachine::setRandom null Aug 30 07:47:00 volumio volumio[11954]: info: CoreStateMachine::pushState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioPushState Aug 30 07:47:00 volumio volumio[11954]: info: Setting Device type: Raspberry PI Aug 30 07:47:00 volumio volumio[11954]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 07:47:00 volumio volumio[11954]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:47:00 volumio volumio[11954]: info: CoreStateMachine::pushState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioPushState Aug 30 07:47:00 volumio volumio[11954]: info: CoreStateMachine::updateTrackBlock Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrackBlock Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 07:47:00 volumio volumio[11954]: info: Discovery: adding 6d849cfc-83e0-4e78-9cc3-7e8f7bad4661 Aug 30 07:47:00 volumio volumio[11954]: info: Discovery: Found device Volumio Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioGetState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioGetState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioGetState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 07:47:00 volumio volumio[11954]: info: Received Get System Info Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:47:00 volumio volumio[11954]: info: Discovery: Getting this device information Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioGetState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioGetState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: Listing playlists Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 07:47:00 volumio volumio[11954]: info: VolumeController:: Volume=0 Mute =false Aug 30 07:47:00 volumio volumio[11954]: info: CoreStateMachine::pushState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioPushState Aug 30 07:47:00 volumio volumio[11954]: info: Completed loading Core Plugins Aug 30 07:47:00 volumio volumio[11954]: info: Preparing to generate the ALSA configuration file Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioGetState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioGetState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 07:47:00 volumio volumio[11954]: info: Received Get System Info Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 07:47:00 volumio volumio[11954]: info: Discovery: Getting this device information Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioGetState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioGetState Aug 30 07:47:00 volumio volumio[11954]: info: CorePlayQueue::getTrack 0 Aug 30 07:47:00 volumio volumio[11954]: info: Listing playlists Aug 30 07:47:00 volumio volumio[11954]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:47:00 volumio volumio[11954]: info: Reading ALSA contributions from plugins. Aug 30 07:47:00 volumio volumio[11954]: info: Asound.conf file unchanged, so no further update is needed Aug 30 07:47:00 volumio volumio[11954]: info: Output device has changed, restarting MPD Aug 30 07:47:00 volumio sudo[12075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:47:00 volumio sudo[12075]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:47:00 volumio volumio[11954]: info: Output device has changed, restarting Shairport Sync Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:47:00 volumio sudo[12075]: pam_unix(sudo:session): session closed for user root Aug 30 07:47:00 volumio sudo[12077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:47:00 volumio sudo[12077]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:47:00 volumio systemd[1]: Stopping Music Player Daemon... Aug 30 07:47:00 volumio volumio[11954]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:47:00 volumio volumio[11954]: info: ___________ START PLUGINS ___________ Aug 30 07:47:00 volumio volumio[11954]: info: ControllerMpd::onStart: Initializing MPD Aug 30 07:47:00 volumio volumio[11954]: info: Creating MPD Configuration file Aug 30 07:47:00 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:47:00 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:47:00 volumio sudo[12085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 07:47:00 volumio sudo[12085]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:47:00 volumio volumio[11954]: info: [1756507620878] CoreMusicLibrary::Adding element 미디어 서버 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:47:00 volumio sudo[12085]: pam_unix(sudo:session): session closed for user root Aug 30 07:47:00 volumio sudo[12087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 07:47:00 volumio sudo[12087]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:47:00 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:47:00 volumio volumio[11954]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:47:00 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 30 07:47:00 volumio systemd[1]: mpd.service: Succeeded. Aug 30 07:47:00 volumio systemd[1]: Stopped Music Player Daemon. Aug 30 07:47:00 volumio systemd[1]: Starting Music Player Daemon... Aug 30 07:47:00 volumio volumio[11954]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:47:00 volumio volumio[11954]: info: [1756507620969] CoreMusicLibrary::Adding element Last_100 Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 07:47:00 volumio volumio[11954]: info: [1756507620972] CoreMusicLibrary::Adding element Webradio Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 07:47:00 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:47:00 volumio volumio[11954]: info: Initializing BBC Radios Aug 30 07:47:01 volumio sudo[12094]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 07:47:01 volumio sudo[12094]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 07:47:01 volumio sudo[12094]: pam_unix(sudo:session): session closed for user root Aug 30 07:47:01 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 07:47:01 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 07:47:01 volumio volumio[11954]: info: Loading i18n strings for locale ko Aug 30 07:47:01 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 30 07:47:01 volumio volumio[11954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 07:47:01 volumio volumio[11954]: info: FusionDsp - mixtype--------------------- Hardware Aug 30 07:47:01 volumio volumio[11954]: info: Volumio Calling Home Aug 30 07:47:01 volumio volumio-remote-updater[593]: [2025-08-30 07:47:01] [connect] Successful connection Aug 30 07:47:01 volumio volumio[11954]: info: Preparing to generate the ALSA configuration file Aug 30 07:47:01 volumio volumio[11954]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 30 07:47:01 volumio volumio[11954]: info: Reading ALSA contributions from plugins. Aug 30 07:47:01 volumio volumio[11954]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:47:01 volumio volumio[11954]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:47:01 volumio volumio[11954]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:47:01 volumio volumio[11954]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:47:01 volumio volumio[11954]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 07:47:01 volumio volumio[11954]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:47:01 volumio volumio[11954]: TypeError: Cannot read property 'off' of undefined Aug 30 07:47:01 volumio volumio[11954]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Aug 30 07:47:01 volumio volumio[11954]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 30 07:47:01 volumio volumio[11954]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Aug 30 07:47:01 volumio volumio[11954]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Aug 30 07:47:01 volumio volumio[11954]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Aug 30 07:47:01 volumio volumio[11954]: at Socket.emit (events.js:315:20) Aug 30 07:47:01 volumio volumio[11954]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 30 07:47:01 volumio volumio[11954]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 30 07:47:01 volumio volumio[11954]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 07:47:01 volumio sudo[12121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 07:46 Aug 30 07:47:01 volumio sudo[12121]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"