-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Thu 2025-05-22 17:17:18 BST. -- May 22 17:16:00 volumiopi volumio[5049]: info: Loading plugin "network"... May 22 17:16:00 volumiopi volumio[5049]: info: Refreshing Cached IP Addresses May 22 17:16:00 volumiopi sudo[5079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:16:00 volumiopi sudo[5079]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:00 volumiopi sudo[5079]: pam_unix(sudo:session): session closed for user root May 22 17:16:00 volumiopi sudo[5081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:16:00 volumiopi sudo[5081]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:00 volumiopi sudo[5081]: pam_unix(sudo:session): session closed for user root May 22 17:16:00 volumiopi volumio[5049]: info: Loading plugin "services"... May 22 17:16:00 volumiopi volumio[5049]: info: Loading plugin "alsa_controller"... May 22 17:16:00 volumiopi sudo[5090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 17:16:00 volumiopi sudo[5090]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:00 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:00 volumiopi volumio[5049]: info: Loading plugin "wizard"... May 22 17:16:00 volumiopi volumio[5049]: info: Loading plugin "networkfs"... May 22 17:16:00 volumiopi volumio[5049]: info: Cannot mount NAS Synology at system boot, trial number 1 ,retrying in 5 seconds May 22 17:16:00 volumiopi volumio[5049]: info: Starting Udev Watcher for removable devices May 22 17:16:00 volumiopi volumio[5049]: info: Ignoring mount for partition: boot May 22 17:16:00 volumiopi volumio[5049]: info: Ignoring mount for partition: volumio May 22 17:16:00 volumiopi volumio[5049]: info: Ignoring mount for partition: volumio_data May 22 17:16:00 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:00 volumiopi volumio[5049]: info: Loading plugin "volumio_command_line_client"... May 22 17:16:00 volumiopi volumio[5049]: info: Loading plugin "upnp"... May 22 17:16:00 volumiopi volumio[5049]: info: [1747930560805] Starting Upmpd Daemon May 22 17:16:00 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:00 volumiopi volumio[5049]: info: Loading plugin "my_music"... May 22 17:16:00 volumiopi volumio[5049]: info: Loading plugin "mpd"... May 22 17:16:01 volumiopi volumio[5049]: info: Loading plugin "upnp_browser"... May 22 17:16:01 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:01] [connect] Successful connection May 22 17:16:01 volumiopi volumio[5049]: info: Loading plugin "alarm-clock"... May 22 17:16:01 volumiopi sudo[5090]: pam_unix(sudo:session): session closed for user root May 22 17:16:01 volumiopi volumio[5049]: info: Loading plugin "airplay_emulation"... May 22 17:16:01 volumiopi volumio[5049]: info: Starting Shairport Sync May 22 17:16:01 volumiopi volumio[5049]: info: Loading plugin "last_100"... May 22 17:16:01 volumiopi volumio[5049]: info: Loading plugin "webradio"... May 22 17:16:01 volumiopi volumio[5049]: info: Loading plugin "i2s_dacs"... May 22 17:16:01 volumiopi volumio[5049]: info: I2S DAC not set, start Auto-detection May 22 17:16:01 volumiopi volumio[5049]: info: Loading plugin "volumiodiscovery"... May 22 17:16:01 volumiopi volumio[5049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:01 volumiopi volumio[5049]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:01 volumiopi volumio[5049]: *** WARNING *** For more information see May 22 17:16:01 volumiopi node[5049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:01 volumiopi volumio[5049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:01 volumiopi volumio[5049]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:01 volumiopi volumio[5049]: *** WARNING *** For more information see May 22 17:16:01 volumiopi node[5049]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:01 volumiopi node[5049]: *** WARNING *** For more information see May 22 17:16:01 volumiopi node[5049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:01 volumiopi node[5049]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:01 volumiopi node[5049]: *** WARNING *** For more information see May 22 17:16:01 volumiopi volumio[5049]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 17:16:01 volumiopi volumio[5049]: info: Discovery: Started advertising with name: VolumioPi May 22 17:16:01 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:01 volumiopi volumio[5049]: info: Loading plugin "autostart"... May 22 17:16:02 volumiopi volumio[5049]: info: Applying required configuration parameters for plugin autostart May 22 17:16:02 volumiopi volumio[5049]: info: AutoStart - onVolumioStart - read config.json May 22 17:16:02 volumiopi volumio[5049]: info: Loading plugin "outputs"... May 22 17:16:02 volumiopi volumio[5049]: info: Loading plugin "albumart"... May 22 17:16:02 volumiopi volumio[5049]: info: Plugin example_plugin is not enabled May 22 17:16:02 volumiopi volumio[5049]: info: Loading plugin "inputs"... May 22 17:16:02 volumiopi volumio[5049]: info: Loading plugin "updater_comm"... May 22 17:16:02 volumiopi volumio[5049]: info: Plugin mpdemulation is not enabled May 22 17:16:02 volumiopi volumio[5049]: info: Loading plugin "rest_api"... May 22 17:16:02 volumiopi volumio[5049]: info: Loading plugin "websocket"... May 22 17:16:02 volumiopi volumio[5049]: info: Starting Socket.io Server version 2.3.0 May 22 17:16:02 volumiopi volumio[5049]: info: Loading plugin "fusiondsp"... May 22 17:16:02 volumiopi volumio[5049]: Forking 3 albumart workers May 22 17:16:02 volumiopi volumio[5049]: info: Applying required configuration parameters for plugin fusiondsp May 22 17:16:02 volumiopi volumio[5049]: info: Loading i18n strings for locale pl May 22 17:16:02 volumiopi volumio[5049]: Updating browse sources language May 22 17:16:02 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::initPlayerControls May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:03 volumiopi volumio[5049]: Express server listening on port 3000 May 22 17:16:03 volumiopi volumio[5049]: [Metrics] WebUI: 5s 171.39ms May 22 17:16:03 volumiopi volumio[5049]: info: CoreStateMachine::resetVolumioState May 22 17:16:03 volumiopi volumio[5049]: info: CoreStateMachine::getcurrentVolume May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:03 volumiopi volumio[5049]: info: Volumio Network Manager: Network status updated: 2 May 22 17:16:03 volumiopi volumio[5049]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 22 17:16:03 volumiopi volumio[5049]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 22 17:16:03 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747930561 101 May 22 17:16:03 volumiopi volumio[5049]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 22 17:16:03 volumiopi volumio[5049]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 22 17:16:03 volumiopi volumio[5049]: info: Reloading queue from file May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 22 17:16:03 volumiopi volumio[5049]: info: VolumeController:: Volume=0 Mute =false May 22 17:16:03 volumiopi volumio[5049]: info: CoreStateMachine::pushState May 22 17:16:03 volumiopi volumio[5049]: info: CorePlayQueue::getTrack 0 May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::volumioPushState May 22 17:16:03 volumiopi volumio[5049]: info: CoreStateMachine::updateTrackBlock May 22 17:16:03 volumiopi volumio[5049]: info: CorePlayQueue::getTrackBlock May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:16:03 volumiopi volumio[5049]: Starting albumart workers May 22 17:16:03 volumiopi volumio[5049]: info: CoreStateMachine::setRepeat null single undefined May 22 17:16:03 volumiopi volumio[5049]: info: CoreStateMachine::pushState May 22 17:16:03 volumiopi volumio[5049]: info: CorePlayQueue::getTrack 0 May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::volumioPushState May 22 17:16:03 volumiopi volumio[5049]: info: CoreStateMachine::setRandom null May 22 17:16:03 volumiopi volumio[5049]: info: CoreStateMachine::pushState May 22 17:16:03 volumiopi volumio[5049]: info: CorePlayQueue::getTrack 0 May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::volumioPushState May 22 17:16:03 volumiopi volumio[5049]: info: Setting Device type: Raspberry PI May 22 17:16:03 volumiopi volumio[5049]: Starting albumart workers May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::volumioGetState May 22 17:16:03 volumiopi volumio[5049]: info: CorePlayQueue::getTrack 0 May 22 17:16:03 volumiopi volumio[5049]: Starting albumart workers May 22 17:16:03 volumiopi volumio[5049]: info: Discovery: adding 12ef70b5-2e29-4466-ab71-dc1c1aea59d9 May 22 17:16:03 volumiopi volumio[5049]: info: Discovery: Found device Volumio May 22 17:16:03 volumiopi volumio[5049]: info: Discovery: Connecting to remote: 192.168.0.7 May 22 17:16:03 volumiopi volumio[5049]: info: VolumeController:: Volume=0 Mute =false May 22 17:16:03 volumiopi volumio[5049]: info: CoreStateMachine::pushState May 22 17:16:03 volumiopi volumio[5049]: info: CorePlayQueue::getTrack 0 May 22 17:16:03 volumiopi volumio[5049]: info: CoreCommandRouter::volumioPushState May 22 17:16:04 volumiopi volumio[5049]: info: Discovery: Connected to remote: 192.168.0.7 May 22 17:16:04 volumiopi volumio[5049]: info: Discovery: adding 8fb1e1c8-fc9d-4a44-9915-ace891a4c314 May 22 17:16:04 volumiopi volumio[5049]: info: Discovery: Found device VolumioPi May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::volumioGetState May 22 17:16:04 volumiopi volumio[5049]: info: CorePlayQueue::getTrack 0 May 22 17:16:04 volumiopi volumio[5049]: info: Completed loading Core Plugins May 22 17:16:04 volumiopi volumio[5049]: info: Preparing to generate the ALSA configuration file May 22 17:16:04 volumiopi volumio[5049]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:16:04 volumiopi volumio[5049]: info: Reading ALSA contributions from plugins. May 22 17:16:04 volumiopi volumio[5049]: info: Asound.conf file unchanged, so no further update is needed May 22 17:16:04 volumiopi volumio[5049]: info: Output device has changed, restarting MPD May 22 17:16:04 volumiopi volumio[5049]: info: Output device has changed, restarting Shairport Sync May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:04 volumiopi sudo[5150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:04 volumiopi sudo[5152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:04 volumiopi sudo[5152]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:04 volumiopi sudo[5150]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:04 volumiopi sudo[5150]: pam_unix(sudo:session): session closed for user root May 22 17:16:04 volumiopi systemd[1]: Stopping Music Player Daemon... May 22 17:16:04 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:04 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:04 volumiopi volumio[5049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:04 volumiopi volumio[5049]: info: ___________ START PLUGINS ___________ May 22 17:16:04 volumiopi volumio[5049]: info: ControllerMpd::onStart: Initializing MPD May 22 17:16:04 volumiopi volumio[5049]: info: Creating MPD Configuration file May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:04 volumiopi volumio[5049]: info: [1747930564515] CoreMusicLibrary::Adding element Serwery Mediów May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:04 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:04 volumiopi sudo[5161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:04 volumiopi sudo[5161]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:04 volumiopi sudo[5162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:04 volumiopi sudo[5162]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:04 volumiopi sudo[5161]: pam_unix(sudo:session): session closed for user root May 22 17:16:04 volumiopi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 17:16:04 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:04 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:04 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:04 volumiopi volumio[5049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:04 volumiopi volumio[5049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:04 volumiopi volumio[5049]: info: [1747930564767] CoreMusicLibrary::Adding element Last_100 May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:04 volumiopi volumio[5049]: info: [1747930564774] CoreMusicLibrary::Adding element Webradio May 22 17:16:04 volumiopi sudo[5173]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:16:04 volumiopi sudo[5173]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:04 volumiopi sudo[5173]: pam_unix(sudo:session): session closed for user root May 22 17:16:04 volumiopi volumio[5049]: info: Initializing BBC Radios May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:04 volumiopi volumio[5049]: info: AutoStart - onStart May 22 17:16:04 volumiopi volumio[5049]: info: Loading i18n strings for locale pl May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 22 17:16:04 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:04 volumiopi volumio[5049]: info: FusionDsp - mixtype--------------------- Hardware May 22 17:16:04 volumiopi volumio[5049]: info: Volumio Calling Home May 22 17:16:04 volumiopi volumio[5049]: info: Preparing to generate the ALSA configuration file May 22 17:16:05 volumiopi volumio[5049]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:16:05 volumiopi volumio[5049]: info: Reading ALSA contributions from plugins. May 22 17:16:05 volumiopi volumio[5049]: info: MPD Permissions set May 22 17:16:05 volumiopi volumio[5049]: info: MPD Permissions set May 22 17:16:05 volumiopi volumio[5049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: Volumio called home May 22 17:16:05 volumiopi volumio[5049]: info: Starting Shairport Sync May 22 17:16:05 volumiopi volumio[5049]: info: Starting Shairport Sync May 22 17:16:05 volumiopi volumio[5049]: info: Starting Shairport Sync May 22 17:16:05 volumiopi sudo[5220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:05 volumiopi sudo[5220]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:05 volumiopi sudo[5222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:05 volumiopi sudo[5222]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:05 volumiopi sudo[5225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:05 volumiopi sudo[5225]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::volumioGetState May 22 17:16:05 volumiopi volumio[5049]: info: CorePlayQueue::getTrack 0 May 22 17:16:05 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:05 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:05 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:05 volumiopi volumio[5049]: info: Asound.conf file unchanged, so no further update is needed May 22 17:16:05 volumiopi volumio[5049]: info: Output device has changed, restarting MPD May 22 17:16:05 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:05 volumiopi sudo[5220]: pam_unix(sudo:session): session closed for user root May 22 17:16:05 volumiopi sudo[5222]: pam_unix(sudo:session): session closed for user root May 22 17:16:05 volumiopi volumio[5049]: info: Output device has changed, restarting Shairport Sync May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:05 volumiopi sudo[5225]: pam_unix(sudo:session): session closed for user root May 22 17:16:05 volumiopi sudo[5230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:05 volumiopi sudo[5230]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:05 volumiopi sudo[5232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:05 volumiopi sudo[5232]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:05 volumiopi sudo[5230]: pam_unix(sudo:session): session closed for user root May 22 17:16:05 volumiopi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 22 17:16:05 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:05 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:05 volumiopi volumio[5049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:05 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:05 volumiopi sudo[5242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:16:05 volumiopi sudo[5242]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:05 volumiopi sudo[5242]: pam_unix(sudo:session): session closed for user root May 22 17:16:05 volumiopi sudo[5244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:16:05 volumiopi sudo[5244]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:05 volumiopi sudo[5244]: pam_unix(sudo:session): session closed for user root May 22 17:16:05 volumiopi volumio[5049]: info: Cannot mount NAS Synology at system boot, trial number 2 ,retrying in 5 seconds May 22 17:16:05 volumiopi volumio[5049]: info: MPD Permissions set May 22 17:16:05 volumiopi volumio[5049]: info: Shairport-Sync Started May 22 17:16:05 volumiopi volumio[5049]: Error adding Membership: Error: addMembership EINVAL May 22 17:16:05 volumiopi volumio[5049]: info: Shairport-Sync Started May 22 17:16:05 volumiopi volumio[5049]: info: Shairport-Sync Started May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::volumioGetState May 22 17:16:05 volumiopi volumio[5049]: info: CorePlayQueue::getTrack 0 May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:05 volumiopi volumio[5049]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 22 17:16:05 volumiopi sudo[5240]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:16:05 volumiopi sudo[5240]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:05 volumiopi sudo[5240]: pam_unix(sudo:session): session closed for user root May 22 17:16:05 volumiopi volumio[5049]: info: Listing playlists May 22 17:16:05 volumiopi volumio[5049]: info: Listing playlists May 22 17:16:05 volumiopi volumio[5049]: info: Starting Shairport Sync May 22 17:16:05 volumiopi sudo[5254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:05 volumiopi sudo[5254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:05 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:16:06 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:06 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:06 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:06 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:06 volumiopi sudo[5254]: pam_unix(sudo:session): session closed for user root May 22 17:16:06 volumiopi volumio[5049]: info: Shairport-Sync Started May 22 17:16:06 volumiopi sudo[5260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:16:06 volumiopi sudo[5260]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:06 volumiopi sudo[5260]: pam_unix(sudo:session): session closed for user root May 22 17:16:06 volumiopi sudo[5262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:16:06 volumiopi sudo[5262]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:06 volumiopi sudo[5262]: pam_unix(sudo:session): session closed for user root May 22 17:16:06 volumiopi volumio[5049]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::volumioGetVisibleSources May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::volumioGetState May 22 17:16:06 volumiopi volumio[5049]: info: CorePlayQueue::getTrack 0 May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 17:16:06 volumiopi volumio[5049]: info: Received Get System Info May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:16:06 volumiopi volumio[5049]: info: Discovery: Getting this device information May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::volumioGetState May 22 17:16:06 volumiopi volumio[5049]: info: CorePlayQueue::getTrack 0 May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::volumioGetState May 22 17:16:06 volumiopi volumio[5049]: info: CorePlayQueue::getTrack 0 May 22 17:16:06 volumiopi volumio[5049]: info: Listing playlists May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 17:16:06 volumiopi volumio[5049]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:16:06 volumiopi volumio[5049]: info: FusionDsp - May 22 17:16:07 volumiopi volumio[5049]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 22 17:16:07 volumiopi volumio[5049]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 22 17:16:07 volumiopi volumio[5049]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 22 17:16:07 volumiopi volumio[5049]: info: camilladsp service started and running in background, instance 1 May 22 17:16:07 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:07 volumiopi volumio[5049]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 22 17:16:07 volumiopi volumio[5049]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 22 17:16:07 volumiopi volumio[5049]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 22 17:16:07 volumiopi volumio[5049]: info: FusionDsp loaded May 22 17:16:07 volumiopi volumio[5049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:16:07 volumiopi sudo[5275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 22 17:16:07 volumiopi sudo[5275]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:07 volumiopi volumio[5049]: info: FusionDsp - Reporting Fusion DSP Enabled May 22 17:16:07 volumiopi volumio[5049]: info: Adding Signal Path Element [object Object] May 22 17:16:07 volumiopi volumio[5049]: info: Adding fusiondspeq DSP Signal Path Element May 22 17:16:07 volumiopi volumio[5049]: info: FusionDsp - ---- installed callbackRead May 22 17:16:07 volumiopi volumio[5049]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:16:07 volumiopi sudo[5275]: pam_unix(sudo:session): session closed for user root May 22 17:16:07 volumiopi volumio[5049]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 22 17:16:07 volumiopi volumio[5049]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 22 17:16:07 volumiopi volumio[5049]: at onErrorNT (internal/child_process.js:465:16) May 22 17:16:07 volumiopi volumio[5049]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 22 17:16:07 volumiopi volumio[5049]: at runNextTicks (internal/process/task_queues.js:62:3) May 22 17:16:07 volumiopi volumio[5049]: at listOnTimeout (internal/timers.js:523:9) May 22 17:16:07 volumiopi volumio[5049]: at processTimers (internal/timers.js:497:7) { May 22 17:16:07 volumiopi volumio[5049]: errno: -2, May 22 17:16:07 volumiopi volumio[5049]: code: 'ENOENT', May 22 17:16:07 volumiopi volumio[5049]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 22 17:16:07 volumiopi volumio[5049]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 22 17:16:07 volumiopi volumio[5049]: spawnargs: [ May 22 17:16:07 volumiopi volumio[5049]: '-p', May 22 17:16:07 volumiopi volumio[5049]: 9876, May 22 17:16:07 volumiopi volumio[5049]: '-o', May 22 17:16:07 volumiopi volumio[5049]: '/tmp/camilladsp.log', May 22 17:16:07 volumiopi volumio[5049]: '-l', May 22 17:16:07 volumiopi volumio[5049]: 'warn', May 22 17:16:07 volumiopi volumio[5049]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 22 17:16:07 volumiopi volumio[5049]: ] May 22 17:16:07 volumiopi volumio[5049]: } May 22 17:16:07 volumiopi volumio[5049]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:16:07 volumiopi mpd[5252]: May 22 17:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 17:16:07 volumiopi systemd[1]: Started Music Player Daemon. May 22 17:16:07 volumiopi sudo[5152]: pam_unix(sudo:session): session closed for user root May 22 17:16:07 volumiopi sudo[5232]: pam_unix(sudo:session): session closed for user root May 22 17:16:07 volumiopi sudo[5162]: pam_unix(sudo:session): session closed for user root May 22 17:16:07 volumiopi sudo[5288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 17:15 May 22 17:16:07 volumiopi sudo[5288]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:08 volumiopi sudo[5288]: pam_unix(sudo:session): session closed for user root May 22 17:16:08 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 17:16:08 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 17:16:08 volumiopi systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 17:16:08 volumiopi systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 17:16:08 volumiopi systemd[1]: Started dynamicswap service. May 22 17:16:08 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:16:08 volumiopi systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 17:16:08 volumiopi systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18. May 22 17:16:08 volumiopi systemd[1]: Started dynamicswap service. May 22 17:16:08 volumiopi systemd[1]: Stopped Volumio Backend Module. May 22 17:16:08 volumiopi systemd[1]: Started Volumio Backend Module. May 22 17:16:08 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:16:10 volumiopi volumio[5299]: info: ------------------------------------------- May 22 17:16:10 volumiopi volumio[5299]: info: ----- Volumio3 ---- May 22 17:16:10 volumiopi volumio[5299]: info: ------------------------------------------- May 22 17:16:10 volumiopi volumio[5299]: info: ----- System startup ---- May 22 17:16:10 volumiopi volumio[5299]: info: ------------------------------------------- May 22 17:16:10 volumiopi volumio[5299]: info: MYVOLUMIO Environment detected May 22 17:16:10 volumiopi volumio[5299]: info: Plugin folders cleanup May 22 17:16:10 volumiopi volumio[5299]: info: Scanning into folder /volumio/app/plugins/ May 22 17:16:10 volumiopi volumio[5299]: info: Scanning category audio_interface May 22 17:16:10 volumiopi volumio[5299]: info: Scanning category miscellanea May 22 17:16:10 volumiopi volumio[5299]: info: Scanning category music_service May 22 17:16:10 volumiopi volumio[5299]: info: Scanning category plugins.json May 22 17:16:10 volumiopi volumio[5299]: info: Scanning category system_controller May 22 17:16:10 volumiopi volumio[5299]: info: Scanning category user_interface May 22 17:16:10 volumiopi volumio[5299]: info: Scanning into folder /data/plugins/ May 22 17:16:10 volumiopi volumio[5299]: info: Scanning category audio_interface May 22 17:16:10 volumiopi volumio[5299]: info: Scanning category system_controller May 22 17:16:10 volumiopi volumio[5299]: info: Plugin folders cleanup completed May 22 17:16:10 volumiopi volumio[5299]: info: ------------------------------------------- May 22 17:16:10 volumiopi volumio[5299]: info: ----- Core plugins startup ---- May 22 17:16:10 volumiopi volumio[5299]: info: ------------------------------------------- May 22 17:16:10 volumiopi volumio[5299]: info: Loading plugins from folder /volumio/app/plugins/ May 22 17:16:10 volumiopi volumio[5299]: info: Adding plugin upnp to MyMusic Plugins May 22 17:16:10 volumiopi volumio[5299]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 17:16:10 volumiopi volumio[5299]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 17:16:10 volumiopi volumio[5299]: info: Loading plugins from folder /data/plugins/ May 22 17:16:10 volumiopi volumio[5299]: info: Loading plugin "system"... May 22 17:16:10 volumiopi volumio[5299]: info: Loading plugin "appearance"... May 22 17:16:11 volumiopi volumio[5299]: info: Loading plugin "network"... May 22 17:16:11 volumiopi volumio[5299]: info: Refreshing Cached IP Addresses May 22 17:16:11 volumiopi sudo[5325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:16:11 volumiopi sudo[5325]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:11 volumiopi sudo[5325]: pam_unix(sudo:session): session closed for user root May 22 17:16:11 volumiopi sudo[5327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:16:11 volumiopi sudo[5327]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:11 volumiopi sudo[5327]: pam_unix(sudo:session): session closed for user root May 22 17:16:11 volumiopi volumio[5299]: info: Loading plugin "services"... May 22 17:16:11 volumiopi volumio[5299]: info: Loading plugin "alsa_controller"... May 22 17:16:11 volumiopi sudo[5338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 17:16:11 volumiopi sudo[5338]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:12 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:12 volumiopi volumio[5299]: info: Loading plugin "wizard"... May 22 17:16:12 volumiopi volumio[5299]: info: Loading plugin "networkfs"... May 22 17:16:12 volumiopi volumio[5299]: info: Cannot mount NAS Synology at system boot, trial number 1 ,retrying in 5 seconds May 22 17:16:12 volumiopi volumio[5299]: info: Starting Udev Watcher for removable devices May 22 17:16:12 volumiopi volumio[5299]: info: Ignoring mount for partition: boot May 22 17:16:12 volumiopi volumio[5299]: info: Ignoring mount for partition: volumio May 22 17:16:12 volumiopi volumio[5299]: info: Ignoring mount for partition: volumio_data May 22 17:16:12 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:12 volumiopi volumio[5299]: info: Loading plugin "volumio_command_line_client"... May 22 17:16:12 volumiopi volumio[5299]: info: Loading plugin "upnp"... May 22 17:16:12 volumiopi volumio[5299]: info: [1747930572176] Starting Upmpd Daemon May 22 17:16:12 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:12 volumiopi volumio[5299]: info: Loading plugin "my_music"... May 22 17:16:12 volumiopi volumio[5299]: info: Loading plugin "mpd"... May 22 17:16:12 volumiopi volumio[5299]: info: Loading plugin "upnp_browser"... May 22 17:16:12 volumiopi volumio[5299]: info: Loading plugin "alarm-clock"... May 22 17:16:12 volumiopi sudo[5338]: pam_unix(sudo:session): session closed for user root May 22 17:16:12 volumiopi volumio[5299]: info: Loading plugin "airplay_emulation"... May 22 17:16:13 volumiopi volumio[5299]: info: Starting Shairport Sync May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "last_100"... May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "webradio"... May 22 17:16:13 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:13] [connect] Successful connection May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "i2s_dacs"... May 22 17:16:13 volumiopi volumio[5299]: info: I2S DAC not set, start Auto-detection May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "volumiodiscovery"... May 22 17:16:13 volumiopi volumio[5299]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:13 volumiopi volumio[5299]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:13 volumiopi volumio[5299]: *** WARNING *** For more information see May 22 17:16:13 volumiopi node[5299]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:13 volumiopi volumio[5299]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:13 volumiopi volumio[5299]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:13 volumiopi volumio[5299]: *** WARNING *** For more information see May 22 17:16:13 volumiopi node[5299]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:13 volumiopi node[5299]: *** WARNING *** For more information see May 22 17:16:13 volumiopi node[5299]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:13 volumiopi node[5299]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:13 volumiopi node[5299]: *** WARNING *** For more information see May 22 17:16:13 volumiopi volumio[5299]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 17:16:13 volumiopi volumio[5299]: info: Discovery: Started advertising with name: VolumioPi May 22 17:16:13 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "autostart"... May 22 17:16:13 volumiopi volumio[5299]: info: Applying required configuration parameters for plugin autostart May 22 17:16:13 volumiopi volumio[5299]: info: AutoStart - onVolumioStart - read config.json May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "outputs"... May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "albumart"... May 22 17:16:13 volumiopi volumio[5299]: info: Plugin example_plugin is not enabled May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "inputs"... May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "updater_comm"... May 22 17:16:13 volumiopi volumio[5299]: info: Plugin mpdemulation is not enabled May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "rest_api"... May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "websocket"... May 22 17:16:13 volumiopi volumio[5299]: info: Starting Socket.io Server version 2.3.0 May 22 17:16:13 volumiopi volumio[5299]: info: Loading plugin "fusiondsp"... May 22 17:16:13 volumiopi volumio[5299]: Forking 3 albumart workers May 22 17:16:14 volumiopi volumio[5299]: info: Applying required configuration parameters for plugin fusiondsp May 22 17:16:14 volumiopi volumio[5299]: info: Loading i18n strings for locale pl May 22 17:16:14 volumiopi volumio[5299]: Updating browse sources language May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::initPlayerControls May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:14 volumiopi volumio[5299]: Express server listening on port 3000 May 22 17:16:14 volumiopi volumio[5299]: [Metrics] WebUI: 5s 169.52ms May 22 17:16:14 volumiopi volumio[5299]: info: CoreStateMachine::resetVolumioState May 22 17:16:14 volumiopi volumio[5299]: info: CoreStateMachine::getcurrentVolume May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:16:14 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:14 volumiopi volumio[5299]: info: Volumio Network Manager: Network status updated: 2 May 22 17:16:14 volumiopi volumio[5299]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 22 17:16:14 volumiopi volumio[5299]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 22 17:16:14 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747930573 101 May 22 17:16:14 volumiopi volumio[5299]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 22 17:16:14 volumiopi volumio[5299]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 22 17:16:14 volumiopi volumio[5299]: Starting albumart workers May 22 17:16:15 volumiopi volumio[5299]: info: Reloading queue from file May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 22 17:16:15 volumiopi volumio[5299]: info: VolumeController:: Volume=0 Mute =false May 22 17:16:15 volumiopi volumio[5299]: info: CoreStateMachine::pushState May 22 17:16:15 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::volumioPushState May 22 17:16:15 volumiopi volumio[5299]: info: CoreStateMachine::updateTrackBlock May 22 17:16:15 volumiopi volumio[5299]: info: CorePlayQueue::getTrackBlock May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:16:15 volumiopi volumio[5299]: Starting albumart workers May 22 17:16:15 volumiopi volumio[5299]: info: CoreStateMachine::setRepeat null single undefined May 22 17:16:15 volumiopi volumio[5299]: info: CoreStateMachine::pushState May 22 17:16:15 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::volumioPushState May 22 17:16:15 volumiopi volumio[5299]: Starting albumart workers May 22 17:16:15 volumiopi volumio[5299]: info: CoreStateMachine::setRandom null May 22 17:16:15 volumiopi volumio[5299]: info: CoreStateMachine::pushState May 22 17:16:15 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::volumioPushState May 22 17:16:15 volumiopi volumio[5299]: info: Setting Device type: Raspberry PI May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::volumioGetState May 22 17:16:15 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:15 volumiopi volumio[5299]: info: Discovery: adding 12ef70b5-2e29-4466-ab71-dc1c1aea59d9 May 22 17:16:15 volumiopi volumio[5299]: info: Discovery: Found device Volumio May 22 17:16:15 volumiopi volumio[5299]: info: Discovery: Connecting to remote: 192.168.0.7 May 22 17:16:15 volumiopi volumio[5299]: info: VolumeController:: Volume=0 Mute =false May 22 17:16:15 volumiopi volumio[5299]: info: CoreStateMachine::pushState May 22 17:16:15 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::volumioPushState May 22 17:16:15 volumiopi volumio[5299]: info: Discovery: Connected to remote: 192.168.0.7 May 22 17:16:15 volumiopi volumio[5299]: info: Discovery: adding 8fb1e1c8-fc9d-4a44-9915-ace891a4c314 May 22 17:16:15 volumiopi volumio[5299]: info: Discovery: Found device VolumioPi May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::volumioGetState May 22 17:16:15 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:15 volumiopi volumio[5299]: info: Completed loading Core Plugins May 22 17:16:15 volumiopi volumio[5299]: info: Preparing to generate the ALSA configuration file May 22 17:16:15 volumiopi volumio[5299]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:16:15 volumiopi volumio[5299]: info: Reading ALSA contributions from plugins. May 22 17:16:15 volumiopi volumio[5299]: info: Asound.conf file unchanged, so no further update is needed May 22 17:16:15 volumiopi volumio[5299]: info: Output device has changed, restarting MPD May 22 17:16:15 volumiopi volumio[5299]: info: Output device has changed, restarting Shairport Sync May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:15 volumiopi sudo[5396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:15 volumiopi sudo[5396]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:15 volumiopi sudo[5396]: pam_unix(sudo:session): session closed for user root May 22 17:16:15 volumiopi sudo[5398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:15 volumiopi sudo[5398]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:15 volumiopi systemd[1]: Stopping Music Player Daemon... May 22 17:16:15 volumiopi volumio[5299]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:15 volumiopi volumio[5299]: info: ___________ START PLUGINS ___________ May 22 17:16:15 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:15 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:15 volumiopi volumio[5299]: info: ControllerMpd::onStart: Initializing MPD May 22 17:16:15 volumiopi volumio[5299]: info: Creating MPD Configuration file May 22 17:16:15 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:15 volumiopi sudo[5414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:15 volumiopi sudo[5414]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:15 volumiopi volumio[5299]: info: [1747930575940] CoreMusicLibrary::Adding element Serwery Mediów May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:15 volumiopi sudo[5414]: pam_unix(sudo:session): session closed for user root May 22 17:16:15 volumiopi sudo[5421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:15 volumiopi sudo[5421]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:15 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:16 volumiopi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 17:16:16 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:16 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:16 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:16 volumiopi volumio[5299]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:16 volumiopi volumio[5299]: info: [1747930576126] CoreMusicLibrary::Adding element Last_100 May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:16 volumiopi volumio[5299]: info: [1747930576131] CoreMusicLibrary::Adding element Webradio May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:16 volumiopi volumio[5299]: info: Initializing BBC Radios May 22 17:16:16 volumiopi sudo[5434]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:16:16 volumiopi sudo[5434]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:16 volumiopi sudo[5434]: pam_unix(sudo:session): session closed for user root May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: AutoStart - onStart May 22 17:16:16 volumiopi volumio[5299]: info: Loading i18n strings for locale pl May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: FusionDsp - mixtype--------------------- Hardware May 22 17:16:16 volumiopi volumio[5299]: info: Volumio Calling Home May 22 17:16:16 volumiopi volumio[5299]: info: Preparing to generate the ALSA configuration file May 22 17:16:16 volumiopi volumio[5299]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:16:16 volumiopi volumio[5299]: info: Reading ALSA contributions from plugins. May 22 17:16:16 volumiopi volumio[5299]: info: MPD Permissions set May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::volumioGetState May 22 17:16:16 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:16 volumiopi volumio[5299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 22 17:16:16 volumiopi volumio[5299]: info: Volumio called home May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: Starting Shairport Sync May 22 17:16:16 volumiopi volumio[5299]: info: Starting Shairport Sync May 22 17:16:16 volumiopi volumio[5299]: info: Starting Shairport Sync May 22 17:16:16 volumiopi sudo[5466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:16 volumiopi sudo[5466]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:16 volumiopi sudo[5468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:16 volumiopi sudo[5468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:16 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:16 volumiopi sudo[5471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:16 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:16 volumiopi sudo[5471]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:16 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::volumioGetState May 22 17:16:16 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:16 volumiopi volumio[5299]: info: Asound.conf file unchanged, so no further update is needed May 22 17:16:16 volumiopi volumio[5299]: info: Output device has changed, restarting MPD May 22 17:16:16 volumiopi volumio[5299]: info: Output device has changed, restarting Shairport Sync May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:16 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:16 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:16 volumiopi sudo[5476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:16 volumiopi sudo[5476]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:16 volumiopi sudo[5476]: pam_unix(sudo:session): session closed for user root May 22 17:16:16 volumiopi sudo[5466]: pam_unix(sudo:session): session closed for user root May 22 17:16:16 volumiopi sudo[5468]: pam_unix(sudo:session): session closed for user root May 22 17:16:16 volumiopi sudo[5480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:16 volumiopi sudo[5480]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:16 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:16 volumiopi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 22 17:16:16 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:16 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:16 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:16 volumiopi sudo[5471]: pam_unix(sudo:session): session closed for user root May 22 17:16:16 volumiopi volumio[5299]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:16 volumiopi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 22 17:16:16 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:16 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:16 volumiopi volumio[5299]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 22 17:16:16 volumiopi volumio[5299]: info: MPD Permissions set May 22 17:16:16 volumiopi volumio[5299]: info: Shairport-Sync Started May 22 17:16:16 volumiopi volumio[5299]: Error adding Membership: Error: addMembership EINVAL May 22 17:16:16 volumiopi volumio[5299]: info: Shairport-Sync Started May 22 17:16:16 volumiopi volumio[5299]: info: Shairport-Sync Started May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:17 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 17:16:17 volumiopi volumio[5299]: info: Starting Shairport Sync May 22 17:16:17 volumiopi volumio[5299]: info: Received Get System Info May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:16:17 volumiopi volumio[5299]: info: Discovery: Getting this device information May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::volumioGetState May 22 17:16:17 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:16:17 volumiopi sudo[5494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:17 volumiopi sudo[5494]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 17:16:17 volumiopi volumio[5299]: info: Received Get System Info May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:16:17 volumiopi volumio[5299]: info: Discovery: Getting this device information May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::volumioGetState May 22 17:16:17 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::volumioGetVisibleSources May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::volumioGetState May 22 17:16:17 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 17:16:17 volumiopi volumio[5299]: info: Received Get System Info May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:16:17 volumiopi volumio[5299]: info: Discovery: Getting this device information May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::volumioGetState May 22 17:16:17 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::volumioGetState May 22 17:16:17 volumiopi volumio[5299]: info: CorePlayQueue::getTrack 0 May 22 17:16:17 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:17 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:17 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:17 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:17 volumiopi volumio[5299]: info: Listing playlists May 22 17:16:17 volumiopi sudo[5486]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:16:17 volumiopi sudo[5486]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:17 volumiopi sudo[5494]: pam_unix(sudo:session): session closed for user root May 22 17:16:17 volumiopi volumio[5299]: info: Cannot mount NAS Synology at system boot, trial number 2 ,retrying in 5 seconds May 22 17:16:17 volumiopi sudo[5486]: pam_unix(sudo:session): session closed for user root May 22 17:16:17 volumiopi volumio[5299]: info: Shairport-Sync Started May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:16:17 volumiopi volumio[5299]: info: FusionDsp - May 22 17:16:17 volumiopi volumio[5299]: info: FusionDsp - May 22 17:16:17 volumiopi volumio[5299]: info: FusionDsp - undefined May 22 17:16:17 volumiopi volumio[5299]: info: FusionDsp - undefined May 22 17:16:17 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 22 17:16:18 volumiopi volumio[5299]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 22 17:16:18 volumiopi volumio[5299]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 22 17:16:18 volumiopi volumio[5299]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 22 17:16:18 volumiopi volumio[5299]: info: camilladsp service started and running in background, instance 1 May 22 17:16:18 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:18 volumiopi volumio[5299]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 22 17:16:18 volumiopi volumio[5299]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 22 17:16:18 volumiopi volumio[5299]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 22 17:16:18 volumiopi volumio[5299]: info: FusionDsp loaded May 22 17:16:18 volumiopi volumio[5299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:16:18 volumiopi sudo[5511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 22 17:16:18 volumiopi sudo[5511]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:18 volumiopi volumio[5299]: info: FusionDsp - Reporting Fusion DSP Enabled May 22 17:16:18 volumiopi volumio[5299]: info: Adding Signal Path Element [object Object] May 22 17:16:18 volumiopi volumio[5299]: info: Adding fusiondspeq DSP Signal Path Element May 22 17:16:18 volumiopi volumio[5299]: info: FusionDsp - ---- installed callbackRead May 22 17:16:18 volumiopi sudo[5511]: pam_unix(sudo:session): session closed for user root May 22 17:16:18 volumiopi volumio[5299]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:16:18 volumiopi volumio[5299]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 22 17:16:18 volumiopi volumio[5299]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 22 17:16:18 volumiopi volumio[5299]: at onErrorNT (internal/child_process.js:465:16) May 22 17:16:18 volumiopi volumio[5299]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 22 17:16:18 volumiopi volumio[5299]: errno: -2, May 22 17:16:18 volumiopi volumio[5299]: code: 'ENOENT', May 22 17:16:18 volumiopi volumio[5299]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 22 17:16:18 volumiopi volumio[5299]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 22 17:16:18 volumiopi volumio[5299]: spawnargs: [ May 22 17:16:18 volumiopi volumio[5299]: '-p', May 22 17:16:18 volumiopi volumio[5299]: 9876, May 22 17:16:18 volumiopi volumio[5299]: '-o', May 22 17:16:18 volumiopi volumio[5299]: '/tmp/camilladsp.log', May 22 17:16:18 volumiopi volumio[5299]: '-l', May 22 17:16:18 volumiopi volumio[5299]: 'warn', May 22 17:16:18 volumiopi volumio[5299]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 22 17:16:18 volumiopi volumio[5299]: ] May 22 17:16:18 volumiopi volumio[5299]: } May 22 17:16:18 volumiopi volumio[5299]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:16:18 volumiopi mpd[5498]: May 22 17:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 17:16:18 volumiopi systemd[1]: Started Music Player Daemon. May 22 17:16:18 volumiopi sudo[5398]: pam_unix(sudo:session): session closed for user root May 22 17:16:18 volumiopi sudo[5421]: pam_unix(sudo:session): session closed for user root May 22 17:16:18 volumiopi sudo[5480]: pam_unix(sudo:session): session closed for user root May 22 17:16:19 volumiopi sudo[5524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 17:15 May 22 17:16:19 volumiopi sudo[5524]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:19 volumiopi sudo[5524]: pam_unix(sudo:session): session closed for user root May 22 17:16:19 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 17:16:19 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 17:16:19 volumiopi systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 17:16:19 volumiopi systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 17:16:19 volumiopi systemd[1]: Started dynamicswap service. May 22 17:16:19 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:16:19 volumiopi systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 17:16:19 volumiopi systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19. May 22 17:16:19 volumiopi systemd[1]: Stopped Volumio Backend Module. May 22 17:16:19 volumiopi systemd[1]: Started Volumio Backend Module. May 22 17:16:19 volumiopi systemd[1]: Started dynamicswap service. May 22 17:16:19 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:16:21 volumiopi volumio[5537]: info: ------------------------------------------- May 22 17:16:21 volumiopi volumio[5537]: info: ----- Volumio3 ---- May 22 17:16:21 volumiopi volumio[5537]: info: ------------------------------------------- May 22 17:16:21 volumiopi volumio[5537]: info: ----- System startup ---- May 22 17:16:21 volumiopi volumio[5537]: info: ------------------------------------------- May 22 17:16:22 volumiopi volumio[5537]: info: MYVOLUMIO Environment detected May 22 17:16:22 volumiopi volumio[5537]: info: Plugin folders cleanup May 22 17:16:22 volumiopi volumio[5537]: info: Scanning into folder /volumio/app/plugins/ May 22 17:16:22 volumiopi volumio[5537]: info: Scanning category audio_interface May 22 17:16:22 volumiopi volumio[5537]: info: Scanning category miscellanea May 22 17:16:22 volumiopi volumio[5537]: info: Scanning category music_service May 22 17:16:22 volumiopi volumio[5537]: info: Scanning category plugins.json May 22 17:16:22 volumiopi volumio[5537]: info: Scanning category system_controller May 22 17:16:22 volumiopi volumio[5537]: info: Scanning category user_interface May 22 17:16:22 volumiopi volumio[5537]: info: Scanning into folder /data/plugins/ May 22 17:16:22 volumiopi volumio[5537]: info: Scanning category audio_interface May 22 17:16:22 volumiopi volumio[5537]: info: Scanning category system_controller May 22 17:16:22 volumiopi volumio[5537]: info: Plugin folders cleanup completed May 22 17:16:22 volumiopi volumio[5537]: info: ------------------------------------------- May 22 17:16:22 volumiopi volumio[5537]: info: ----- Core plugins startup ---- May 22 17:16:22 volumiopi volumio[5537]: info: ------------------------------------------- May 22 17:16:22 volumiopi volumio[5537]: info: Loading plugins from folder /volumio/app/plugins/ May 22 17:16:22 volumiopi volumio[5537]: info: Adding plugin upnp to MyMusic Plugins May 22 17:16:22 volumiopi volumio[5537]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 17:16:22 volumiopi volumio[5537]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 17:16:22 volumiopi volumio[5537]: info: Loading plugins from folder /data/plugins/ May 22 17:16:22 volumiopi volumio[5537]: info: Loading plugin "system"... May 22 17:16:22 volumiopi volumio[5537]: info: Loading plugin "appearance"... May 22 17:16:23 volumiopi volumio[5537]: info: Loading plugin "network"... May 22 17:16:23 volumiopi volumio[5537]: info: Refreshing Cached IP Addresses May 22 17:16:23 volumiopi sudo[5564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:16:23 volumiopi sudo[5564]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:23 volumiopi sudo[5564]: pam_unix(sudo:session): session closed for user root May 22 17:16:23 volumiopi sudo[5566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:16:23 volumiopi sudo[5566]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:23 volumiopi sudo[5566]: pam_unix(sudo:session): session closed for user root May 22 17:16:23 volumiopi volumio[5537]: info: Loading plugin "services"... May 22 17:16:23 volumiopi volumio[5537]: info: Loading plugin "alsa_controller"... May 22 17:16:23 volumiopi sudo[5579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 17:16:23 volumiopi sudo[5579]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:23 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:23 volumiopi volumio[5537]: info: Loading plugin "wizard"... May 22 17:16:23 volumiopi volumio[5537]: info: Loading plugin "networkfs"... May 22 17:16:23 volumiopi volumio[5537]: info: Cannot mount NAS Synology at system boot, trial number 1 ,retrying in 5 seconds May 22 17:16:23 volumiopi volumio[5537]: info: Starting Udev Watcher for removable devices May 22 17:16:23 volumiopi volumio[5537]: info: Ignoring mount for partition: boot May 22 17:16:23 volumiopi volumio[5537]: info: Ignoring mount for partition: volumio May 22 17:16:23 volumiopi volumio[5537]: info: Ignoring mount for partition: volumio_data May 22 17:16:23 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:23 volumiopi volumio[5537]: info: Loading plugin "volumio_command_line_client"... May 22 17:16:23 volumiopi volumio[5537]: info: Loading plugin "upnp"... May 22 17:16:23 volumiopi volumio[5537]: info: [1747930583479] Starting Upmpd Daemon May 22 17:16:23 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:23 volumiopi volumio[5537]: info: Loading plugin "my_music"... May 22 17:16:23 volumiopi volumio[5537]: info: Loading plugin "mpd"... May 22 17:16:23 volumiopi volumio[5537]: info: Loading plugin "upnp_browser"... May 22 17:16:24 volumiopi volumio[5537]: info: Loading plugin "alarm-clock"... May 22 17:16:24 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:24] [connect] Successful connection May 22 17:16:24 volumiopi sudo[5579]: pam_unix(sudo:session): session closed for user root May 22 17:16:24 volumiopi volumio[5537]: info: Loading plugin "airplay_emulation"... May 22 17:16:24 volumiopi volumio[5537]: info: Starting Shairport Sync May 22 17:16:24 volumiopi volumio[5537]: info: Loading plugin "last_100"... May 22 17:16:24 volumiopi volumio[5537]: info: Loading plugin "webradio"... May 22 17:16:24 volumiopi volumio[5537]: info: Loading plugin "i2s_dacs"... May 22 17:16:24 volumiopi volumio[5537]: info: I2S DAC not set, start Auto-detection May 22 17:16:24 volumiopi volumio[5537]: info: Loading plugin "volumiodiscovery"... May 22 17:16:24 volumiopi volumio[5537]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:24 volumiopi volumio[5537]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:24 volumiopi volumio[5537]: *** WARNING *** For more information see May 22 17:16:24 volumiopi node[5537]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:24 volumiopi volumio[5537]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:24 volumiopi volumio[5537]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:24 volumiopi volumio[5537]: *** WARNING *** For more information see May 22 17:16:24 volumiopi node[5537]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:24 volumiopi node[5537]: *** WARNING *** For more information see May 22 17:16:24 volumiopi node[5537]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:24 volumiopi node[5537]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:24 volumiopi node[5537]: *** WARNING *** For more information see May 22 17:16:24 volumiopi volumio[5537]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 17:16:24 volumiopi volumio[5537]: info: Discovery: Started advertising with name: VolumioPi May 22 17:16:24 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:24 volumiopi volumio[5537]: info: Loading plugin "autostart"... May 22 17:16:24 volumiopi volumio[5537]: info: Applying required configuration parameters for plugin autostart May 22 17:16:24 volumiopi volumio[5537]: info: AutoStart - onVolumioStart - read config.json May 22 17:16:24 volumiopi volumio[5537]: info: Loading plugin "outputs"... May 22 17:16:24 volumiopi volumio[5537]: info: Loading plugin "albumart"... May 22 17:16:24 volumiopi volumio[5537]: info: Plugin example_plugin is not enabled May 22 17:16:24 volumiopi volumio[5537]: info: Loading plugin "inputs"... May 22 17:16:24 volumiopi volumio[5537]: info: Loading plugin "updater_comm"... May 22 17:16:25 volumiopi volumio[5537]: info: Plugin mpdemulation is not enabled May 22 17:16:25 volumiopi volumio[5537]: info: Loading plugin "rest_api"... May 22 17:16:25 volumiopi volumio[5537]: info: Loading plugin "websocket"... May 22 17:16:25 volumiopi volumio[5537]: info: Starting Socket.io Server version 2.3.0 May 22 17:16:25 volumiopi volumio[5537]: info: Loading plugin "fusiondsp"... May 22 17:16:25 volumiopi volumio[5537]: Forking 3 albumart workers May 22 17:16:25 volumiopi volumio[5537]: info: Applying required configuration parameters for plugin fusiondsp May 22 17:16:25 volumiopi volumio[5537]: info: Loading i18n strings for locale pl May 22 17:16:25 volumiopi volumio[5537]: Updating browse sources language May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::initPlayerControls May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:25 volumiopi volumio[5537]: Express server listening on port 3000 May 22 17:16:25 volumiopi volumio[5537]: [Metrics] WebUI: 5s 183.08ms May 22 17:16:25 volumiopi volumio[5537]: info: CoreStateMachine::resetVolumioState May 22 17:16:25 volumiopi volumio[5537]: info: CoreStateMachine::getcurrentVolume May 22 17:16:25 volumiopi volumio[5537]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:26 volumiopi volumio[5537]: info: Volumio Network Manager: Network status updated: 2 May 22 17:16:26 volumiopi volumio[5537]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 22 17:16:26 volumiopi volumio[5537]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 22 17:16:26 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747930584 101 May 22 17:16:26 volumiopi volumio[5537]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 22 17:16:26 volumiopi volumio[5537]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::volumioGetState May 22 17:16:26 volumiopi volumio[5537]: Starting albumart workers May 22 17:16:26 volumiopi volumio[5537]: info: CorePlayQueue::getTrack 0 May 22 17:16:26 volumiopi volumio[5537]: Starting albumart workers May 22 17:16:26 volumiopi volumio[5537]: Starting albumart workers May 22 17:16:26 volumiopi volumio[5537]: info: Reloading queue from file May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 22 17:16:26 volumiopi volumio[5537]: info: VolumeController:: Volume=0 Mute =false May 22 17:16:26 volumiopi volumio[5537]: info: CoreStateMachine::pushState May 22 17:16:26 volumiopi volumio[5537]: info: CorePlayQueue::getTrack 0 May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::volumioPushState May 22 17:16:26 volumiopi volumio[5537]: info: CoreStateMachine::updateTrackBlock May 22 17:16:26 volumiopi volumio[5537]: info: CorePlayQueue::getTrackBlock May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:16:26 volumiopi volumio[5537]: info: CoreStateMachine::setRepeat null single undefined May 22 17:16:26 volumiopi volumio[5537]: info: CoreStateMachine::pushState May 22 17:16:26 volumiopi volumio[5537]: info: CorePlayQueue::getTrack 0 May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::volumioPushState May 22 17:16:26 volumiopi volumio[5537]: info: CoreStateMachine::setRandom null May 22 17:16:26 volumiopi volumio[5537]: info: CoreStateMachine::pushState May 22 17:16:26 volumiopi volumio[5537]: info: CorePlayQueue::getTrack 0 May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::volumioPushState May 22 17:16:26 volumiopi volumio[5537]: info: Setting Device type: Raspberry PI May 22 17:16:26 volumiopi volumio[5537]: info: Listing playlists May 22 17:16:26 volumiopi volumio[5537]: info: Listing playlists May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::volumioGetState May 22 17:16:26 volumiopi volumio[5537]: info: CorePlayQueue::getTrack 0 May 22 17:16:26 volumiopi volumio[5537]: info: Completed loading Core Plugins May 22 17:16:26 volumiopi volumio[5537]: info: Preparing to generate the ALSA configuration file May 22 17:16:26 volumiopi volumio[5537]: info: Discovery: adding 8fb1e1c8-fc9d-4a44-9915-ace891a4c314 May 22 17:16:26 volumiopi volumio[5537]: info: Discovery: Found device VolumioPi May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::volumioGetState May 22 17:16:26 volumiopi volumio[5537]: info: CorePlayQueue::getTrack 0 May 22 17:16:26 volumiopi volumio[5537]: info: Discovery: adding 12ef70b5-2e29-4466-ab71-dc1c1aea59d9 May 22 17:16:26 volumiopi volumio[5537]: info: Discovery: Found device Volumio May 22 17:16:26 volumiopi volumio[5537]: info: Discovery: Connecting to remote: 192.168.0.7 May 22 17:16:26 volumiopi volumio[5537]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:16:26 volumiopi volumio[5537]: info: Reading ALSA contributions from plugins. May 22 17:16:26 volumiopi volumio[5537]: info: VolumeController:: Volume=0 Mute =false May 22 17:16:26 volumiopi volumio[5537]: info: CoreStateMachine::pushState May 22 17:16:26 volumiopi volumio[5537]: info: CorePlayQueue::getTrack 0 May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::volumioPushState May 22 17:16:26 volumiopi volumio[5537]: info: Asound.conf file unchanged, so no further update is needed May 22 17:16:26 volumiopi volumio[5537]: info: Output device has changed, restarting MPD May 22 17:16:26 volumiopi sudo[5635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:26 volumiopi volumio[5537]: info: Output device has changed, restarting Shairport Sync May 22 17:16:26 volumiopi sudo[5635]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:26 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:26 volumiopi sudo[5635]: pam_unix(sudo:session): session closed for user root May 22 17:16:27 volumiopi sudo[5637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:27 volumiopi sudo[5637]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:27 volumiopi systemd[1]: Stopping Music Player Daemon... May 22 17:16:27 volumiopi volumio[5537]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:27 volumiopi volumio[5537]: info: ___________ START PLUGINS ___________ May 22 17:16:27 volumiopi volumio[5537]: info: ControllerMpd::onStart: Initializing MPD May 22 17:16:27 volumiopi volumio[5537]: info: Creating MPD Configuration file May 22 17:16:27 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:27 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:27 volumiopi sudo[5644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:27 volumiopi sudo[5644]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:27 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:27 volumiopi volumio[5537]: info: [1747930587152] CoreMusicLibrary::Adding element Serwery Mediów May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:27 volumiopi sudo[5647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:27 volumiopi sudo[5647]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:27 volumiopi sudo[5644]: pam_unix(sudo:session): session closed for user root May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:27 volumiopi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 17:16:27 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:27 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:27 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:27 volumiopi volumio[5537]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:27 volumiopi volumio[5537]: info: [1747930587372] CoreMusicLibrary::Adding element Last_100 May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:27 volumiopi volumio[5537]: info: [1747930587377] CoreMusicLibrary::Adding element Webradio May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:27 volumiopi volumio[5537]: info: Initializing BBC Radios May 22 17:16:27 volumiopi sudo[5657]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:16:27 volumiopi sudo[5657]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:27 volumiopi sudo[5657]: pam_unix(sudo:session): session closed for user root May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: AutoStart - onStart May 22 17:16:27 volumiopi volumio[5537]: info: Loading i18n strings for locale pl May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: FusionDsp - mixtype--------------------- Hardware May 22 17:16:27 volumiopi volumio[5537]: info: Volumio Calling Home May 22 17:16:27 volumiopi volumio[5537]: info: Preparing to generate the ALSA configuration file May 22 17:16:27 volumiopi volumio[5537]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:16:27 volumiopi volumio[5537]: info: Reading ALSA contributions from plugins. May 22 17:16:27 volumiopi volumio[5537]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 22 17:16:27 volumiopi volumio[5537]: info: MPD Permissions set May 22 17:16:27 volumiopi volumio[5537]: info: MPD Permissions set May 22 17:16:27 volumiopi volumio[5537]: info: Volumio called home May 22 17:16:27 volumiopi volumio[5537]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:27 volumiopi volumio[5537]: info: Starting Shairport Sync May 22 17:16:28 volumiopi volumio[5537]: info: Starting Shairport Sync May 22 17:16:28 volumiopi volumio[5537]: info: Starting Shairport Sync May 22 17:16:28 volumiopi sudo[5705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:28 volumiopi sudo[5705]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:28 volumiopi volumio[5537]: info: Discovery: A device disappeared from network May 22 17:16:28 volumiopi sudo[5707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:28 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:28 volumiopi sudo[5710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:28 volumiopi sudo[5710]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:28 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:28 volumiopi sudo[5707]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:28 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:28 volumiopi volumio[5537]: info: CoreCommandRouter::volumioGetState May 22 17:16:28 volumiopi volumio[5537]: info: CorePlayQueue::getTrack 0 May 22 17:16:28 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:28 volumiopi sudo[5705]: pam_unix(sudo:session): session closed for user root May 22 17:16:28 volumiopi volumio[5537]: info: Asound.conf file unchanged, so no further update is needed May 22 17:16:28 volumiopi volumio[5537]: info: Output device has changed, restarting MPD May 22 17:16:28 volumiopi volumio[5537]: info: Output device has changed, restarting Shairport Sync May 22 17:16:28 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:28 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:28 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:28 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:28 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:28 volumiopi sudo[5718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:28 volumiopi sudo[5718]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:28 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:28 volumiopi sudo[5715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:28 volumiopi sudo[5715]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:28 volumiopi sudo[5707]: pam_unix(sudo:session): session closed for user root May 22 17:16:28 volumiopi sudo[5710]: pam_unix(sudo:session): session closed for user root May 22 17:16:28 volumiopi sudo[5715]: pam_unix(sudo:session): session closed for user root May 22 17:16:28 volumiopi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 22 17:16:28 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:28 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:28 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:28 volumiopi volumio[5537]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:28 volumiopi volumio[5537]: info: MPD Permissions set May 22 17:16:28 volumiopi volumio[5537]: info: Shairport-Sync Started May 22 17:16:28 volumiopi volumio[5537]: Error adding Membership: Error: addMembership EINVAL May 22 17:16:28 volumiopi volumio[5537]: info: Shairport-Sync Started May 22 17:16:28 volumiopi volumio[5537]: info: Shairport-Sync Started May 22 17:16:28 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:28 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:28 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:28 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:28 volumiopi volumio[5537]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 22 17:16:28 volumiopi volumio[5537]: info: Starting Shairport Sync May 22 17:16:28 volumiopi sudo[5726]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:16:28 volumiopi sudo[5726]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:28 volumiopi volumio[5537]: info: Cannot mount NAS Synology at system boot, trial number 2 ,retrying in 5 seconds May 22 17:16:28 volumiopi sudo[5726]: pam_unix(sudo:session): session closed for user root May 22 17:16:28 volumiopi sudo[5752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:28 volumiopi sudo[5752]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:28 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 22 17:16:28 volumiopi volumio[5537]: info: CoreCommandRouter::volumioGetState May 22 17:16:28 volumiopi volumio[5537]: info: CorePlayQueue::getTrack 0 May 22 17:16:28 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:28 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:28 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:28 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:28 volumiopi sudo[5752]: pam_unix(sudo:session): session closed for user root May 22 17:16:28 volumiopi volumio[5537]: info: Shairport-Sync Started May 22 17:16:28 volumiopi volumio[5537]: info: Discovery: adding 12ef70b5-2e29-4466-ab71-dc1c1aea59d9 May 22 17:16:28 volumiopi volumio[5537]: info: Discovery: Found device Volumio May 22 17:16:28 volumiopi volumio[5537]: info: Discovery: Connecting to remote: 192.168.0.7 May 22 17:16:28 volumiopi volumio[5537]: info: Discovery: Connected to remote: 192.168.0.7 May 22 17:16:29 volumiopi volumio[5537]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 22 17:16:29 volumiopi volumio[5537]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 22 17:16:29 volumiopi volumio[5537]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 22 17:16:29 volumiopi volumio[5537]: info: camilladsp service started and running in background, instance 1 May 22 17:16:29 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:29 volumiopi volumio[5537]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 22 17:16:29 volumiopi volumio[5537]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 22 17:16:29 volumiopi volumio[5537]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 22 17:16:29 volumiopi volumio[5537]: info: FusionDsp loaded May 22 17:16:29 volumiopi volumio[5537]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:16:29 volumiopi sudo[5789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 22 17:16:29 volumiopi sudo[5789]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:29 volumiopi volumio[5537]: info: FusionDsp - Reporting Fusion DSP Enabled May 22 17:16:29 volumiopi volumio[5537]: info: Adding Signal Path Element [object Object] May 22 17:16:29 volumiopi volumio[5537]: info: Adding fusiondspeq DSP Signal Path Element May 22 17:16:29 volumiopi volumio[5537]: info: FusionDsp - ---- installed callbackRead May 22 17:16:29 volumiopi volumio[5537]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:16:29 volumiopi sudo[5789]: pam_unix(sudo:session): session closed for user root May 22 17:16:29 volumiopi volumio[5537]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 22 17:16:29 volumiopi volumio[5537]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 22 17:16:29 volumiopi volumio[5537]: at onErrorNT (internal/child_process.js:465:16) May 22 17:16:29 volumiopi volumio[5537]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 22 17:16:29 volumiopi volumio[5537]: errno: -2, May 22 17:16:29 volumiopi volumio[5537]: code: 'ENOENT', May 22 17:16:29 volumiopi volumio[5537]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 22 17:16:29 volumiopi volumio[5537]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 22 17:16:29 volumiopi volumio[5537]: spawnargs: [ May 22 17:16:29 volumiopi volumio[5537]: '-p', May 22 17:16:29 volumiopi volumio[5537]: 9876, May 22 17:16:29 volumiopi volumio[5537]: '-o', May 22 17:16:29 volumiopi volumio[5537]: '/tmp/camilladsp.log', May 22 17:16:29 volumiopi volumio[5537]: '-l', May 22 17:16:29 volumiopi volumio[5537]: 'warn', May 22 17:16:29 volumiopi volumio[5537]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 22 17:16:29 volumiopi volumio[5537]: ] May 22 17:16:29 volumiopi volumio[5537]: } May 22 17:16:29 volumiopi volumio[5537]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:16:29 volumiopi mpd[5757]: May 22 17:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 17:16:30 volumiopi systemd[1]: Started Music Player Daemon. May 22 17:16:30 volumiopi sudo[5647]: pam_unix(sudo:session): session closed for user root May 22 17:16:30 volumiopi sudo[5718]: pam_unix(sudo:session): session closed for user root May 22 17:16:30 volumiopi sudo[5637]: pam_unix(sudo:session): session closed for user root May 22 17:16:30 volumiopi sudo[5802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 17:15 May 22 17:16:30 volumiopi sudo[5802]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:30 volumiopi sudo[5802]: pam_unix(sudo:session): session closed for user root May 22 17:16:30 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:30] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 17:16:30 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:30] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 17:16:30 volumiopi systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 17:16:30 volumiopi systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 17:16:30 volumiopi systemd[1]: Started dynamicswap service. May 22 17:16:30 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:16:30 volumiopi systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 17:16:30 volumiopi systemd[1]: volumio.service: Scheduled restart job, restart counter is at 20. May 22 17:16:30 volumiopi systemd[1]: Stopped Volumio Backend Module. May 22 17:16:30 volumiopi systemd[1]: Started Volumio Backend Module. May 22 17:16:30 volumiopi systemd[1]: Started dynamicswap service. May 22 17:16:30 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:16:32 volumiopi volumio[5812]: info: ------------------------------------------- May 22 17:16:32 volumiopi volumio[5812]: info: ----- Volumio3 ---- May 22 17:16:32 volumiopi volumio[5812]: info: ------------------------------------------- May 22 17:16:32 volumiopi volumio[5812]: info: ----- System startup ---- May 22 17:16:32 volumiopi volumio[5812]: info: ------------------------------------------- May 22 17:16:33 volumiopi volumio[5812]: info: MYVOLUMIO Environment detected May 22 17:16:33 volumiopi volumio[5812]: info: Plugin folders cleanup May 22 17:16:33 volumiopi volumio[5812]: info: Scanning into folder /volumio/app/plugins/ May 22 17:16:33 volumiopi volumio[5812]: info: Scanning category audio_interface May 22 17:16:33 volumiopi volumio[5812]: info: Scanning category miscellanea May 22 17:16:33 volumiopi volumio[5812]: info: Scanning category music_service May 22 17:16:33 volumiopi volumio[5812]: info: Scanning category plugins.json May 22 17:16:33 volumiopi volumio[5812]: info: Scanning category system_controller May 22 17:16:33 volumiopi volumio[5812]: info: Scanning category user_interface May 22 17:16:33 volumiopi volumio[5812]: info: Scanning into folder /data/plugins/ May 22 17:16:33 volumiopi volumio[5812]: info: Scanning category audio_interface May 22 17:16:33 volumiopi volumio[5812]: info: Scanning category system_controller May 22 17:16:33 volumiopi volumio[5812]: info: Plugin folders cleanup completed May 22 17:16:33 volumiopi volumio[5812]: info: ------------------------------------------- May 22 17:16:33 volumiopi volumio[5812]: info: ----- Core plugins startup ---- May 22 17:16:33 volumiopi volumio[5812]: info: ------------------------------------------- May 22 17:16:33 volumiopi volumio[5812]: info: Loading plugins from folder /volumio/app/plugins/ May 22 17:16:33 volumiopi volumio[5812]: info: Adding plugin upnp to MyMusic Plugins May 22 17:16:33 volumiopi volumio[5812]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 17:16:33 volumiopi volumio[5812]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 17:16:33 volumiopi volumio[5812]: info: Loading plugins from folder /data/plugins/ May 22 17:16:33 volumiopi volumio[5812]: info: Loading plugin "system"... May 22 17:16:33 volumiopi volumio[5812]: info: Loading plugin "appearance"... May 22 17:16:34 volumiopi volumio[5812]: info: Loading plugin "network"... May 22 17:16:34 volumiopi volumio[5812]: info: Refreshing Cached IP Addresses May 22 17:16:34 volumiopi sudo[5839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:16:34 volumiopi sudo[5839]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:34 volumiopi sudo[5839]: pam_unix(sudo:session): session closed for user root May 22 17:16:34 volumiopi sudo[5841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:16:34 volumiopi sudo[5841]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:34 volumiopi volumio[5812]: info: Loading plugin "services"... May 22 17:16:34 volumiopi sudo[5841]: pam_unix(sudo:session): session closed for user root May 22 17:16:34 volumiopi volumio[5812]: info: Loading plugin "alsa_controller"... May 22 17:16:34 volumiopi sudo[5849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 17:16:34 volumiopi sudo[5849]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:34 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:34 volumiopi volumio[5812]: info: Loading plugin "wizard"... May 22 17:16:34 volumiopi volumio[5812]: info: Loading plugin "networkfs"... May 22 17:16:34 volumiopi volumio[5812]: info: Cannot mount NAS Synology at system boot, trial number 1 ,retrying in 5 seconds May 22 17:16:34 volumiopi volumio[5812]: info: Starting Udev Watcher for removable devices May 22 17:16:34 volumiopi volumio[5812]: info: Ignoring mount for partition: boot May 22 17:16:34 volumiopi volumio[5812]: info: Ignoring mount for partition: volumio May 22 17:16:34 volumiopi volumio[5812]: info: Ignoring mount for partition: volumio_data May 22 17:16:34 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:34 volumiopi volumio[5812]: info: Loading plugin "volumio_command_line_client"... May 22 17:16:34 volumiopi volumio[5812]: info: Loading plugin "upnp"... May 22 17:16:34 volumiopi volumio[5812]: info: [1747930594675] Starting Upmpd Daemon May 22 17:16:34 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:34 volumiopi volumio[5812]: info: Loading plugin "my_music"... May 22 17:16:34 volumiopi volumio[5812]: info: Loading plugin "mpd"... May 22 17:16:35 volumiopi volumio[5812]: info: Loading plugin "upnp_browser"... May 22 17:16:35 volumiopi volumio[5812]: info: Loading plugin "alarm-clock"... May 22 17:16:35 volumiopi sudo[5849]: pam_unix(sudo:session): session closed for user root May 22 17:16:35 volumiopi volumio[5812]: info: Loading plugin "airplay_emulation"... May 22 17:16:35 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:35] [connect] Successful connection May 22 17:16:35 volumiopi volumio[5812]: info: Starting Shairport Sync May 22 17:16:35 volumiopi volumio[5812]: info: Loading plugin "last_100"... May 22 17:16:35 volumiopi volumio[5812]: info: Loading plugin "webradio"... May 22 17:16:35 volumiopi volumio[5812]: info: Loading plugin "i2s_dacs"... May 22 17:16:35 volumiopi volumio[5812]: info: I2S DAC not set, start Auto-detection May 22 17:16:35 volumiopi volumio[5812]: info: Loading plugin "volumiodiscovery"... May 22 17:16:35 volumiopi volumio[5812]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:35 volumiopi volumio[5812]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:35 volumiopi volumio[5812]: *** WARNING *** For more information see May 22 17:16:35 volumiopi node[5812]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:35 volumiopi volumio[5812]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:35 volumiopi volumio[5812]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:35 volumiopi volumio[5812]: *** WARNING *** For more information see May 22 17:16:35 volumiopi node[5812]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:35 volumiopi node[5812]: *** WARNING *** For more information see May 22 17:16:35 volumiopi node[5812]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:35 volumiopi node[5812]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:35 volumiopi node[5812]: *** WARNING *** For more information see May 22 17:16:35 volumiopi volumio[5812]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 17:16:35 volumiopi volumio[5812]: info: Discovery: Started advertising with name: VolumioPi May 22 17:16:35 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:35 volumiopi volumio[5812]: info: Loading plugin "autostart"... May 22 17:16:36 volumiopi volumio[5812]: info: Applying required configuration parameters for plugin autostart May 22 17:16:36 volumiopi volumio[5812]: info: AutoStart - onVolumioStart - read config.json May 22 17:16:36 volumiopi volumio[5812]: info: Loading plugin "outputs"... May 22 17:16:36 volumiopi volumio[5812]: info: Loading plugin "albumart"... May 22 17:16:36 volumiopi volumio[5812]: info: Plugin example_plugin is not enabled May 22 17:16:36 volumiopi volumio[5812]: info: Loading plugin "inputs"... May 22 17:16:36 volumiopi volumio[5812]: info: Loading plugin "updater_comm"... May 22 17:16:36 volumiopi volumio[5812]: info: Plugin mpdemulation is not enabled May 22 17:16:36 volumiopi volumio[5812]: info: Loading plugin "rest_api"... May 22 17:16:36 volumiopi volumio[5812]: info: Loading plugin "websocket"... May 22 17:16:36 volumiopi volumio[5812]: info: Starting Socket.io Server version 2.3.0 May 22 17:16:36 volumiopi volumio[5812]: info: Loading plugin "fusiondsp"... May 22 17:16:36 volumiopi volumio[5812]: Forking 3 albumart workers May 22 17:16:36 volumiopi volumio[5812]: info: Applying required configuration parameters for plugin fusiondsp May 22 17:16:36 volumiopi volumio[5812]: info: Loading i18n strings for locale pl May 22 17:16:36 volumiopi volumio[5812]: Updating browse sources language May 22 17:16:36 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::initPlayerControls May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:37 volumiopi volumio[5812]: Express server listening on port 3000 May 22 17:16:37 volumiopi volumio[5812]: [Metrics] WebUI: 5s 175.92ms May 22 17:16:37 volumiopi volumio[5812]: info: CoreStateMachine::resetVolumioState May 22 17:16:37 volumiopi volumio[5812]: info: CoreStateMachine::getcurrentVolume May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:37 volumiopi volumio[5812]: info: Volumio Network Manager: Network status updated: 2 May 22 17:16:37 volumiopi volumio[5812]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 22 17:16:37 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747930595 101 May 22 17:16:37 volumiopi volumio[5812]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 May 22 17:16:37 volumiopi volumio[5812]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::volumioGetState May 22 17:16:37 volumiopi volumio[5812]: Starting albumart workers May 22 17:16:37 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:37 volumiopi volumio[5812]: Starting albumart workers May 22 17:16:37 volumiopi volumio[5812]: info: Reloading queue from file May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 22 17:16:37 volumiopi volumio[5812]: Starting albumart workers May 22 17:16:37 volumiopi volumio[5812]: info: VolumeController:: Volume=0 Mute =false May 22 17:16:37 volumiopi volumio[5812]: info: CoreStateMachine::pushState May 22 17:16:37 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::volumioPushState May 22 17:16:37 volumiopi volumio[5812]: info: CoreStateMachine::updateTrackBlock May 22 17:16:37 volumiopi volumio[5812]: info: CorePlayQueue::getTrackBlock May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:16:37 volumiopi volumio[5812]: info: Setting Device type: Raspberry PI May 22 17:16:37 volumiopi volumio[5812]: info: CoreStateMachine::setRepeat null single undefined May 22 17:16:37 volumiopi volumio[5812]: info: CoreStateMachine::pushState May 22 17:16:37 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::volumioPushState May 22 17:16:37 volumiopi volumio[5812]: info: CoreStateMachine::setRandom null May 22 17:16:37 volumiopi volumio[5812]: info: CoreStateMachine::pushState May 22 17:16:37 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::volumioPushState May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::volumioGetState May 22 17:16:37 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:37 volumiopi volumio[5812]: info: Discovery: adding 12ef70b5-2e29-4466-ab71-dc1c1aea59d9 May 22 17:16:37 volumiopi volumio[5812]: info: Discovery: Found device Volumio May 22 17:16:37 volumiopi volumio[5812]: info: Discovery: Connecting to remote: 192.168.0.7 May 22 17:16:37 volumiopi volumio[5812]: info: VolumeController:: Volume=0 Mute =false May 22 17:16:37 volumiopi volumio[5812]: info: CoreStateMachine::pushState May 22 17:16:37 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:37 volumiopi volumio[5812]: info: CoreCommandRouter::volumioPushState May 22 17:16:38 volumiopi volumio[5812]: info: Discovery: Connected to remote: 192.168.0.7 May 22 17:16:38 volumiopi volumio[5812]: info: Discovery: adding 8fb1e1c8-fc9d-4a44-9915-ace891a4c314 May 22 17:16:38 volumiopi volumio[5812]: info: Discovery: Found device VolumioPi May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::volumioGetState May 22 17:16:38 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:38 volumiopi volumio[5812]: info: Completed loading Core Plugins May 22 17:16:38 volumiopi volumio[5812]: info: Preparing to generate the ALSA configuration file May 22 17:16:38 volumiopi volumio[5812]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:16:38 volumiopi volumio[5812]: info: Reading ALSA contributions from plugins. May 22 17:16:38 volumiopi volumio[5812]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 22 17:16:38 volumiopi volumio[5812]: info: Asound.conf file unchanged, so no further update is needed May 22 17:16:38 volumiopi volumio[5812]: info: Output device has changed, restarting MPD May 22 17:16:38 volumiopi volumio[5812]: info: Output device has changed, restarting Shairport Sync May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:38 volumiopi sudo[5927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:38 volumiopi sudo[5927]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:38 volumiopi sudo[5927]: pam_unix(sudo:session): session closed for user root May 22 17:16:38 volumiopi sudo[5933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:38 volumiopi sudo[5933]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:38 volumiopi systemd[1]: Stopping Music Player Daemon... May 22 17:16:38 volumiopi volumio[5812]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:38 volumiopi volumio[5812]: info: ___________ START PLUGINS ___________ May 22 17:16:38 volumiopi volumio[5812]: info: ControllerMpd::onStart: Initializing MPD May 22 17:16:38 volumiopi volumio[5812]: info: Creating MPD Configuration file May 22 17:16:38 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:38 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:38 volumiopi sudo[5944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:38 volumiopi sudo[5944]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:38 volumiopi volumio[5812]: info: [1747930598497] CoreMusicLibrary::Adding element Serwery Mediów May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:38 volumiopi sudo[5944]: pam_unix(sudo:session): session closed for user root May 22 17:16:38 volumiopi sudo[5947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:38 volumiopi sudo[5947]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:38 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:38 volumiopi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 17:16:38 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:38 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:38 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:38 volumiopi volumio[5812]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:38 volumiopi volumio[5812]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:38 volumiopi volumio[5812]: info: [1747930598642] CoreMusicLibrary::Adding element Last_100 May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:38 volumiopi volumio[5812]: info: [1747930598646] CoreMusicLibrary::Adding element Webradio May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:38 volumiopi volumio[5812]: info: Initializing BBC Radios May 22 17:16:38 volumiopi sudo[5951]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:16:38 volumiopi sudo[5951]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:38 volumiopi sudo[5951]: pam_unix(sudo:session): session closed for user root May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:38 volumiopi volumio[5812]: info: AutoStart - onStart May 22 17:16:38 volumiopi volumio[5812]: info: Loading i18n strings for locale pl May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:38 volumiopi volumio[5812]: info: FusionDsp - mixtype--------------------- Hardware May 22 17:16:38 volumiopi volumio[5812]: info: Volumio Calling Home May 22 17:16:38 volumiopi volumio[5812]: info: Preparing to generate the ALSA configuration file May 22 17:16:38 volumiopi volumio[5812]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:16:38 volumiopi volumio[5812]: info: Reading ALSA contributions from plugins. May 22 17:16:38 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 17:16:38 volumiopi volumio[5812]: info: MPD Permissions set May 22 17:16:38 volumiopi volumio[5812]: info: MPD Permissions set May 22 17:16:39 volumiopi volumio[5812]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 22 17:16:39 volumiopi volumio[5812]: info: Volumio called home May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 22 17:16:39 volumiopi volumio[5812]: info: Received Get System Version May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:39 volumiopi volumio[5812]: info: Received Get System Info May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:16:39 volumiopi volumio[5812]: info: Discovery: Getting this device information May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::volumioGetState May 22 17:16:39 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::volumioGetVisibleSources May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::volumioGetState May 22 17:16:39 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 17:16:39 volumiopi volumio[5812]: info: Received Get System Info May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:16:39 volumiopi volumio[5812]: info: Discovery: Getting this device information May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::volumioGetState May 22 17:16:39 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::volumioGetState May 22 17:16:39 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:39 volumiopi volumio[5812]: info: Listing playlists May 22 17:16:39 volumiopi volumio[5812]: info: Starting Shairport Sync May 22 17:16:39 volumiopi volumio[5812]: info: Starting Shairport Sync May 22 17:16:39 volumiopi volumio[5812]: info: Starting Shairport Sync May 22 17:16:39 volumiopi sudo[5981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:39 volumiopi sudo[5981]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:39 volumiopi sudo[5983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:39 volumiopi sudo[5983]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:39 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:39 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:39 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:39 volumiopi sudo[5986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:39 volumiopi sudo[5986]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::volumioGetState May 22 17:16:39 volumiopi volumio[5812]: info: CorePlayQueue::getTrack 0 May 22 17:16:39 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:39 volumiopi volumio[5812]: info: Asound.conf file unchanged, so no further update is needed May 22 17:16:39 volumiopi volumio[5812]: info: Output device has changed, restarting MPD May 22 17:16:39 volumiopi sudo[5981]: pam_unix(sudo:session): session closed for user root May 22 17:16:39 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:39 volumiopi sudo[5991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:39 volumiopi sudo[5991]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:39 volumiopi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 22 17:16:39 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:39 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:39 volumiopi volumio[5812]: info: Output device has changed, restarting Shairport Sync May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:39 volumiopi sudo[5991]: pam_unix(sudo:session): session closed for user root May 22 17:16:39 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:39 volumiopi sudo[5986]: pam_unix(sudo:session): session closed for user root May 22 17:16:39 volumiopi sudo[5983]: pam_unix(sudo:session): session closed for user root May 22 17:16:39 volumiopi sudo[5994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:39 volumiopi sudo[5994]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:39 volumiopi volumio[5812]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:39 volumiopi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 22 17:16:39 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:39 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:39 volumiopi volumio[5812]: info: Cannot mount NAS Synology at system boot, trial number 2 ,retrying in 5 seconds May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:16:39 volumiopi volumio[5812]: info: MPD Permissions set May 22 17:16:39 volumiopi volumio[5812]: info: Shairport-Sync Started May 22 17:16:39 volumiopi volumio[5812]: Error adding Membership: Error: addMembership EINVAL May 22 17:16:39 volumiopi volumio[5812]: info: Shairport-Sync Started May 22 17:16:39 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:39 volumiopi volumio[5812]: info: FusionDsp - May 22 17:16:39 volumiopi sudo[6004]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:16:39 volumiopi sudo[6004]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:39 volumiopi sudo[6004]: pam_unix(sudo:session): session closed for user root May 22 17:16:39 volumiopi volumio[5812]: info: FusionDsp - May 22 17:16:40 volumiopi volumio[5812]: info: FusionDsp - May 22 17:16:40 volumiopi volumio[5812]: info: FusionDsp - undefined May 22 17:16:40 volumiopi volumio[5812]: info: FusionDsp - undefined May 22 17:16:40 volumiopi volumio[5812]: info: FusionDsp - undefined May 22 17:16:40 volumiopi volumio[5812]: info: Starting Shairport Sync May 22 17:16:40 volumiopi volumio[5812]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:16:40 volumiopi sudo[6012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:40 volumiopi sudo[6012]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:40 volumiopi volumio[5812]: TypeError: Cannot read property 'off' of undefined May 22 17:16:40 volumiopi volumio[5812]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) May 22 17:16:40 volumiopi volumio[5812]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 22 17:16:40 volumiopi volumio[5812]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 22 17:16:40 volumiopi volumio[5812]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 22 17:16:40 volumiopi volumio[5812]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 22 17:16:40 volumiopi volumio[5812]: at Socket.emit (events.js:315:20) May 22 17:16:40 volumiopi volumio[5812]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 22 17:16:40 volumiopi volumio[5812]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 22 17:16:40 volumiopi volumio[5812]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:16:40 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:40 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:40 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:40 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:40 volumiopi sudo[6012]: pam_unix(sudo:session): session closed for user root May 22 17:16:41 volumiopi sudo[6026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 17:15 May 22 17:16:41 volumiopi sudo[6026]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:41 volumiopi mpd[6010]: May 22 17:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 17:16:41 volumiopi systemd[1]: Started Music Player Daemon. May 22 17:16:41 volumiopi sudo[5933]: pam_unix(sudo:session): session closed for user root May 22 17:16:41 volumiopi sudo[5994]: pam_unix(sudo:session): session closed for user root May 22 17:16:41 volumiopi sudo[5947]: pam_unix(sudo:session): session closed for user root May 22 17:16:41 volumiopi sudo[6026]: pam_unix(sudo:session): session closed for user root May 22 17:16:42 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 17:16:42 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 17:16:42 volumiopi systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 17:16:42 volumiopi systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 17:16:42 volumiopi systemd[1]: Started dynamicswap service. May 22 17:16:42 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:16:42 volumiopi systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 17:16:42 volumiopi systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21. May 22 17:16:42 volumiopi systemd[1]: Stopped Volumio Backend Module. May 22 17:16:42 volumiopi systemd[1]: Started Volumio Backend Module. May 22 17:16:42 volumiopi systemd[1]: Started dynamicswap service. May 22 17:16:42 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:16:44 volumiopi volumio[6042]: info: ------------------------------------------- May 22 17:16:44 volumiopi volumio[6042]: info: ----- Volumio3 ---- May 22 17:16:44 volumiopi volumio[6042]: info: ------------------------------------------- May 22 17:16:44 volumiopi volumio[6042]: info: ----- System startup ---- May 22 17:16:44 volumiopi volumio[6042]: info: ------------------------------------------- May 22 17:16:45 volumiopi volumio[6042]: info: MYVOLUMIO Environment detected May 22 17:16:45 volumiopi volumio[6042]: info: Plugin folders cleanup May 22 17:16:45 volumiopi volumio[6042]: info: Scanning into folder /volumio/app/plugins/ May 22 17:16:45 volumiopi volumio[6042]: info: Scanning category audio_interface May 22 17:16:45 volumiopi volumio[6042]: info: Scanning category miscellanea May 22 17:16:45 volumiopi volumio[6042]: info: Scanning category music_service May 22 17:16:45 volumiopi volumio[6042]: info: Scanning category plugins.json May 22 17:16:45 volumiopi volumio[6042]: info: Scanning category system_controller May 22 17:16:45 volumiopi volumio[6042]: info: Scanning category user_interface May 22 17:16:45 volumiopi volumio[6042]: info: Scanning into folder /data/plugins/ May 22 17:16:45 volumiopi volumio[6042]: info: Scanning category audio_interface May 22 17:16:45 volumiopi volumio[6042]: info: Scanning category system_controller May 22 17:16:45 volumiopi volumio[6042]: info: Plugin folders cleanup completed May 22 17:16:45 volumiopi volumio[6042]: info: ------------------------------------------- May 22 17:16:45 volumiopi volumio[6042]: info: ----- Core plugins startup ---- May 22 17:16:45 volumiopi volumio[6042]: info: ------------------------------------------- May 22 17:16:45 volumiopi volumio[6042]: info: Loading plugins from folder /volumio/app/plugins/ May 22 17:16:45 volumiopi volumio[6042]: info: Adding plugin upnp to MyMusic Plugins May 22 17:16:45 volumiopi volumio[6042]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 17:16:45 volumiopi volumio[6042]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 17:16:45 volumiopi volumio[6042]: info: Loading plugins from folder /data/plugins/ May 22 17:16:45 volumiopi volumio[6042]: info: Loading plugin "system"... May 22 17:16:45 volumiopi volumio[6042]: info: Loading plugin "appearance"... May 22 17:16:46 volumiopi volumio[6042]: info: Loading plugin "network"... May 22 17:16:46 volumiopi volumio[6042]: info: Refreshing Cached IP Addresses May 22 17:16:46 volumiopi sudo[6069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:16:46 volumiopi sudo[6069]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:46 volumiopi sudo[6069]: pam_unix(sudo:session): session closed for user root May 22 17:16:46 volumiopi sudo[6071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:16:46 volumiopi sudo[6071]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:46 volumiopi sudo[6071]: pam_unix(sudo:session): session closed for user root May 22 17:16:46 volumiopi volumio[6042]: info: Loading plugin "services"... May 22 17:16:46 volumiopi volumio[6042]: info: Loading plugin "alsa_controller"... May 22 17:16:46 volumiopi sudo[6084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 17:16:46 volumiopi sudo[6084]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:46 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:46 volumiopi volumio[6042]: info: Loading plugin "wizard"... May 22 17:16:46 volumiopi volumio[6042]: info: Loading plugin "networkfs"... May 22 17:16:46 volumiopi volumio[6042]: info: Cannot mount NAS Synology at system boot, trial number 1 ,retrying in 5 seconds May 22 17:16:46 volumiopi volumio[6042]: info: Starting Udev Watcher for removable devices May 22 17:16:46 volumiopi volumio[6042]: info: Ignoring mount for partition: boot May 22 17:16:46 volumiopi volumio[6042]: info: Ignoring mount for partition: volumio May 22 17:16:46 volumiopi volumio[6042]: info: Ignoring mount for partition: volumio_data May 22 17:16:46 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:46 volumiopi volumio[6042]: info: Loading plugin "volumio_command_line_client"... May 22 17:16:46 volumiopi volumio[6042]: info: Loading plugin "upnp"... May 22 17:16:46 volumiopi volumio[6042]: info: [1747930606490] Starting Upmpd Daemon May 22 17:16:46 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:46 volumiopi volumio[6042]: info: Loading plugin "my_music"... May 22 17:16:46 volumiopi volumio[6042]: info: Loading plugin "mpd"... May 22 17:16:46 volumiopi volumio[6042]: info: Loading plugin "upnp_browser"... May 22 17:16:47 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:47] [connect] Successful connection May 22 17:16:47 volumiopi volumio[6042]: info: Loading plugin "alarm-clock"... May 22 17:16:47 volumiopi sudo[6084]: pam_unix(sudo:session): session closed for user root May 22 17:16:47 volumiopi volumio[6042]: info: Loading plugin "airplay_emulation"... May 22 17:16:47 volumiopi volumio[6042]: info: Starting Shairport Sync May 22 17:16:47 volumiopi volumio[6042]: info: Loading plugin "last_100"... May 22 17:16:47 volumiopi volumio[6042]: info: Loading plugin "webradio"... May 22 17:16:47 volumiopi volumio[6042]: info: Loading plugin "i2s_dacs"... May 22 17:16:47 volumiopi volumio[6042]: info: I2S DAC not set, start Auto-detection May 22 17:16:47 volumiopi volumio[6042]: info: Loading plugin "volumiodiscovery"... May 22 17:16:47 volumiopi volumio[6042]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:47 volumiopi volumio[6042]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:47 volumiopi volumio[6042]: *** WARNING *** For more information see May 22 17:16:47 volumiopi node[6042]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:47 volumiopi volumio[6042]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:47 volumiopi volumio[6042]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:47 volumiopi volumio[6042]: *** WARNING *** For more information see May 22 17:16:47 volumiopi node[6042]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:47 volumiopi node[6042]: *** WARNING *** For more information see May 22 17:16:47 volumiopi node[6042]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:47 volumiopi node[6042]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:47 volumiopi node[6042]: *** WARNING *** For more information see May 22 17:16:47 volumiopi volumio[6042]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 17:16:47 volumiopi volumio[6042]: info: Discovery: Started advertising with name: VolumioPi May 22 17:16:47 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:47 volumiopi volumio[6042]: info: Loading plugin "autostart"... May 22 17:16:47 volumiopi volumio[6042]: info: Applying required configuration parameters for plugin autostart May 22 17:16:47 volumiopi volumio[6042]: info: AutoStart - onVolumioStart - read config.json May 22 17:16:47 volumiopi volumio[6042]: info: Loading plugin "outputs"... May 22 17:16:47 volumiopi volumio[6042]: info: Loading plugin "albumart"... May 22 17:16:47 volumiopi volumio[6042]: info: Plugin example_plugin is not enabled May 22 17:16:47 volumiopi volumio[6042]: info: Loading plugin "inputs"... May 22 17:16:47 volumiopi volumio[6042]: info: Loading plugin "updater_comm"... May 22 17:16:48 volumiopi volumio[6042]: info: Plugin mpdemulation is not enabled May 22 17:16:48 volumiopi volumio[6042]: info: Loading plugin "rest_api"... May 22 17:16:48 volumiopi volumio[6042]: info: Loading plugin "websocket"... May 22 17:16:48 volumiopi volumio[6042]: info: Starting Socket.io Server version 2.3.0 May 22 17:16:48 volumiopi volumio[6042]: info: Loading plugin "fusiondsp"... May 22 17:16:48 volumiopi volumio[6042]: Forking 3 albumart workers May 22 17:16:48 volumiopi volumio[6042]: info: Applying required configuration parameters for plugin fusiondsp May 22 17:16:48 volumiopi volumio[6042]: info: Loading i18n strings for locale pl May 22 17:16:48 volumiopi volumio[6042]: Updating browse sources language May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::initPlayerControls May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:48 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:48 volumiopi volumio[6042]: Express server listening on port 3000 May 22 17:16:48 volumiopi volumio[6042]: [Metrics] WebUI: 5s 272.26ms May 22 17:16:49 volumiopi volumio[6042]: info: CoreStateMachine::resetVolumioState May 22 17:16:49 volumiopi volumio[6042]: info: CoreStateMachine::getcurrentVolume May 22 17:16:49 volumiopi volumio[6042]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:16:49 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:49 volumiopi volumio[6042]: info: Volumio Network Manager: Network status updated: 2 May 22 17:16:49 volumiopi volumio[6042]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 22 17:16:49 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747930607 101 May 22 17:16:49 volumiopi volumio[6042]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 May 22 17:16:49 volumiopi volumio[6042]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 22 17:16:49 volumiopi volumio[6042]: info: CoreCommandRouter::volumioGetState May 22 17:16:49 volumiopi volumio[6042]: Starting albumart workers May 22 17:16:49 volumiopi volumio[6042]: info: CorePlayQueue::getTrack 0 May 22 17:16:49 volumiopi volumio[6042]: Starting albumart workers May 22 17:16:49 volumiopi volumio[6042]: Starting albumart workers May 22 17:16:49 volumiopi volumio[6042]: info: Reloading queue from file May 22 17:16:49 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 22 17:16:49 volumiopi volumio[6042]: info: VolumeController:: Volume=0 Mute =false May 22 17:16:49 volumiopi volumio[6042]: info: CoreStateMachine::pushState May 22 17:16:49 volumiopi volumio[6042]: info: CorePlayQueue::getTrack 0 May 22 17:16:49 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:16:49 volumiopi volumio[6042]: info: CoreCommandRouter::volumioPushState May 22 17:16:49 volumiopi volumio[6042]: info: CoreStateMachine::updateTrackBlock May 22 17:16:49 volumiopi volumio[6042]: info: CorePlayQueue::getTrackBlock May 22 17:16:49 volumiopi volumio[6042]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:16:49 volumiopi volumio[6042]: info: Setting Device type: Raspberry PI May 22 17:16:49 volumiopi volumio[6042]: info: CoreStateMachine::setRepeat null single undefined May 22 17:16:49 volumiopi volumio[6042]: info: CoreStateMachine::pushState May 22 17:16:49 volumiopi volumio[6042]: info: CorePlayQueue::getTrack 0 May 22 17:16:49 volumiopi volumio[6042]: info: CoreCommandRouter::volumioPushState May 22 17:16:49 volumiopi volumio[6042]: info: CoreStateMachine::setRandom null May 22 17:16:49 volumiopi volumio[6042]: info: CoreStateMachine::pushState May 22 17:16:49 volumiopi volumio[6042]: info: CorePlayQueue::getTrack 0 May 22 17:16:49 volumiopi volumio[6042]: info: CoreCommandRouter::volumioPushState May 22 17:16:49 volumiopi volumio[6042]: info: Listing playlists May 22 17:16:49 volumiopi volumio[6042]: info: Listing playlists May 22 17:16:49 volumiopi volumio[6042]: info: CoreCommandRouter::volumioGetState May 22 17:16:49 volumiopi volumio[6042]: info: CorePlayQueue::getTrack 0 May 22 17:16:49 volumiopi volumio[6042]: info: Discovery: adding 12ef70b5-2e29-4466-ab71-dc1c1aea59d9 May 22 17:16:49 volumiopi volumio[6042]: info: Discovery: Found device Volumio May 22 17:16:49 volumiopi volumio[6042]: info: Discovery: Connecting to remote: 192.168.0.7 May 22 17:16:49 volumiopi volumio[6042]: info: VolumeController:: Volume=0 Mute =false May 22 17:16:49 volumiopi volumio[6042]: info: CoreStateMachine::pushState May 22 17:16:49 volumiopi volumio[6042]: info: CorePlayQueue::getTrack 0 May 22 17:16:49 volumiopi volumio[6042]: info: CoreCommandRouter::volumioPushState May 22 17:16:49 volumiopi volumio[6042]: info: Discovery: Connected to remote: 192.168.0.7 May 22 17:16:49 volumiopi volumio[6042]: info: Completed loading Core Plugins May 22 17:16:49 volumiopi volumio[6042]: info: Preparing to generate the ALSA configuration file May 22 17:16:50 volumiopi volumio[6042]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:16:50 volumiopi volumio[6042]: info: Reading ALSA contributions from plugins. May 22 17:16:50 volumiopi volumio[6042]: info: Discovery: adding 8fb1e1c8-fc9d-4a44-9915-ace891a4c314 May 22 17:16:50 volumiopi volumio[6042]: info: Discovery: Found device VolumioPi May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::volumioGetState May 22 17:16:50 volumiopi volumio[6042]: info: CorePlayQueue::getTrack 0 May 22 17:16:50 volumiopi volumio[6042]: info: Asound.conf file unchanged, so no further update is needed May 22 17:16:50 volumiopi volumio[6042]: info: Output device has changed, restarting MPD May 22 17:16:50 volumiopi sudo[6148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:50 volumiopi sudo[6148]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:50 volumiopi volumio[6042]: info: Output device has changed, restarting Shairport Sync May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:50 volumiopi sudo[6148]: pam_unix(sudo:session): session closed for user root May 22 17:16:50 volumiopi sudo[6150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:50 volumiopi sudo[6150]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:50 volumiopi systemd[1]: Stopping Music Player Daemon... May 22 17:16:50 volumiopi volumio[6042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:50 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:50 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:50 volumiopi volumio[6042]: info: ___________ START PLUGINS ___________ May 22 17:16:50 volumiopi volumio[6042]: info: ControllerMpd::onStart: Initializing MPD May 22 17:16:50 volumiopi volumio[6042]: info: Creating MPD Configuration file May 22 17:16:50 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:50 volumiopi sudo[6174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:50 volumiopi volumio[6042]: info: [1747930610356] CoreMusicLibrary::Adding element Serwery Mediów May 22 17:16:50 volumiopi sudo[6174]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:50 volumiopi sudo[6176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:50 volumiopi sudo[6176]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:50 volumiopi sudo[6174]: pam_unix(sudo:session): session closed for user root May 22 17:16:50 volumiopi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 17:16:50 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:50 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:50 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:50 volumiopi volumio[6042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:50 volumiopi volumio[6042]: info: [1747930610507] CoreMusicLibrary::Adding element Last_100 May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:16:50 volumiopi volumio[6042]: info: [1747930610512] CoreMusicLibrary::Adding element Webradio May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:50 volumiopi volumio[6042]: info: Initializing BBC Radios May 22 17:16:50 volumiopi sudo[6179]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:16:50 volumiopi sudo[6179]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:50 volumiopi sudo[6179]: pam_unix(sudo:session): session closed for user root May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: AutoStart - onStart May 22 17:16:50 volumiopi volumio[6042]: info: Loading i18n strings for locale pl May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: FusionDsp - mixtype--------------------- Hardware May 22 17:16:50 volumiopi volumio[6042]: info: Volumio Calling Home May 22 17:16:50 volumiopi volumio[6042]: info: Preparing to generate the ALSA configuration file May 22 17:16:50 volumiopi volumio[6042]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:16:50 volumiopi volumio[6042]: info: Reading ALSA contributions from plugins. May 22 17:16:50 volumiopi volumio[6042]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 22 17:16:50 volumiopi volumio[6042]: info: MPD Permissions set May 22 17:16:50 volumiopi volumio[6042]: info: MPD Permissions set May 22 17:16:50 volumiopi volumio[6042]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 22 17:16:50 volumiopi volumio[6042]: info: Volumio called home May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:50 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:16:51 volumiopi volumio[6042]: info: Starting Shairport Sync May 22 17:16:51 volumiopi volumio[6042]: info: Starting Shairport Sync May 22 17:16:51 volumiopi volumio[6042]: info: Starting Shairport Sync May 22 17:16:51 volumiopi sudo[6210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:51 volumiopi sudo[6210]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:51 volumiopi sudo[6212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::volumioGetVisibleSources May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:16:51 volumiopi sudo[6212]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::volumioGetState May 22 17:16:51 volumiopi volumio[6042]: info: CorePlayQueue::getTrack 0 May 22 17:16:51 volumiopi sudo[6215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:51 volumiopi sudo[6215]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 17:16:51 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 17:16:51 volumiopi volumio[6042]: info: Received Get System Info May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:16:51 volumiopi volumio[6042]: info: Discovery: Getting this device information May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::volumioGetState May 22 17:16:51 volumiopi volumio[6042]: info: CorePlayQueue::getTrack 0 May 22 17:16:51 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:16:51 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::volumioGetState May 22 17:16:51 volumiopi volumio[6042]: info: CorePlayQueue::getTrack 0 May 22 17:16:51 volumiopi volumio[6042]: info: Listing playlists May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::volumioGetState May 22 17:16:51 volumiopi volumio[6042]: info: CorePlayQueue::getTrack 0 May 22 17:16:51 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:51 volumiopi sudo[6210]: pam_unix(sudo:session): session closed for user root May 22 17:16:51 volumiopi sudo[6212]: pam_unix(sudo:session): session closed for user root May 22 17:16:51 volumiopi sudo[6215]: pam_unix(sudo:session): session closed for user root May 22 17:16:51 volumiopi volumio[6042]: info: Asound.conf file unchanged, so no further update is needed May 22 17:16:51 volumiopi volumio[6042]: info: Output device has changed, restarting MPD May 22 17:16:51 volumiopi volumio[6042]: info: Output device has changed, restarting Shairport Sync May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:51 volumiopi sudo[6222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:16:51 volumiopi sudo[6222]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:51 volumiopi sudo[6222]: pam_unix(sudo:session): session closed for user root May 22 17:16:51 volumiopi sudo[6224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:16:51 volumiopi sudo[6224]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:51 volumiopi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 22 17:16:51 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:16:51 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:16:51 volumiopi volumio[6042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:16:51 volumiopi volumio[6042]: info: Shairport-Sync Started May 22 17:16:51 volumiopi volumio[6042]: Error adding Membership: Error: addMembership EINVAL May 22 17:16:51 volumiopi volumio[6042]: info: Shairport-Sync Started May 22 17:16:51 volumiopi volumio[6042]: info: Shairport-Sync Started May 22 17:16:51 volumiopi volumio[6042]: info: Cannot mount NAS Synology at system boot, trial number 2 ,retrying in 5 seconds May 22 17:16:51 volumiopi volumio[6042]: info: MPD Permissions set May 22 17:16:51 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:16:51 volumiopi volumio[6042]: info: Starting Shairport Sync May 22 17:16:51 volumiopi sudo[6230]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:16:51 volumiopi sudo[6230]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:51 volumiopi sudo[6230]: pam_unix(sudo:session): session closed for user root May 22 17:16:51 volumiopi sudo[6236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:16:51 volumiopi sudo[6236]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:51 volumiopi volumio[6042]: info: FusionDsp - May 22 17:16:51 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:16:51 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:16:51 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:16:51 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:16:51 volumiopi sudo[6236]: pam_unix(sudo:session): session closed for user root May 22 17:16:51 volumiopi volumio[6042]: info: FusionDsp - May 22 17:16:52 volumiopi volumio[6042]: info: FusionDsp - May 22 17:16:52 volumiopi volumio[6042]: info: FusionDsp - May 22 17:16:52 volumiopi volumio[6042]: info: FusionDsp - undefined May 22 17:16:52 volumiopi volumio[6042]: info: FusionDsp - undefined May 22 17:16:52 volumiopi volumio[6042]: info: FusionDsp - undefined May 22 17:16:52 volumiopi volumio[6042]: info: FusionDsp - undefined May 22 17:16:52 volumiopi volumio[6042]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:16:52 volumiopi volumio[6042]: TypeError: Cannot read property 'off' of undefined May 22 17:16:52 volumiopi volumio[6042]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) May 22 17:16:52 volumiopi volumio[6042]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 22 17:16:52 volumiopi volumio[6042]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 22 17:16:52 volumiopi volumio[6042]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 22 17:16:52 volumiopi volumio[6042]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 22 17:16:52 volumiopi volumio[6042]: at Socket.emit (events.js:315:20) May 22 17:16:52 volumiopi volumio[6042]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 22 17:16:52 volumiopi volumio[6042]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 22 17:16:52 volumiopi volumio[6042]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:16:53 volumiopi mpd[6238]: May 22 17:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 17:16:53 volumiopi systemd[1]: Started Music Player Daemon. May 22 17:16:53 volumiopi sudo[6150]: pam_unix(sudo:session): session closed for user root May 22 17:16:53 volumiopi sudo[6176]: pam_unix(sudo:session): session closed for user root May 22 17:16:53 volumiopi sudo[6224]: pam_unix(sudo:session): session closed for user root May 22 17:16:53 volumiopi sudo[6254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 17:15 May 22 17:16:53 volumiopi sudo[6254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:53 volumiopi sudo[6254]: pam_unix(sudo:session): session closed for user root May 22 17:16:53 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:53] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 17:16:53 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:53] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 17:16:53 volumiopi systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 17:16:53 volumiopi systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 17:16:53 volumiopi systemd[1]: Started dynamicswap service. May 22 17:16:53 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:16:53 volumiopi systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 17:16:53 volumiopi systemd[1]: volumio.service: Scheduled restart job, restart counter is at 22. May 22 17:16:54 volumiopi systemd[1]: Started dynamicswap service. May 22 17:16:54 volumiopi systemd[1]: Stopped Volumio Backend Module. May 22 17:16:54 volumiopi systemd[1]: Started Volumio Backend Module. May 22 17:16:54 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:16:55 volumiopi volumio[6265]: info: ------------------------------------------- May 22 17:16:55 volumiopi volumio[6265]: info: ----- Volumio3 ---- May 22 17:16:55 volumiopi volumio[6265]: info: ------------------------------------------- May 22 17:16:55 volumiopi volumio[6265]: info: ----- System startup ---- May 22 17:16:55 volumiopi volumio[6265]: info: ------------------------------------------- May 22 17:16:56 volumiopi volumio[6265]: info: MYVOLUMIO Environment detected May 22 17:16:56 volumiopi volumio[6265]: info: Plugin folders cleanup May 22 17:16:56 volumiopi volumio[6265]: info: Scanning into folder /volumio/app/plugins/ May 22 17:16:56 volumiopi volumio[6265]: info: Scanning category audio_interface May 22 17:16:56 volumiopi volumio[6265]: info: Scanning category miscellanea May 22 17:16:56 volumiopi volumio[6265]: info: Scanning category music_service May 22 17:16:56 volumiopi volumio[6265]: info: Scanning category plugins.json May 22 17:16:56 volumiopi volumio[6265]: info: Scanning category system_controller May 22 17:16:56 volumiopi volumio[6265]: info: Scanning category user_interface May 22 17:16:56 volumiopi volumio[6265]: info: Scanning into folder /data/plugins/ May 22 17:16:56 volumiopi volumio[6265]: info: Scanning category audio_interface May 22 17:16:56 volumiopi volumio[6265]: info: Scanning category system_controller May 22 17:16:56 volumiopi volumio[6265]: info: Plugin folders cleanup completed May 22 17:16:56 volumiopi volumio[6265]: info: ------------------------------------------- May 22 17:16:56 volumiopi volumio[6265]: info: ----- Core plugins startup ---- May 22 17:16:56 volumiopi volumio[6265]: info: ------------------------------------------- May 22 17:16:56 volumiopi volumio[6265]: info: Loading plugins from folder /volumio/app/plugins/ May 22 17:16:56 volumiopi volumio[6265]: info: Adding plugin upnp to MyMusic Plugins May 22 17:16:56 volumiopi volumio[6265]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 17:16:56 volumiopi volumio[6265]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 17:16:56 volumiopi volumio[6265]: info: Loading plugins from folder /data/plugins/ May 22 17:16:56 volumiopi volumio[6265]: info: Loading plugin "system"... May 22 17:16:56 volumiopi volumio[6265]: info: Loading plugin "appearance"... May 22 17:16:57 volumiopi volumio[6265]: info: Loading plugin "network"... May 22 17:16:57 volumiopi volumio[6265]: info: Refreshing Cached IP Addresses May 22 17:16:57 volumiopi sudo[6292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:16:57 volumiopi sudo[6292]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:57 volumiopi sudo[6292]: pam_unix(sudo:session): session closed for user root May 22 17:16:57 volumiopi sudo[6294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:16:57 volumiopi sudo[6294]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:57 volumiopi sudo[6294]: pam_unix(sudo:session): session closed for user root May 22 17:16:57 volumiopi volumio[6265]: info: Loading plugin "services"... May 22 17:16:57 volumiopi volumio[6265]: info: Loading plugin "alsa_controller"... May 22 17:16:57 volumiopi sudo[6303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 17:16:57 volumiopi sudo[6303]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:16:57 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:16:57 volumiopi volumio[6265]: info: Loading plugin "wizard"... May 22 17:16:57 volumiopi volumio[6265]: info: Loading plugin "networkfs"... May 22 17:16:57 volumiopi volumio[6265]: info: Cannot mount NAS Synology at system boot, trial number 1 ,retrying in 5 seconds May 22 17:16:57 volumiopi volumio[6265]: info: Starting Udev Watcher for removable devices May 22 17:16:57 volumiopi volumio[6265]: info: Ignoring mount for partition: boot May 22 17:16:57 volumiopi volumio[6265]: info: Ignoring mount for partition: volumio May 22 17:16:57 volumiopi volumio[6265]: info: Ignoring mount for partition: volumio_data May 22 17:16:57 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:57 volumiopi volumio[6265]: info: Loading plugin "volumio_command_line_client"... May 22 17:16:57 volumiopi volumio[6265]: info: Loading plugin "upnp"... May 22 17:16:57 volumiopi volumio[6265]: info: [1747930617927] Starting Upmpd Daemon May 22 17:16:57 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:57 volumiopi volumio[6265]: info: Loading plugin "my_music"... May 22 17:16:57 volumiopi volumio[6265]: info: Loading plugin "mpd"... May 22 17:16:58 volumiopi volumio[6265]: info: Loading plugin "upnp_browser"... May 22 17:16:58 volumiopi volumio[6265]: info: Loading plugin "alarm-clock"... May 22 17:16:58 volumiopi sudo[6303]: pam_unix(sudo:session): session closed for user root May 22 17:16:58 volumiopi volumio[6265]: info: Loading plugin "airplay_emulation"... May 22 17:16:58 volumiopi volumio[6265]: info: Starting Shairport Sync May 22 17:16:58 volumiopi volumio[6265]: info: Loading plugin "last_100"... May 22 17:16:58 volumiopi volumio[6265]: info: Loading plugin "webradio"... May 22 17:16:58 volumiopi volumio-remote-updater[589]: [2025-05-22 17:16:58] [connect] Successful connection May 22 17:16:58 volumiopi volumio[6265]: info: Loading plugin "i2s_dacs"... May 22 17:16:58 volumiopi volumio[6265]: info: I2S DAC not set, start Auto-detection May 22 17:16:58 volumiopi volumio[6265]: info: Loading plugin "volumiodiscovery"... May 22 17:16:58 volumiopi volumio[6265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:58 volumiopi volumio[6265]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:58 volumiopi volumio[6265]: *** WARNING *** For more information see May 22 17:16:58 volumiopi node[6265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:16:58 volumiopi volumio[6265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:58 volumiopi volumio[6265]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:58 volumiopi volumio[6265]: *** WARNING *** For more information see May 22 17:16:58 volumiopi node[6265]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:58 volumiopi node[6265]: *** WARNING *** For more information see May 22 17:16:58 volumiopi node[6265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:16:58 volumiopi node[6265]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:16:58 volumiopi node[6265]: *** WARNING *** For more information see May 22 17:16:58 volumiopi volumio[6265]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 17:16:58 volumiopi volumio[6265]: info: Discovery: Started advertising with name: VolumioPi May 22 17:16:58 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:16:58 volumiopi volumio[6265]: info: Loading plugin "autostart"... May 22 17:16:59 volumiopi volumio[6265]: info: Applying required configuration parameters for plugin autostart May 22 17:16:59 volumiopi volumio[6265]: info: AutoStart - onVolumioStart - read config.json May 22 17:16:59 volumiopi volumio[6265]: info: Loading plugin "outputs"... May 22 17:16:59 volumiopi volumio[6265]: info: Loading plugin "albumart"... May 22 17:16:59 volumiopi volumio[6265]: info: Plugin example_plugin is not enabled May 22 17:16:59 volumiopi volumio[6265]: info: Loading plugin "inputs"... May 22 17:16:59 volumiopi volumio[6265]: info: Loading plugin "updater_comm"... May 22 17:16:59 volumiopi volumio[6265]: info: Plugin mpdemulation is not enabled May 22 17:16:59 volumiopi volumio[6265]: info: Loading plugin "rest_api"... May 22 17:16:59 volumiopi volumio[6265]: info: Loading plugin "websocket"... May 22 17:16:59 volumiopi volumio[6265]: info: Starting Socket.io Server version 2.3.0 May 22 17:16:59 volumiopi volumio[6265]: info: Loading plugin "fusiondsp"... May 22 17:16:59 volumiopi volumio[6265]: Forking 3 albumart workers May 22 17:16:59 volumiopi volumio[6265]: info: Applying required configuration parameters for plugin fusiondsp May 22 17:17:00 volumiopi volumio[6265]: info: Loading i18n strings for locale pl May 22 17:17:00 volumiopi volumio[6265]: Updating browse sources language May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::initPlayerControls May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:17:00 volumiopi volumio[6265]: Express server listening on port 3000 May 22 17:17:00 volumiopi volumio[6265]: [Metrics] WebUI: 5s 66.22ms May 22 17:17:00 volumiopi volumio[6265]: info: CoreStateMachine::resetVolumioState May 22 17:17:00 volumiopi volumio[6265]: info: CoreStateMachine::getcurrentVolume May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:00 volumiopi volumio[6265]: info: Volumio Network Manager: Network status updated: 2 May 22 17:17:00 volumiopi volumio[6265]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 22 17:17:00 volumiopi volumio-remote-updater[589]: [2025-05-22 17:17:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747930618 101 May 22 17:17:00 volumiopi volumio[6265]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 May 22 17:17:00 volumiopi volumio[6265]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::volumioGetState May 22 17:17:00 volumiopi volumio[6265]: info: CorePlayQueue::getTrack 0 May 22 17:17:00 volumiopi volumio[6265]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 22 17:17:00 volumiopi volumio[6265]: info: Reloading queue from file May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 22 17:17:00 volumiopi volumio[6265]: info: VolumeController:: Volume=0 Mute =false May 22 17:17:00 volumiopi volumio[6265]: info: CoreStateMachine::pushState May 22 17:17:00 volumiopi volumio[6265]: info: CorePlayQueue::getTrack 0 May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::volumioPushState May 22 17:17:00 volumiopi volumio[6265]: info: CoreStateMachine::updateTrackBlock May 22 17:17:00 volumiopi volumio[6265]: info: CorePlayQueue::getTrackBlock May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:17:00 volumiopi volumio[6265]: info: CoreStateMachine::setRepeat null single undefined May 22 17:17:00 volumiopi volumio[6265]: info: CoreStateMachine::pushState May 22 17:17:00 volumiopi volumio[6265]: info: CorePlayQueue::getTrack 0 May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::volumioPushState May 22 17:17:00 volumiopi volumio[6265]: info: CoreStateMachine::setRandom null May 22 17:17:00 volumiopi volumio[6265]: info: CoreStateMachine::pushState May 22 17:17:00 volumiopi volumio[6265]: info: CorePlayQueue::getTrack 0 May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::volumioPushState May 22 17:17:00 volumiopi volumio[6265]: Starting albumart workers May 22 17:17:00 volumiopi volumio[6265]: Starting albumart workers May 22 17:17:00 volumiopi volumio[6265]: info: Setting Device type: Raspberry PI May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:17:00 volumiopi volumio[6265]: Starting albumart workers May 22 17:17:00 volumiopi volumio[6265]: info: CoreCommandRouter::volumioGetState May 22 17:17:00 volumiopi volumio[6265]: info: CorePlayQueue::getTrack 0 May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::volumioGetVisibleSources May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::volumioGetState May 22 17:17:01 volumiopi volumio[6265]: info: CorePlayQueue::getTrack 0 May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 17:17:01 volumiopi volumio[6265]: info: Received Get System Info May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:17:01 volumiopi volumio[6265]: info: Discovery: Getting this device information May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::volumioGetState May 22 17:17:01 volumiopi volumio[6265]: info: CorePlayQueue::getTrack 0 May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::volumioGetState May 22 17:17:01 volumiopi volumio[6265]: info: CorePlayQueue::getTrack 0 May 22 17:17:01 volumiopi volumio[6265]: info: Listing playlists May 22 17:17:01 volumiopi volumio[6265]: info: Discovery: adding 12ef70b5-2e29-4466-ab71-dc1c1aea59d9 May 22 17:17:01 volumiopi volumio[6265]: info: Discovery: Found device Volumio May 22 17:17:01 volumiopi volumio[6265]: info: Discovery: Connecting to remote: 192.168.0.7 May 22 17:17:01 volumiopi volumio[6265]: info: VolumeController:: Volume=0 Mute =false May 22 17:17:01 volumiopi volumio[6265]: info: CoreStateMachine::pushState May 22 17:17:01 volumiopi volumio[6265]: info: CorePlayQueue::getTrack 0 May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::volumioPushState May 22 17:17:01 volumiopi volumio[6265]: info: Discovery: adding 8fb1e1c8-fc9d-4a44-9915-ace891a4c314 May 22 17:17:01 volumiopi volumio[6265]: info: Discovery: Found device VolumioPi May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::volumioGetState May 22 17:17:01 volumiopi volumio[6265]: info: CorePlayQueue::getTrack 0 May 22 17:17:01 volumiopi volumio[6265]: info: Completed loading Core Plugins May 22 17:17:01 volumiopi volumio[6265]: info: Preparing to generate the ALSA configuration file May 22 17:17:01 volumiopi volumio[6265]: info: Discovery: Connected to remote: 192.168.0.7 May 22 17:17:01 volumiopi volumio[6265]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:17:01 volumiopi volumio[6265]: info: Reading ALSA contributions from plugins. May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:17:01 volumiopi volumio[6265]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:17:01 volumiopi volumio[6265]: info: FusionDsp - May 22 17:17:01 volumiopi volumio[6265]: info: FusionDsp - May 22 17:17:01 volumiopi volumio[6265]: info: FusionDsp - May 22 17:17:02 volumiopi volumio[6265]: info: FusionDsp - May 22 17:17:02 volumiopi volumio[6265]: info: FusionDsp - May 22 17:17:02 volumiopi volumio[6265]: info: FusionDsp - undefined May 22 17:17:02 volumiopi volumio[6265]: info: FusionDsp - undefined May 22 17:17:02 volumiopi volumio[6265]: info: FusionDsp - undefined May 22 17:17:02 volumiopi volumio[6265]: info: FusionDsp - undefined May 22 17:17:02 volumiopi volumio[6265]: info: FusionDsp - undefined May 22 17:17:02 volumiopi volumio[6265]: info: Cannot mount NAS Synology at system boot, trial number 2 ,retrying in 5 seconds May 22 17:17:02 volumiopi volumio[6265]: info: Asound.conf file unchanged, so no further update is needed May 22 17:17:02 volumiopi volumio[6265]: info: Output device has changed, restarting MPD May 22 17:17:02 volumiopi sudo[6390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:17:02 volumiopi volumio[6265]: info: Output device has changed, restarting Shairport Sync May 22 17:17:02 volumiopi sudo[6390]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:02 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:02 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:02 volumiopi sudo[6390]: pam_unix(sudo:session): session closed for user root May 22 17:17:02 volumiopi sudo[6392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:17:02 volumiopi sudo[6392]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:02 volumiopi systemd[1]: Stopping Music Player Daemon... May 22 17:17:03 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:17:03 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:17:03 volumiopi volumio[6265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:17:03 volumiopi volumio[6265]: info: ___________ START PLUGINS ___________ May 22 17:17:03 volumiopi volumio[6265]: info: ControllerMpd::onStart: Initializing MPD May 22 17:17:03 volumiopi volumio[6265]: info: Creating MPD Configuration file May 22 17:17:03 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:17:03 volumiopi sudo[6400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:17:03 volumiopi sudo[6400]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:17:03 volumiopi sudo[6400]: pam_unix(sudo:session): session closed for user root May 22 17:17:03 volumiopi volumio[6265]: info: [1747930623098] CoreMusicLibrary::Adding element Serwery Mediów May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:17:03 volumiopi sudo[6402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:17:03 volumiopi sudo[6402]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:03 volumiopi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 17:17:03 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:17:03 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:17:03 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:17:03 volumiopi volumio[6265]: info: [1747930623272] CoreMusicLibrary::Adding element Last_100 May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:17:03 volumiopi volumio[6265]: info: [1747930623276] CoreMusicLibrary::Adding element Webradio May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:17:03 volumiopi volumio[6265]: info: Initializing BBC Radios May 22 17:17:03 volumiopi sudo[6405]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:17:03 volumiopi sudo[6405]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:03 volumiopi sudo[6405]: pam_unix(sudo:session): session closed for user root May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: AutoStart - onStart May 22 17:17:03 volumiopi volumio[6265]: info: Loading i18n strings for locale pl May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: FusionDsp - mixtype--------------------- Hardware May 22 17:17:03 volumiopi volumio[6265]: info: Volumio Calling Home May 22 17:17:03 volumiopi volumio[6265]: info: Preparing to generate the ALSA configuration file May 22 17:17:03 volumiopi volumio[6265]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:17:03 volumiopi volumio[6265]: info: Reading ALSA contributions from plugins. May 22 17:17:03 volumiopi volumio[6265]: info: MPD Permissions set May 22 17:17:03 volumiopi volumio[6265]: info: MPD Permissions set May 22 17:17:03 volumiopi volumio[6265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 22 17:17:03 volumiopi volumio[6265]: info: Volumio called home May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:03 volumiopi volumio[6265]: info: Starting Shairport Sync May 22 17:17:03 volumiopi volumio[6265]: info: Starting Shairport Sync May 22 17:17:03 volumiopi sudo[6436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:17:03 volumiopi volumio[6265]: info: Starting Shairport Sync May 22 17:17:03 volumiopi sudo[6436]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:03 volumiopi sudo[6438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:17:03 volumiopi sudo[6438]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:03 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:17:03 volumiopi volumio[6265]: info: CoreCommandRouter::volumioGetState May 22 17:17:03 volumiopi volumio[6265]: info: CorePlayQueue::getTrack 0 May 22 17:17:03 volumiopi sudo[6441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:17:03 volumiopi sudo[6441]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:03 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:17:03 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:17:03 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:17:03 volumiopi volumio[6265]: info: Asound.conf file unchanged, so no further update is needed May 22 17:17:03 volumiopi sudo[6436]: pam_unix(sudo:session): session closed for user root May 22 17:17:03 volumiopi volumio[6265]: info: Output device has changed, restarting MPD May 22 17:17:04 volumiopi sudo[6438]: pam_unix(sudo:session): session closed for user root May 22 17:17:04 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:17:04 volumiopi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 22 17:17:04 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:17:04 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:17:04 volumiopi volumio[6265]: info: Output device has changed, restarting Shairport Sync May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:04 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:17:04 volumiopi sudo[6446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:17:04 volumiopi sudo[6441]: pam_unix(sudo:session): session closed for user root May 22 17:17:04 volumiopi sudo[6446]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:04 volumiopi sudo[6449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:17:04 volumiopi sudo[6449]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:04 volumiopi sudo[6446]: pam_unix(sudo:session): session closed for user root May 22 17:17:04 volumiopi volumio[6265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:17:04 volumiopi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 22 17:17:04 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:17:04 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:17:04 volumiopi volumio[6265]: info: MPD Permissions set May 22 17:17:04 volumiopi volumio[6265]: info: Shairport-Sync Started May 22 17:17:04 volumiopi volumio[6265]: Error adding Membership: Error: addMembership EINVAL May 22 17:17:04 volumiopi volumio[6265]: info: Shairport-Sync Started May 22 17:17:04 volumiopi volumio[6265]: info: Shairport-Sync Started May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:04 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:04 volumiopi volumio[6265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:04 volumiopi volumio[6265]: info: Starting Shairport Sync May 22 17:17:04 volumiopi sudo[6463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:17:04 volumiopi sudo[6463]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:04 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:17:04 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:17:04 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:17:04 volumiopi sudo[6457]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:17:04 volumiopi sudo[6457]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:04 volumiopi sudo[6457]: pam_unix(sudo:session): session closed for user root May 22 17:17:04 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:17:04 volumiopi sudo[6463]: pam_unix(sudo:session): session closed for user root May 22 17:17:04 volumiopi volumio[6265]: info: Shairport-Sync Started May 22 17:17:04 volumiopi volumio[6265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:17:04 volumiopi volumio[6265]: TypeError: Cannot read property 'off' of undefined May 22 17:17:04 volumiopi volumio[6265]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) May 22 17:17:04 volumiopi volumio[6265]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 22 17:17:04 volumiopi volumio[6265]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 22 17:17:04 volumiopi volumio[6265]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 22 17:17:04 volumiopi volumio[6265]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 22 17:17:04 volumiopi volumio[6265]: at Socket.emit (events.js:315:20) May 22 17:17:04 volumiopi volumio[6265]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 22 17:17:04 volumiopi volumio[6265]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 22 17:17:04 volumiopi volumio[6265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:17:05 volumiopi sudo[6479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 17:16 May 22 17:17:05 volumiopi sudo[6479]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:05 volumiopi sudo[6479]: pam_unix(sudo:session): session closed for user root May 22 17:17:05 volumiopi volumio-remote-updater[589]: [2025-05-22 17:17:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 22 17:17:05 volumiopi volumio-remote-updater[589]: [2025-05-22 17:17:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 22 17:17:05 volumiopi systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 22 17:17:05 volumiopi sudo[6449]: pam_unix(sudo:session): session closed for user root May 22 17:17:05 volumiopi sudo[6392]: pam_unix(sudo:session): session closed for user root May 22 17:17:05 volumiopi sudo[6402]: pam_unix(sudo:session): session closed for user root May 22 17:17:05 volumiopi systemd[1]: volumio.service: Failed with result 'exit-code'. May 22 17:17:05 volumiopi systemd[1]: Started dynamicswap service. May 22 17:17:05 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:17:05 volumiopi systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 22 17:17:05 volumiopi systemd[1]: volumio.service: Scheduled restart job, restart counter is at 23. May 22 17:17:05 volumiopi systemd[1]: Stopped Volumio Backend Module. May 22 17:17:05 volumiopi systemd[1]: Started Volumio Backend Module. May 22 17:17:05 volumiopi systemd[1]: Started dynamicswap service. May 22 17:17:05 volumiopi systemd[1]: dynamicswap.service: Succeeded. May 22 17:17:06 volumiopi mpd[6467]: May 22 17:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 17:17:06 volumiopi systemd[1]: Started Music Player Daemon. May 22 17:17:07 volumiopi volumio[6489]: info: ------------------------------------------- May 22 17:17:07 volumiopi volumio[6489]: info: ----- Volumio3 ---- May 22 17:17:07 volumiopi volumio[6489]: info: ------------------------------------------- May 22 17:17:07 volumiopi volumio[6489]: info: ----- System startup ---- May 22 17:17:07 volumiopi volumio[6489]: info: ------------------------------------------- May 22 17:17:08 volumiopi volumio[6489]: info: MYVOLUMIO Environment detected May 22 17:17:08 volumiopi volumio[6489]: info: Plugin folders cleanup May 22 17:17:08 volumiopi volumio[6489]: info: Scanning into folder /volumio/app/plugins/ May 22 17:17:08 volumiopi volumio[6489]: info: Scanning category audio_interface May 22 17:17:08 volumiopi volumio[6489]: info: Scanning category miscellanea May 22 17:17:08 volumiopi volumio[6489]: info: Scanning category music_service May 22 17:17:08 volumiopi volumio[6489]: info: Scanning category plugins.json May 22 17:17:08 volumiopi volumio[6489]: info: Scanning category system_controller May 22 17:17:08 volumiopi volumio[6489]: info: Scanning category user_interface May 22 17:17:08 volumiopi volumio[6489]: info: Scanning into folder /data/plugins/ May 22 17:17:08 volumiopi volumio[6489]: info: Scanning category audio_interface May 22 17:17:08 volumiopi volumio[6489]: info: Scanning category system_controller May 22 17:17:08 volumiopi volumio[6489]: info: Plugin folders cleanup completed May 22 17:17:08 volumiopi volumio[6489]: info: ------------------------------------------- May 22 17:17:08 volumiopi volumio[6489]: info: ----- Core plugins startup ---- May 22 17:17:08 volumiopi volumio[6489]: info: ------------------------------------------- May 22 17:17:08 volumiopi volumio[6489]: info: Loading plugins from folder /volumio/app/plugins/ May 22 17:17:08 volumiopi volumio[6489]: info: Adding plugin upnp to MyMusic Plugins May 22 17:17:08 volumiopi volumio[6489]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 17:17:08 volumiopi volumio[6489]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 17:17:08 volumiopi volumio[6489]: info: Loading plugins from folder /data/plugins/ May 22 17:17:08 volumiopi volumio[6489]: info: Loading plugin "system"... May 22 17:17:08 volumiopi volumio[6489]: info: Loading plugin "appearance"... May 22 17:17:09 volumiopi volumio[6489]: info: Loading plugin "network"... May 22 17:17:09 volumiopi volumio[6489]: info: Refreshing Cached IP Addresses May 22 17:17:09 volumiopi sudo[6519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:17:09 volumiopi sudo[6519]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:09 volumiopi sudo[6521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:17:09 volumiopi sudo[6521]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:09 volumiopi sudo[6519]: pam_unix(sudo:session): session closed for user root May 22 17:17:09 volumiopi sudo[6521]: pam_unix(sudo:session): session closed for user root May 22 17:17:09 volumiopi volumio[6489]: info: Loading plugin "services"... May 22 17:17:09 volumiopi volumio[6489]: info: Loading plugin "alsa_controller"... May 22 17:17:09 volumiopi sudo[6531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 17:17:09 volumiopi sudo[6531]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:09 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:17:09 volumiopi volumio[6489]: info: Loading plugin "wizard"... May 22 17:17:09 volumiopi volumio[6489]: info: Loading plugin "networkfs"... May 22 17:17:09 volumiopi volumio[6489]: info: Cannot mount NAS Synology at system boot, trial number 1 ,retrying in 5 seconds May 22 17:17:09 volumiopi volumio[6489]: info: Starting Udev Watcher for removable devices May 22 17:17:09 volumiopi volumio[6489]: info: Ignoring mount for partition: boot May 22 17:17:09 volumiopi volumio[6489]: info: Ignoring mount for partition: volumio May 22 17:17:09 volumiopi volumio[6489]: info: Ignoring mount for partition: volumio_data May 22 17:17:09 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:17:09 volumiopi volumio[6489]: info: Loading plugin "volumio_command_line_client"... May 22 17:17:09 volumiopi volumio[6489]: info: Loading plugin "upnp"... May 22 17:17:09 volumiopi volumio[6489]: info: [1747930629724] Starting Upmpd Daemon May 22 17:17:09 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:17:09 volumiopi volumio[6489]: info: Loading plugin "my_music"... May 22 17:17:09 volumiopi volumio[6489]: info: Loading plugin "mpd"... May 22 17:17:10 volumiopi volumio[6489]: info: Loading plugin "upnp_browser"... May 22 17:17:10 volumiopi volumio[6489]: info: Loading plugin "alarm-clock"... May 22 17:17:10 volumiopi sudo[6531]: pam_unix(sudo:session): session closed for user root May 22 17:17:10 volumiopi volumio-remote-updater[589]: [2025-05-22 17:17:10] [connect] Successful connection May 22 17:17:10 volumiopi volumio[6489]: info: Loading plugin "airplay_emulation"... May 22 17:17:10 volumiopi volumio[6489]: info: Starting Shairport Sync May 22 17:17:10 volumiopi volumio[6489]: info: Loading plugin "last_100"... May 22 17:17:10 volumiopi volumio[6489]: info: Loading plugin "webradio"... May 22 17:17:10 volumiopi volumio[6489]: info: Loading plugin "i2s_dacs"... May 22 17:17:10 volumiopi volumio[6489]: info: I2S DAC not set, start Auto-detection May 22 17:17:10 volumiopi volumio[6489]: info: Loading plugin "volumiodiscovery"... May 22 17:17:10 volumiopi volumio[6489]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:17:10 volumiopi volumio[6489]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:17:10 volumiopi volumio[6489]: *** WARNING *** For more information see May 22 17:17:10 volumiopi node[6489]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:17:10 volumiopi volumio[6489]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:17:10 volumiopi volumio[6489]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:17:10 volumiopi volumio[6489]: *** WARNING *** For more information see May 22 17:17:10 volumiopi node[6489]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:17:10 volumiopi node[6489]: *** WARNING *** For more information see May 22 17:17:10 volumiopi node[6489]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:17:10 volumiopi node[6489]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:17:10 volumiopi node[6489]: *** WARNING *** For more information see May 22 17:17:10 volumiopi volumio[6489]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 17:17:10 volumiopi volumio[6489]: info: Discovery: Started advertising with name: VolumioPi May 22 17:17:10 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:17:10 volumiopi volumio[6489]: info: Loading plugin "autostart"... May 22 17:17:11 volumiopi volumio[6489]: info: Applying required configuration parameters for plugin autostart May 22 17:17:11 volumiopi volumio[6489]: info: AutoStart - onVolumioStart - read config.json May 22 17:17:11 volumiopi volumio[6489]: info: Loading plugin "outputs"... May 22 17:17:11 volumiopi volumio[6489]: info: Loading plugin "albumart"... May 22 17:17:11 volumiopi volumio[6489]: info: Plugin example_plugin is not enabled May 22 17:17:11 volumiopi volumio[6489]: info: Loading plugin "inputs"... May 22 17:17:11 volumiopi volumio[6489]: info: Loading plugin "updater_comm"... May 22 17:17:11 volumiopi volumio[6489]: info: Plugin mpdemulation is not enabled May 22 17:17:11 volumiopi volumio[6489]: info: Loading plugin "rest_api"... May 22 17:17:11 volumiopi volumio[6489]: info: Loading plugin "websocket"... May 22 17:17:11 volumiopi volumio[6489]: info: Starting Socket.io Server version 2.3.0 May 22 17:17:11 volumiopi volumio[6489]: info: Loading plugin "fusiondsp"... May 22 17:17:11 volumiopi volumio[6489]: Forking 3 albumart workers May 22 17:17:11 volumiopi volumio[6489]: info: Applying required configuration parameters for plugin fusiondsp May 22 17:17:11 volumiopi volumio[6489]: info: Loading i18n strings for locale pl May 22 17:17:11 volumiopi volumio[6489]: Updating browse sources language May 22 17:17:11 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::initPlayerControls May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:17:12 volumiopi volumio[6489]: Express server listening on port 3000 May 22 17:17:12 volumiopi volumio[6489]: [Metrics] WebUI: 5s 135.22ms May 22 17:17:12 volumiopi volumio[6489]: info: CoreStateMachine::resetVolumioState May 22 17:17:12 volumiopi volumio[6489]: info: CoreStateMachine::getcurrentVolume May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:12 volumiopi volumio[6489]: info: Volumio Network Manager: Network status updated: 2 May 22 17:17:12 volumiopi volumio[6489]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 22 17:17:12 volumiopi volumio-remote-updater[589]: [2025-05-22 17:17:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747930630 101 May 22 17:17:12 volumiopi volumio[6489]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 May 22 17:17:12 volumiopi volumio[6489]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 22 17:17:12 volumiopi volumio[6489]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 22 17:17:12 volumiopi volumio[6489]: info: Reloading queue from file May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 22 17:17:12 volumiopi volumio[6489]: info: VolumeController:: Volume=0 Mute =false May 22 17:17:12 volumiopi volumio[6489]: info: CoreStateMachine::pushState May 22 17:17:12 volumiopi volumio[6489]: info: CorePlayQueue::getTrack 0 May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::volumioPushState May 22 17:17:12 volumiopi volumio[6489]: info: CoreStateMachine::updateTrackBlock May 22 17:17:12 volumiopi volumio[6489]: info: CorePlayQueue::getTrackBlock May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:17:12 volumiopi volumio[6489]: Starting albumart workers May 22 17:17:12 volumiopi volumio[6489]: Starting albumart workers May 22 17:17:12 volumiopi volumio[6489]: info: CoreStateMachine::setRepeat null single undefined May 22 17:17:12 volumiopi volumio[6489]: info: CoreStateMachine::pushState May 22 17:17:12 volumiopi volumio[6489]: info: CorePlayQueue::getTrack 0 May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::volumioPushState May 22 17:17:12 volumiopi volumio[6489]: info: CoreStateMachine::setRandom null May 22 17:17:12 volumiopi volumio[6489]: info: CoreStateMachine::pushState May 22 17:17:12 volumiopi volumio[6489]: info: CorePlayQueue::getTrack 0 May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::volumioPushState May 22 17:17:12 volumiopi volumio[6489]: info: Setting Device type: Raspberry PI May 22 17:17:12 volumiopi volumio[6489]: Starting albumart workers May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::volumioGetState May 22 17:17:12 volumiopi volumio[6489]: info: CorePlayQueue::getTrack 0 May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::volumioGetVisibleSources May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::volumioGetState May 22 17:17:12 volumiopi volumio[6489]: info: CorePlayQueue::getTrack 0 May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 17:17:12 volumiopi volumio[6489]: info: Received Get System Info May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:17:12 volumiopi volumio[6489]: info: Discovery: Getting this device information May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::volumioGetState May 22 17:17:12 volumiopi volumio[6489]: info: CorePlayQueue::getTrack 0 May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:17:12 volumiopi volumio[6489]: info: CoreCommandRouter::volumioGetState May 22 17:17:12 volumiopi volumio[6489]: info: CorePlayQueue::getTrack 0 May 22 17:17:12 volumiopi volumio[6489]: info: Listing playlists May 22 17:17:12 volumiopi volumio[6489]: info: Discovery: adding 12ef70b5-2e29-4466-ab71-dc1c1aea59d9 May 22 17:17:12 volumiopi volumio[6489]: info: Discovery: Found device Volumio May 22 17:17:13 volumiopi volumio[6489]: info: Discovery: Connecting to remote: 192.168.0.7 May 22 17:17:13 volumiopi volumio[6489]: info: VolumeController:: Volume=0 Mute =false May 22 17:17:13 volumiopi volumio[6489]: info: CoreStateMachine::pushState May 22 17:17:13 volumiopi volumio[6489]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumiopi volumio[6489]: info: CoreCommandRouter::volumioPushState May 22 17:17:13 volumiopi volumio[6489]: info: Completed loading Core Plugins May 22 17:17:13 volumiopi volumio[6489]: info: Preparing to generate the ALSA configuration file May 22 17:17:13 volumiopi volumio[6489]: info: Discovery: adding 8fb1e1c8-fc9d-4a44-9915-ace891a4c314 May 22 17:17:13 volumiopi volumio[6489]: info: Discovery: Found device VolumioPi May 22 17:17:13 volumiopi volumio[6489]: info: CoreCommandRouter::volumioGetState May 22 17:17:13 volumiopi volumio[6489]: info: CorePlayQueue::getTrack 0 May 22 17:17:13 volumiopi volumio[6489]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:17:13 volumiopi volumio[6489]: info: Reading ALSA contributions from plugins. May 22 17:17:13 volumiopi volumio[6489]: info: Discovery: Connected to remote: 192.168.0.7 May 22 17:17:13 volumiopi volumio[6489]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:17:13 volumiopi volumio[6489]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:17:13 volumiopi volumio[6489]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:17:13 volumiopi volumio[6489]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:17:13 volumiopi volumio[6489]: info: FusionDsp - May 22 17:17:13 volumiopi volumio[6489]: info: FusionDsp - May 22 17:17:13 volumiopi volumio[6489]: info: FusionDsp - May 22 17:17:14 volumiopi volumio[6489]: info: FusionDsp - May 22 17:17:14 volumiopi volumio[6489]: info: FusionDsp - May 22 17:17:14 volumiopi volumio[6489]: info: FusionDsp - undefined May 22 17:17:14 volumiopi volumio[6489]: info: FusionDsp - undefined May 22 17:17:14 volumiopi volumio[6489]: info: FusionDsp - undefined May 22 17:17:14 volumiopi volumio[6489]: info: FusionDsp - undefined May 22 17:17:14 volumiopi volumio[6489]: info: FusionDsp - undefined May 22 17:17:14 volumiopi volumio[6489]: info: Cannot mount NAS Synology at system boot, trial number 2 ,retrying in 5 seconds May 22 17:17:14 volumiopi volumio[6489]: info: Asound.conf file unchanged, so no further update is needed May 22 17:17:14 volumiopi volumio[6489]: info: Output device has changed, restarting MPD May 22 17:17:14 volumiopi sudo[6615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:17:14 volumiopi sudo[6615]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:14 volumiopi sudo[6615]: pam_unix(sudo:session): session closed for user root May 22 17:17:14 volumiopi volumio[6489]: info: Output device has changed, restarting Shairport Sync May 22 17:17:14 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:14 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:14 volumiopi sudo[6618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:17:14 volumiopi sudo[6618]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:14 volumiopi systemd[1]: Stopping Music Player Daemon... May 22 17:17:14 volumiopi volumio[6489]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:17:14 volumiopi volumio[6489]: info: ___________ START PLUGINS ___________ May 22 17:17:14 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:17:14 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:17:14 volumiopi volumio[6489]: info: ControllerMpd::onStart: Initializing MPD May 22 17:17:14 volumiopi volumio[6489]: info: Creating MPD Configuration file May 22 17:17:14 volumiopi sudo[6625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:17:14 volumiopi sudo[6625]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:14 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:17:14 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:17:14 volumiopi sudo[6625]: pam_unix(sudo:session): session closed for user root May 22 17:17:14 volumiopi sudo[6627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:17:14 volumiopi sudo[6627]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:14 volumiopi volumio[6489]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:17:14 volumiopi volumio[6489]: info: [1747930634962] CoreMusicLibrary::Adding element Serwery Mediów May 22 17:17:14 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:17:14 volumiopi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 22 17:17:14 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:17:14 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:17:14 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:15 volumiopi sudo[6630]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:17:15 volumiopi sudo[6630]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:15 volumiopi volumio[6489]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:17:15 volumiopi volumio[6489]: info: [1747930635110] CoreMusicLibrary::Adding element Last_100 May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:17:15 volumiopi sudo[6630]: pam_unix(sudo:session): session closed for user root May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:17:15 volumiopi volumio[6489]: info: [1747930635115] CoreMusicLibrary::Adding element Webradio May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:17:15 volumiopi volumio[6489]: info: Initializing BBC Radios May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: AutoStart - onStart May 22 17:17:15 volumiopi volumio[6489]: info: Loading i18n strings for locale pl May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: FusionDsp - mixtype--------------------- Hardware May 22 17:17:15 volumiopi volumio[6489]: info: Volumio Calling Home May 22 17:17:15 volumiopi volumio[6489]: info: Preparing to generate the ALSA configuration file May 22 17:17:15 volumiopi volumio[6489]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 22 17:17:15 volumiopi volumio[6489]: info: Reading ALSA contributions from plugins. May 22 17:17:15 volumiopi volumio[6489]: info: MPD Permissions set May 22 17:17:15 volumiopi volumio[6489]: info: MPD Permissions set May 22 17:17:15 volumiopi volumio[6489]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 22 17:17:15 volumiopi volumio[6489]: info: Volumio called home May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: Starting Shairport Sync May 22 17:17:15 volumiopi volumio[6489]: info: Starting Shairport Sync May 22 17:17:15 volumiopi sudo[6661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:17:15 volumiopi volumio[6489]: info: Starting Shairport Sync May 22 17:17:15 volumiopi sudo[6661]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:15 volumiopi sudo[6663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:17:15 volumiopi sudo[6663]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::volumioGetState May 22 17:17:15 volumiopi volumio[6489]: info: CorePlayQueue::getTrack 0 May 22 17:17:15 volumiopi sudo[6666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:17:15 volumiopi sudo[6666]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:15 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::volumioGetState May 22 17:17:15 volumiopi volumio[6489]: info: CorePlayQueue::getTrack 0 May 22 17:17:15 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:17:15 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:17:15 volumiopi volumio[6489]: info: Asound.conf file unchanged, so no further update is needed May 22 17:17:15 volumiopi volumio[6489]: info: Output device has changed, restarting MPD May 22 17:17:15 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:17:15 volumiopi sudo[6663]: pam_unix(sudo:session): session closed for user root May 22 17:17:15 volumiopi volumio[6489]: info: Output device has changed, restarting Shairport Sync May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:15 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:15 volumiopi sudo[6661]: pam_unix(sudo:session): session closed for user root May 22 17:17:15 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:17:15 volumiopi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 22 17:17:15 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:17:15 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:17:15 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:17:15 volumiopi sudo[6673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:17:16 volumiopi sudo[6672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:17:16 volumiopi sudo[6672]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:16 volumiopi sudo[6673]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:16 volumiopi sudo[6666]: pam_unix(sudo:session): session closed for user root May 22 17:17:16 volumiopi sudo[6672]: pam_unix(sudo:session): session closed for user root May 22 17:17:16 volumiopi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 22 17:17:16 volumiopi systemd[1]: mpd.service: Succeeded. May 22 17:17:16 volumiopi systemd[1]: Stopped Music Player Daemon. May 22 17:17:16 volumiopi volumio[6489]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:17:16 volumiopi systemd[1]: Starting Music Player Daemon... May 22 17:17:16 volumiopi volumio[6489]: info: Listing playlists May 22 17:17:16 volumiopi volumio[6489]: info: Listing playlists May 22 17:17:16 volumiopi volumio[6489]: info: MPD Permissions set May 22 17:17:16 volumiopi volumio[6489]: info: Shairport-Sync Started May 22 17:17:16 volumiopi volumio[6489]: Error adding Membership: Error: addMembership EINVAL May 22 17:17:16 volumiopi volumio[6489]: info: Shairport-Sync Started May 22 17:17:16 volumiopi volumio[6489]: info: Shairport-Sync Started May 22 17:17:16 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:16 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:16 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:16 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:16 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:16 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:17:16 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:16 volumiopi volumio[6489]: info: Starting Shairport Sync May 22 17:17:16 volumiopi sudo[6688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:17:16 volumiopi sudo[6688]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:16 volumiopi sudo[6682]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:17:16 volumiopi sudo[6682]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:16 volumiopi sudo[6682]: pam_unix(sudo:session): session closed for user root May 22 17:17:16 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:17:16 volumiopi systemd[1]: shairport-sync.service: Succeeded. May 22 17:17:16 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:17:16 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:17:16 volumiopi sudo[6688]: pam_unix(sudo:session): session closed for user root May 22 17:17:16 volumiopi volumio[6489]: info: Shairport-Sync Started May 22 17:17:17 volumiopi volumio[6489]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 22 17:17:17 volumiopi volumio[6489]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 22 17:17:17 volumiopi volumio[6489]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 22 17:17:17 volumiopi volumio[6489]: info: camilladsp service started and running in background, instance 1 May 22 17:17:17 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:17:17 volumiopi volumio[6489]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 22 17:17:17 volumiopi volumio[6489]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 22 17:17:17 volumiopi volumio[6489]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 22 17:17:17 volumiopi volumio[6489]: info: FusionDsp loaded May 22 17:17:17 volumiopi volumio[6489]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:17:17 volumiopi sudo[6705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 22 17:17:17 volumiopi sudo[6705]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:17:17 volumiopi volumio[6489]: info: FusionDsp - Reporting Fusion DSP Enabled May 22 17:17:17 volumiopi volumio[6489]: info: Adding Signal Path Element [object Object] May 22 17:17:17 volumiopi volumio[6489]: info: Adding fusiondspeq DSP Signal Path Element May 22 17:17:17 volumiopi sudo[6705]: pam_unix(sudo:session): session closed for user root May 22 17:17:17 volumiopi volumio[6489]: info: FusionDsp - ---- installed callbackRead May 22 17:17:17 volumiopi volumio[6489]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:17:17 volumiopi volumio[6489]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 22 17:17:17 volumiopi volumio[6489]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 22 17:17:17 volumiopi volumio[6489]: at onErrorNT (internal/child_process.js:465:16) May 22 17:17:17 volumiopi volumio[6489]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 22 17:17:17 volumiopi volumio[6489]: errno: -2, May 22 17:17:17 volumiopi volumio[6489]: code: 'ENOENT', May 22 17:17:17 volumiopi volumio[6489]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 22 17:17:17 volumiopi volumio[6489]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 22 17:17:17 volumiopi volumio[6489]: spawnargs: [ May 22 17:17:17 volumiopi volumio[6489]: '-p', May 22 17:17:17 volumiopi volumio[6489]: 9876, May 22 17:17:17 volumiopi volumio[6489]: '-o', May 22 17:17:17 volumiopi volumio[6489]: '/tmp/camilladsp.log', May 22 17:17:17 volumiopi volumio[6489]: '-l', May 22 17:17:17 volumiopi volumio[6489]: 'warn', May 22 17:17:17 volumiopi volumio[6489]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 22 17:17:17 volumiopi volumio[6489]: ] May 22 17:17:17 volumiopi volumio[6489]: } May 22 17:17:17 volumiopi volumio[6489]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:17:17 volumiopi mpd[6691]: May 22 17:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 17:17:17 volumiopi systemd[1]: Started Music Player Daemon. May 22 17:17:17 volumiopi sudo[6618]: pam_unix(sudo:session): session closed for user root May 22 17:17:17 volumiopi sudo[6627]: pam_unix(sudo:session): session closed for user root May 22 17:17:17 volumiopi sudo[6673]: pam_unix(sudo:session): session closed for user root May 22 17:17:18 volumiopi sudo[6718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 17:16 May 22 17:17:18 volumiopi sudo[6718]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"